Console Output
Started calculate disk usage of build
Finished Calculation of disk usage of build in 0 seconds
Started calculate disk usage of workspace
Finished Calculation of disk usage of workspace in 0 seconds
[INFO]
[INFO] --< it.tidalwave.northernwind:it-tidalwave-northernwind-frontend-media >--
[INFO] Building NorthernWind :: Frontend :: Media 1.2-ALPHA-9-SNAPSHOT [17/24]
[INFO] from modules/frontend/Media/pom.xml
[INFO] --------------------------------[ jar ]---------------------------------
[INFO] Downloading from maven-proxy.tidalwave.it: https://services.tidalwave.it/nexus/content/groups/public/org/imajine/image/org-imajine-image-core/1.0-ALPHA-3/org-imajine-image-core-1.0-ALPHA-3.pom
[INFO] Downloaded from maven-proxy.tidalwave.it: https://services.tidalwave.it/nexus/content/groups/public/org/imajine/image/org-imajine-image-core/1.0-ALPHA-3/org-imajine-image-core-1.0-ALPHA-3.pom (4.1 kB at 3.0 kB/s)
[INFO] Downloading from maven-proxy.tidalwave.it: https://services.tidalwave.it/nexus/content/groups/public/org/imajine/image/org-imajine-image-modules/1.0-ALPHA-3/org-imajine-image-modules-1.0-ALPHA-3.pom
[INFO] Downloaded from maven-proxy.tidalwave.it: https://services.tidalwave.it/nexus/content/groups/public/org/imajine/image/org-imajine-image-modules/1.0-ALPHA-3/org-imajine-image-modules-1.0-ALPHA-3.pom (4.8 kB at 15 kB/s)
[INFO] Downloading from maven-proxy.tidalwave.it: https://services.tidalwave.it/nexus/content/groups/public/org/imajine/image/org-imajine-image/1.0-ALPHA-3/org-imajine-image-1.0-ALPHA-3.pom
[INFO] Downloaded from maven-proxy.tidalwave.it: https://services.tidalwave.it/nexus/content/groups/public/org/imajine/image/org-imajine-image/1.0-ALPHA-3/org-imajine-image-1.0-ALPHA-3.pom (5.5 kB at 17 kB/s)
[INFO] Downloading from maven-proxy.tidalwave.it: https://services.tidalwave.it/nexus/content/groups/public/it/tidalwave/superpom/superpom/2.15/superpom-2.15.pom
[INFO] Downloaded from maven-proxy.tidalwave.it: https://services.tidalwave.it/nexus/content/groups/public/it/tidalwave/superpom/superpom/2.15/superpom-2.15.pom (4.6 kB at 15 kB/s)
[INFO] Downloading from maven-proxy.tidalwave.it: https://services.tidalwave.it/nexus/content/groups/public/it/tidalwave/thesefoolishthings/superpom/2.15/superpom-2.15.pom
[INFO] Downloaded from maven-proxy.tidalwave.it: https://services.tidalwave.it/nexus/content/groups/public/it/tidalwave/thesefoolishthings/superpom/2.15/superpom-2.15.pom (151 kB at 443 kB/s)
[INFO] Downloading from maven-proxy.tidalwave.it: https://services.tidalwave.it/nexus/content/groups/public/it/tidalwave/thesefoolishthings/thesefoolishthings/1.28/thesefoolishthings-1.28.pom
[INFO] Downloaded from maven-proxy.tidalwave.it: https://services.tidalwave.it/nexus/content/groups/public/it/tidalwave/thesefoolishthings/thesefoolishthings/1.28/thesefoolishthings-1.28.pom (9.9 kB at 32 kB/s)
[INFO] Downloading from maven-proxy.tidalwave.it: https://services.tidalwave.it/nexus/content/groups/public/it/tidalwave/thesefoolishthings/superpom/2.7/superpom-2.7.pom
[INFO] Downloaded from maven-proxy.tidalwave.it: https://services.tidalwave.it/nexus/content/groups/public/it/tidalwave/thesefoolishthings/superpom/2.7/superpom-2.7.pom (113 kB at 370 kB/s)
[INFO] Downloading from maven-proxy.tidalwave.it: https://services.tidalwave.it/nexus/content/groups/public/it/tidalwave/superpom/platform/RELEASE71-1/platform-RELEASE71-1.pom
[INFO] Downloaded from maven-proxy.tidalwave.it: https://services.tidalwave.it/nexus/content/groups/public/it/tidalwave/superpom/platform/RELEASE71-1/platform-RELEASE71-1.pom (14 kB at 46 kB/s)
[INFO] Downloading from maven-proxy.tidalwave.it: https://services.tidalwave.it/nexus/content/groups/public/com/kenai/nbpwr/com-drew-metadata/2.5.0rc3-201203012000/com-drew-metadata-2.5.0rc3-201203012000.pom
[INFO] Downloaded from maven-proxy.tidalwave.it: https://services.tidalwave.it/nexus/content/groups/public/com/kenai/nbpwr/com-drew-metadata/2.5.0rc3-201203012000/com-drew-metadata-2.5.0rc3-201203012000.pom (2.6 kB at 8.3 kB/s)
[INFO] Downloading from maven-proxy.tidalwave.it: https://services.tidalwave.it/nexus/content/groups/public/com/kenai/nbpwr/wrappers/201003102038/wrappers-201003102038.pom
[INFO] Downloaded from maven-proxy.tidalwave.it: https://services.tidalwave.it/nexus/content/groups/public/com/kenai/nbpwr/wrappers/201003102038/wrappers-201003102038.pom (7.3 kB at 23 kB/s)
[INFO] Downloading from maven-proxy.tidalwave.it: https://services.tidalwave.it/nexus/content/groups/public/com/drewnoakes/metadata-extractor/2.5.0-rc-3/metadata-extractor-2.5.0-rc-3.pom
[INFO] Downloaded from maven-proxy.tidalwave.it: https://services.tidalwave.it/nexus/content/groups/public/com/drewnoakes/metadata-extractor/2.5.0-rc-3/metadata-extractor-2.5.0-rc-3.pom (882 B at 649 B/s)
[INFO] Downloading from maven-proxy.tidalwave.it: https://services.tidalwave.it/nexus/content/groups/public/com/drewnoakes/xmpcore-patched/5.1.0/xmpcore-patched-5.1.0.pom
[INFO] Downloaded from maven-proxy.tidalwave.it: https://services.tidalwave.it/nexus/content/groups/public/com/drewnoakes/xmpcore-patched/5.1.0/xmpcore-patched-5.1.0.pom (465 B at 658 B/s)
[INFO] Downloading from maven-proxy.tidalwave.it: https://services.tidalwave.it/nexus/content/groups/public/org/imajine/image/org-imajine-image-core/1.0-ALPHA-3/org-imajine-image-core-1.0-ALPHA-3.jar
[INFO] Downloaded from maven-proxy.tidalwave.it: https://services.tidalwave.it/nexus/content/groups/public/org/imajine/image/org-imajine-image-core/1.0-ALPHA-3/org-imajine-image-core-1.0-ALPHA-3.jar (276 kB at 693 kB/s)
[INFO] Downloading from maven-proxy.tidalwave.it: https://services.tidalwave.it/nexus/content/groups/public/com/kenai/nbpwr/com-drew-metadata/2.5.0rc3-201203012000/com-drew-metadata-2.5.0rc3-201203012000.jar
[INFO] Downloading from maven-proxy.tidalwave.it: https://services.tidalwave.it/nexus/content/groups/public/com/drewnoakes/metadata-extractor/2.5.0-rc-3/metadata-extractor-2.5.0-rc-3.jar
[INFO] Downloading from maven-proxy.tidalwave.it: https://services.tidalwave.it/nexus/content/groups/public/com/drewnoakes/xmpcore-patched/5.1.0/xmpcore-patched-5.1.0.jar
[INFO] Downloaded from maven-proxy.tidalwave.it: https://services.tidalwave.it/nexus/content/groups/public/com/kenai/nbpwr/com-drew-metadata/2.5.0rc3-201203012000/com-drew-metadata-2.5.0rc3-201203012000.jar (2.2 kB at 5.9 kB/s)
[INFO] Downloaded from maven-proxy.tidalwave.it: https://services.tidalwave.it/nexus/content/groups/public/com/drewnoakes/xmpcore-patched/5.1.0/xmpcore-patched-5.1.0.jar (90 kB at 104 kB/s)
[INFO] Downloaded from maven-proxy.tidalwave.it: https://services.tidalwave.it/nexus/content/groups/public/com/drewnoakes/metadata-extractor/2.5.0-rc-3/metadata-extractor-2.5.0-rc-3.jar (166 kB at 152 kB/s)
[INFO]
[INFO] --- clean:3.1.0:clean (default-clean) @ it-tidalwave-northernwind-frontend-media ---
[INFO]
[INFO] --- buildnumber:1.4:create (default) @ it-tidalwave-northernwind-frontend-media ---
[INFO] ShortRevision tag detected. The value is '12'.
[INFO] Executing: /bin/sh -c cd '/var/jenkins_home/jobs/NorthernWind_Build_and_Test/workspace/modules/frontend/Media' && 'git' 'rev-parse' '--verify' '--short=12' 'HEAD'
[INFO] Working directory: /var/jenkins_home/jobs/NorthernWind_Build_and_Test/workspace/modules/frontend/Media
[INFO] Storing buildNumber: 010b15d1677e at timestamp: 1683565251896
[WARNING] Cannot get the branch information from the git repository:
Detecting the current branch failed: fatal: ref HEAD is not a symbolic ref
[INFO] ShortRevision tag detected. The value is '12'.
[INFO] Executing: /bin/sh -c cd '/var/jenkins_home/jobs/NorthernWind_Build_and_Test/workspace/modules/frontend/Media' && 'git' 'rev-parse' '--verify' '--short=12' 'HEAD'
[INFO] Working directory: /var/jenkins_home/jobs/NorthernWind_Build_and_Test/workspace/modules/frontend/Media
[INFO] Storing buildScmBranch: UNKNOWN
[INFO]
[INFO] --- enforcer:3.0.0-M3:enforce (enforce-maven) @ it-tidalwave-northernwind-frontend-media ---
[INFO]
[INFO] --- enforcer:3.0.0-M3:enforce (enforce-banned-dependencies) @ it-tidalwave-northernwind-frontend-media ---
[INFO]
[INFO] --- antrun:3.0.0:run (generate-and-print-build-description) @ it-tidalwave-northernwind-frontend-media ---
[INFO] Executing tasks
[INFO] [echo] DESCRIPTION: origin/fix-for-NW-108 1.2-ALPHA-9-SNAPSHOT 010b15d1677e
[INFO] [echo] Java Version: 11.0.18 -source 11 -target 11 -release 11
[INFO] Executed tasks
[INFO]
[INFO] --- antrun:3.0.0:run (create-unwoven-classes-dirs) @ it-tidalwave-northernwind-frontend-media ---
[INFO] Executing tasks
[INFO] [mkdir] Created dir: /var/jenkins_home/jobs/NorthernWind_Build_and_Test/workspace/modules/frontend/Media/target/unwoven-classes
[INFO] [mkdir] Created dir: /var/jenkins_home/jobs/NorthernWind_Build_and_Test/workspace/modules/frontend/Media/target/unwoven-test-classes
[INFO] Executed tasks
[INFO]
[INFO] --- resources:3.2.0:resources (default-resources) @ it-tidalwave-northernwind-frontend-media ---
[INFO] Using 'UTF-8' encoding to copy filtered resources.
[INFO] Using 'UTF-8' encoding to copy filtered properties files.
[INFO] Copying 1 resource
[INFO]
[INFO] --- compiler:3.8.1:compile (default-compile) @ it-tidalwave-northernwind-frontend-media ---
[WARNING] Parameter 'optimize' (user property 'maven.compiler.optimize') is deprecated: This property is a no-op in {@code javac}.
[WARNING] Parameter 'outputDirectory' is read-only, must not be used in configuration
[INFO] Changes detected - recompiling the module!
[INFO] Compiling 15 source files to /var/jenkins_home/jobs/NorthernWind_Build_and_Test/workspace/modules/frontend/Media/target/unwoven-classes
[WARNING] [path] bad path element "/var/jenkins_home/maven-repositories/0/com/kenai/nbpwr/com-drew-metadata/2.5.0rc3-201203012000/ext/metadata-extractor-2.5.0-rc-3.jar": no such file or directory
[WARNING] [path] bad path element "/var/jenkins_home/maven-repositories/0/com/kenai/nbpwr/com-drew-metadata/2.5.0rc3-201203012000/ext/xmpcore-5.1.0.jar": no such file or directory
[WARNING] [path] bad path element "/var/jenkins_home/maven-repositories/0/com/kenai/nbpwr/com-sun-media-imageio/1.1-201007271250/ext/jai_imageio-1.1.jar": no such file or directory
[WARNING] [path] bad path element "/var/jenkins_home/maven-repositories/0/com/kenai/nbpwr/com-sun-media-imageio/1.1-201007271250/ext/clibwrapper_jiio-1.1.jar": no such file or directory
[WARNING] Supported source version 'RELEASE_6' from annotation processor 'org.netbeans.modules.openide.util.ServiceProviderProcessor' less than -source '11'
[WARNING] Supported source version 'RELEASE_6' from annotation processor 'org.netbeans.modules.openide.util.NamedServiceProcessor' less than -source '11'
[WARNING] No processor claimed any of these annotations: /org.springframework.beans.factory.annotation.Configurable,/javax.annotation.CheckForNull,/javax.annotation.Nonnegative,/org.aspectj.lang.annotation.Around,/javax.annotation.Nonnull,/it.tidalwave.northernwind.aspect.DebugProfiling,/org.aspectj.lang.annotation.Aspect,/javax.annotation.PostConstruct,/javax.inject.Inject
[WARNING] /var/jenkins_home/jobs/NorthernWind_Build_and_Test/workspace/modules/frontend/Media/src/main/java/it/tidalwave/northernwind/frontend/media/impl/EmbeddedMediaMetadataProvider.java:[51,76] [deprecation] Key(String) in Key has been deprecated
[WARNING] /var/jenkins_home/jobs/NorthernWind_Build_and_Test/workspace/modules/frontend/Media/src/main/java/it/tidalwave/northernwind/frontend/media/impl/EmbeddedMediaMetadataProvider.java:[53,82] [deprecation] Key(String) in Key has been deprecated
[WARNING] /var/jenkins_home/jobs/NorthernWind_Build_and_Test/workspace/modules/frontend/Media/src/main/java/it/tidalwave/northernwind/frontend/media/impl/interpolator/ScanningMetadataInterpolatorFactory.java:[69,61] [deprecation] newInstance() in Class has been deprecated
where T is a type-variable:
T extends Object declared in class Class
[INFO]
[INFO] --- aspectj:1.11:compile (weave-classes) @ it-tidalwave-northernwind-frontend-media ---
[INFO] Showing AJC message detail for messages of types: [error, warning, fail]
[INFO]
[INFO] --- resources:3.2.0:testResources (default-testResources) @ it-tidalwave-northernwind-frontend-media ---
[INFO] Using 'UTF-8' encoding to copy filtered resources.
[INFO] Using 'UTF-8' encoding to copy filtered properties files.
[INFO] Copying 6 resources
[INFO]
[INFO] --- compiler:3.8.1:testCompile (default-testCompile) @ it-tidalwave-northernwind-frontend-media ---
[WARNING] Parameter 'optimize' (user property 'maven.compiler.optimize') is deprecated: This property is a no-op in {@code javac}.
[WARNING] Parameter 'outputDirectory' is read-only, must not be used in configuration
[INFO] Changes detected - recompiling the module!
[INFO] Compiling 7 source files to /var/jenkins_home/jobs/NorthernWind_Build_and_Test/workspace/modules/frontend/Media/target/unwoven-test-classes
[WARNING] [path] bad path element "/var/jenkins_home/maven-repositories/0/com/kenai/nbpwr/com-drew-metadata/2.5.0rc3-201203012000/ext/metadata-extractor-2.5.0-rc-3.jar": no such file or directory
[WARNING] [path] bad path element "/var/jenkins_home/maven-repositories/0/com/kenai/nbpwr/com-drew-metadata/2.5.0rc3-201203012000/ext/xmpcore-5.1.0.jar": no such file or directory
[WARNING] [path] bad path element "/var/jenkins_home/maven-repositories/0/com/kenai/nbpwr/com-sun-media-imageio/1.1-201007271250/ext/jai_imageio-1.1.jar": no such file or directory
[WARNING] [path] bad path element "/var/jenkins_home/maven-repositories/0/com/kenai/nbpwr/com-sun-media-imageio/1.1-201007271250/ext/clibwrapper_jiio-1.1.jar": no such file or directory
[WARNING] Supported source version 'RELEASE_6' from annotation processor 'org.netbeans.modules.openide.util.ServiceProviderProcessor' less than -source '11'
[WARNING] Supported source version 'RELEASE_6' from annotation processor 'org.netbeans.modules.openide.util.NamedServiceProcessor' less than -source '11'
[WARNING] No processor claimed any of these annotations: /javax.annotation.Nonnull,/org.testng.annotations.BeforeMethod,/org.testng.annotations.Test,/org.testng.annotations.DataProvider
[WARNING] /var/jenkins_home/jobs/NorthernWind_Build_and_Test/workspace/modules/frontend/Media/src/test/java/it/tidalwave/northernwind/frontend/media/impl/ImageTestBuilder.java:[73,182] [unchecked] unchecked cast
required: Map<Class<? extends Directory>,List<? extends Directory>>
found: Object
[INFO]
[INFO] --- aspectj:1.11:test-compile (weave-test-classes) @ it-tidalwave-northernwind-frontend-media ---
[INFO] Showing AJC message detail for messages of types: [error, warning, fail]
[INFO]
[INFO] --- surefire:3.0.0-M5:test (default-test) @ it-tidalwave-northernwind-frontend-media ---
[WARNING] Parameter 'localRepository' is deprecated core expression; Avoid use of ArtifactRepository type. If you need access to local repository, switch to '${repositorySystemSession}' expression and get LRM from it instead.
[INFO]
[INFO] -------------------------------------------------------
[INFO] T E S T S
[INFO] -------------------------------------------------------
[INFO] Running TestSuite
[ERROR] WARNING: An illegal reflective access operation has occurred
[ERROR] WARNING: Illegal reflective access by org.openide.util.lookup.implspi.ActiveQueue$Impl (file:/var/jenkins_home/maven-repositories/0/org/netbeans/api/org-openide-util-lookup/RELEASE80/org-openide-util-lookup-RELEASE80.jar) to field java.lang.ref.ReferenceQueue.lock
[ERROR] WARNING: Please consider reporting this to the maintainers of org.openide.util.lookup.implspi.ActiveQueue$Impl
[ERROR] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations
[ERROR] WARNING: All illegal access operations will be denied in a future release
17:01:04.176 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - STARTING TESTS OF it.tidalwave.northernwind.frontend.media.impl.DefaultMetadataCacheTest
17:01:07.272 [main ] INFO i.t.northernwind.util.test.SpringTestHelper - Beans: [org.springframework.context.annotation.internalConfigurationAnnotationProcessor, org.springframework.context.annotation.internalAutowiredAnnotationProcessor, org.springframework.context.annotation.internalCommonAnnotationProcessor, org.springframework.context.event.internalEventListenerProcessor, org.springframework.context.event.internalEventListenerFactory, org.springframework.context.config.internalBeanConfigurerAspect, underTest, metadataLoader, metadataInterpolators]
17:01:07.554 [main ] TRACE org.imajine.image.op.ImplementationFactoryRegistry - findImplementation(CreateOp(10, 10, BYTE, [D@6107165), null, canConvert: false)
17:01:07.598 [main ] WARN org.imajine.image.java2d.ImplementationFactoryJ2D - Additional Java2D operations not available: java.lang.ClassNotFoundException: org.imajine.image.java2d.AdditionalOperations
17:01:07.599 [main ] TRACE org.imajine.image.op.ImplementationFactoryRegistry - >>>> found implementation from ImplementationFactory(modelClass=class java.awt.image.BufferedImage)
17:01:07.600 [main ] DEBUG org.imajine.image.op.OperationImplementation - executing CreateOp(10, 10, BYTE, [D@6107165)
May 08, 2023 5:01:07 PM org.imajine.image.java2d.CreateJ2DOp execute
INFO: CreateJ2DOp.execute(CreateOp(10, 10, BYTE, [D@6107165), EditableImage(imageModelHolder=SerializableImageModelHolder[null], attributeMapByName={}))
17:01:07.699 [main ] DEBUG org.imajine.image.op.OperationImplementation - >>>> CreateOp(10, 10, BYTE, [D@6107165) done in 99 msec
17:01:07.876 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:00Z[GMT]
17:01:07.876 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - *************************************************************************************************
17:01:07.876 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - TEST "must cache the same instance within expiration time without checking for file modification"
17:01:07.877 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - *************************************************************************************************
17:01:07.877 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.880 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> loading metadata...
17:01:07.912 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:00Z[GMT]
17:01:07.913 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.913 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.914 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.914 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:00:06Z[GMT]
17:01:07.914 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.914 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.914 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.915 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:00:12Z[GMT]
17:01:07.915 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.915 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.915 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.915 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:00:18Z[GMT]
17:01:07.915 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.915 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.915 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.916 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:00:24Z[GMT]
17:01:07.916 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.916 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.916 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.916 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:00:30Z[GMT]
17:01:07.916 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.916 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.917 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.917 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:00:36Z[GMT]
17:01:07.917 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.917 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.917 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.917 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:00:42Z[GMT]
17:01:07.917 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.917 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.918 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.918 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:00:48Z[GMT]
17:01:07.918 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.918 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.926 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.927 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:00:54Z[GMT]
17:01:07.927 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.927 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.927 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.927 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:01Z[GMT]
17:01:07.928 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.928 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.928 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.928 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:01:06Z[GMT]
17:01:07.928 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.928 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.928 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.928 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:01:12Z[GMT]
17:01:07.928 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.929 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.929 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.929 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:01:18Z[GMT]
17:01:07.929 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.929 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.929 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.929 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:01:24Z[GMT]
17:01:07.929 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.929 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.930 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.930 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:01:30Z[GMT]
17:01:07.930 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.930 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.930 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.930 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:01:36Z[GMT]
17:01:07.930 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.930 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.931 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.931 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:01:42Z[GMT]
17:01:07.931 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.931 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.931 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.931 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:01:48Z[GMT]
17:01:07.931 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.931 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.931 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.932 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:01:54Z[GMT]
17:01:07.932 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.932 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.932 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.932 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:02Z[GMT]
17:01:07.932 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.932 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.932 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.932 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:02:06Z[GMT]
17:01:07.932 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.932 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.933 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.933 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:02:12Z[GMT]
17:01:07.933 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.933 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.937 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.937 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:02:18Z[GMT]
17:01:07.937 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.938 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.938 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.938 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:02:24Z[GMT]
17:01:07.938 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.938 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.938 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.939 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:02:30Z[GMT]
17:01:07.939 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.939 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.939 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.939 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:02:36Z[GMT]
17:01:07.939 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.939 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.939 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.939 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:02:42Z[GMT]
17:01:07.939 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.946 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.947 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.947 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:02:48Z[GMT]
17:01:07.947 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.947 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.947 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.947 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:02:54Z[GMT]
17:01:07.947 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.947 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.947 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.947 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:03Z[GMT]
17:01:07.948 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.948 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.948 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.948 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:03:06Z[GMT]
17:01:07.948 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.948 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.948 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.948 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:03:12Z[GMT]
17:01:07.948 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.948 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.948 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.949 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:03:18Z[GMT]
17:01:07.949 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.949 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.949 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.949 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:03:24Z[GMT]
17:01:07.949 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.949 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.949 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.949 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:03:30Z[GMT]
17:01:07.949 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.949 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.949 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.950 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:03:36Z[GMT]
17:01:07.950 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.950 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.950 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.950 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:03:42Z[GMT]
17:01:07.950 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.950 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.950 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.950 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:03:48Z[GMT]
17:01:07.951 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.951 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.951 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.951 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:03:54Z[GMT]
17:01:07.951 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.951 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.951 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.951 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:04Z[GMT]
17:01:07.951 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.952 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.954 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.954 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:04:06Z[GMT]
17:01:07.954 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.955 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.955 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.955 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:04:12Z[GMT]
17:01:07.955 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.955 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.955 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.955 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:04:18Z[GMT]
17:01:07.955 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.955 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.955 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.955 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:04:24Z[GMT]
17:01:07.955 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.956 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.956 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.956 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:04:30Z[GMT]
17:01:07.956 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.956 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.956 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.956 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:04:36Z[GMT]
17:01:07.956 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.956 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.956 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.956 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:04:42Z[GMT]
17:01:07.957 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.957 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.957 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.957 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:04:48Z[GMT]
17:01:07.957 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.957 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.957 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.957 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:04:54Z[GMT]
17:01:07.957 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.957 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.957 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.957 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:05Z[GMT]
17:01:07.957 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.957 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.958 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.958 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:05:06Z[GMT]
17:01:07.958 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.958 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.958 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.958 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:05:12Z[GMT]
17:01:07.958 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.958 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.958 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.958 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:05:18Z[GMT]
17:01:07.958 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.959 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.959 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.959 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:05:24Z[GMT]
17:01:07.959 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.959 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.959 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.959 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:05:30Z[GMT]
17:01:07.959 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.959 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.959 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.959 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:05:36Z[GMT]
17:01:07.959 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.959 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.960 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.960 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:05:42Z[GMT]
17:01:07.960 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.960 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.960 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.960 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:05:48Z[GMT]
17:01:07.960 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.960 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.960 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.960 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:05:54Z[GMT]
17:01:07.960 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.960 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.961 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.961 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:06Z[GMT]
17:01:07.961 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.961 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.961 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.961 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:06:06Z[GMT]
17:01:07.961 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.961 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.961 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.961 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:06:12Z[GMT]
17:01:07.961 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.962 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.962 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.962 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:06:18Z[GMT]
17:01:07.962 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.962 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.962 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.962 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:06:24Z[GMT]
17:01:07.962 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.979 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.979 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.979 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:06:30Z[GMT]
17:01:07.979 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.979 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.979 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.979 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:06:36Z[GMT]
17:01:07.979 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.979 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.979 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.979 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:06:42Z[GMT]
17:01:07.979 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.980 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.980 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.980 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:06:48Z[GMT]
17:01:07.980 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.980 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.980 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.980 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:06:54Z[GMT]
17:01:07.980 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.980 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.981 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.981 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:07Z[GMT]
17:01:07.981 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.981 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.981 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.981 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:07:06Z[GMT]
17:01:07.981 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.981 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.981 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.981 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:07:12Z[GMT]
17:01:07.981 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.981 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.981 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.981 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:07:18Z[GMT]
17:01:07.982 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.982 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.982 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.982 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:07:24Z[GMT]
17:01:07.982 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.982 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.982 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.990 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:07:30Z[GMT]
17:01:07.990 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.990 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:07.994 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:07.995 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:07:36Z[GMT]
17:01:07.995 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:07.996 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:08.001 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:08.001 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:07:42Z[GMT]
17:01:08.001 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:08.001 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:08.001 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:08.001 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:07:48Z[GMT]
17:01:08.001 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:08.001 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:08.001 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:08.002 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:07:54Z[GMT]
17:01:08.002 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:08.002 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:08.002 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:08.002 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:08Z[GMT]
17:01:08.002 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:08.002 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:08.002 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:08.002 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:08:06Z[GMT]
17:01:08.002 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:08.002 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:08.002 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:08.002 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:08:12Z[GMT]
17:01:08.002 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:08.002 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:08.002 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:08.002 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:08:18Z[GMT]
17:01:08.002 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:08.003 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:08.003 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:08.003 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:08:24Z[GMT]
17:01:08.003 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:08.003 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:08.003 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:08.003 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:08:30Z[GMT]
17:01:08.003 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:08.003 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:08.003 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:08.003 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:08:36Z[GMT]
17:01:08.003 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:08.003 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:08.003 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:08.003 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:08:42Z[GMT]
17:01:08.003 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:08.003 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:08.003 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:08.003 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:08:48Z[GMT]
17:01:08.004 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:08.004 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:08.004 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:08.004 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:08:54Z[GMT]
17:01:08.004 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:08.004 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:08.004 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:08.004 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:09Z[GMT]
17:01:08.004 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:08.004 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:08.004 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:08.004 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:09:06Z[GMT]
17:01:08.004 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:08.004 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:08.004 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:08.004 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:09:12Z[GMT]
17:01:08.008 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:08.009 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:08.009 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:08.009 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:09:18Z[GMT]
17:01:08.009 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:08.009 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:08.009 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:08.009 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:09:24Z[GMT]
17:01:08.009 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:08.009 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:08.009 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:08.009 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:09:30Z[GMT]
17:01:08.009 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:08.009 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:08.009 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:08.009 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:09:36Z[GMT]
17:01:08.010 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:08.010 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:08.010 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:08.010 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:09:42Z[GMT]
17:01:08.010 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:08.010 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:08.010 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:08.010 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:09:48Z[GMT]
17:01:08.010 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:08.010 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:08.010 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:08.010 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:09:54Z[GMT]
17:01:08.010 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:08.010 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> returning cached data which will expire at 2013-05-20T20:10Z[GMT]
17:01:08.010 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:10Z[GMT]
17:01:08.044 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - TEST PASSED in 168 msec
17:01:08.045 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest -
17:01:08.214 [main ] INFO i.t.northernwind.util.test.SpringTestHelper - Beans: [org.springframework.context.annotation.internalConfigurationAnnotationProcessor, org.springframework.context.annotation.internalAutowiredAnnotationProcessor, org.springframework.context.annotation.internalCommonAnnotationProcessor, org.springframework.context.event.internalEventListenerProcessor, org.springframework.context.event.internalEventListenerFactory, org.springframework.context.config.internalBeanConfigurerAspect, underTest, metadataLoader, metadataInterpolators]
17:01:08.218 [main ] TRACE org.imajine.image.op.ImplementationFactoryRegistry - findImplementation(CreateOp(10, 10, BYTE, [D@9fc9f91), null, canConvert: false)
17:01:08.218 [main ] TRACE org.imajine.image.op.ImplementationFactoryRegistry - >>>> found implementation from ImplementationFactory(modelClass=class java.awt.image.BufferedImage)
17:01:08.218 [main ] DEBUG org.imajine.image.op.OperationImplementation - executing CreateOp(10, 10, BYTE, [D@9fc9f91)
May 08, 2023 5:01:08 PM org.imajine.image.java2d.CreateJ2DOp execute
INFO: CreateJ2DOp.execute(CreateOp(10, 10, BYTE, [D@9fc9f91), EditableImage(imageModelHolder=SerializableImageModelHolder[null], attributeMapByName={}))
17:01:08.221 [main ] DEBUG org.imajine.image.op.OperationImplementation - >>>> CreateOp(10, 10, BYTE, [D@9fc9f91) done in 3 msec
17:01:08.229 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:00Z[GMT]
17:01:08.239 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - *******************************************************************************************************
17:01:08.239 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - TEST "must check file modification after expiration time and still keep in cache when no modifications"
17:01:08.239 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - *******************************************************************************************************
17:01:08.244 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:08.245 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> loading metadata...
17:01:08.246 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:10:00.000000001Z[GMT]
17:01:08.247 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:08.247 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>>>>>> postponing metadata expiration: file 2013-05-20T19:59:59.999999999Z[GMT] < metadata 2013-05-20T20:00Z[GMT]
17:01:08.247 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:20:00.000000001Z[GMT]
17:01:08.248 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:20:00.000000002Z[GMT]
17:01:08.248 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:08.248 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>>>>>> postponing metadata expiration: file 2013-05-20T19:59:59.999999999Z[GMT] < metadata 2013-05-20T20:00Z[GMT]
17:01:08.248 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:30:00.000000002Z[GMT]
17:01:08.249 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:30:00.000000003Z[GMT]
17:01:08.249 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:08.249 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>>>>>> postponing metadata expiration: file 2013-05-20T19:59:59.999999999Z[GMT] < metadata 2013-05-20T20:00Z[GMT]
17:01:08.249 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:40:00.000000003Z[GMT]
17:01:08.249 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:40:00.000000004Z[GMT]
17:01:08.249 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:08.250 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>>>>>> postponing metadata expiration: file 2013-05-20T19:59:59.999999999Z[GMT] < metadata 2013-05-20T20:00Z[GMT]
17:01:08.250 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:50:00.000000004Z[GMT]
17:01:08.251 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:50:00.000000005Z[GMT]
17:01:08.252 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:08.252 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>>>>>> postponing metadata expiration: file 2013-05-20T19:59:59.999999999Z[GMT] < metadata 2013-05-20T20:00Z[GMT]
17:01:08.252 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T21:00:00.000000005Z[GMT]
17:01:08.252 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T21:00:00.000000006Z[GMT]
17:01:08.252 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:08.253 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>>>>>> postponing metadata expiration: file 2013-05-20T19:59:59.999999999Z[GMT] < metadata 2013-05-20T20:00Z[GMT]
17:01:08.253 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T21:10:00.000000006Z[GMT]
17:01:08.253 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T21:10:00.000000007Z[GMT]
17:01:08.253 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:08.253 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>>>>>> postponing metadata expiration: file 2013-05-20T19:59:59.999999999Z[GMT] < metadata 2013-05-20T20:00Z[GMT]
17:01:08.253 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T21:20:00.000000007Z[GMT]
17:01:08.254 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T21:20:00.000000008Z[GMT]
17:01:08.254 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:08.255 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>>>>>> postponing metadata expiration: file 2013-05-20T19:59:59.999999999Z[GMT] < metadata 2013-05-20T20:00Z[GMT]
17:01:08.260 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T21:30:00.000000008Z[GMT]
17:01:08.260 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T21:30:00.000000009Z[GMT]
17:01:08.261 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:08.262 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>>>>>> postponing metadata expiration: file 2013-05-20T19:59:59.999999999Z[GMT] < metadata 2013-05-20T20:00Z[GMT]
17:01:08.263 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T21:40:00.000000009Z[GMT]
17:01:08.263 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T21:40:00.000000010Z[GMT]
17:01:08.263 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:08.263 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>>>>>> postponing metadata expiration: file 2013-05-20T19:59:59.999999999Z[GMT] < metadata 2013-05-20T20:00Z[GMT]
17:01:08.264 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T21:50:00.000000010Z[GMT]
17:01:08.271 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - TEST PASSED in 32 msec
17:01:08.271 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest -
17:01:08.355 [main ] INFO i.t.northernwind.util.test.SpringTestHelper - Beans: [org.springframework.context.annotation.internalConfigurationAnnotationProcessor, org.springframework.context.annotation.internalAutowiredAnnotationProcessor, org.springframework.context.annotation.internalCommonAnnotationProcessor, org.springframework.context.event.internalEventListenerProcessor, org.springframework.context.event.internalEventListenerFactory, org.springframework.context.config.internalBeanConfigurerAspect, underTest, metadataLoader, metadataInterpolators]
17:01:08.358 [main ] TRACE org.imajine.image.op.ImplementationFactoryRegistry - findImplementation(CreateOp(10, 10, BYTE, [D@7342e05d), null, canConvert: false)
17:01:08.364 [main ] TRACE org.imajine.image.op.ImplementationFactoryRegistry - >>>> found implementation from ImplementationFactory(modelClass=class java.awt.image.BufferedImage)
17:01:08.364 [main ] DEBUG org.imajine.image.op.OperationImplementation - executing CreateOp(10, 10, BYTE, [D@7342e05d)
May 08, 2023 5:01:08 PM org.imajine.image.java2d.CreateJ2DOp execute
INFO: CreateJ2DOp.execute(CreateOp(10, 10, BYTE, [D@7342e05d), EditableImage(imageModelHolder=SerializableImageModelHolder[null], attributeMapByName={}))
17:01:08.365 [main ] DEBUG org.imajine.image.op.OperationImplementation - >>>> CreateOp(10, 10, BYTE, [D@7342e05d) done in 1 msec
17:01:08.366 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:00Z[GMT]
17:01:08.366 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - *****************************************************
17:01:08.366 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - TEST "must correctly load metadata when not in cache"
17:01:08.366 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - *****************************************************
17:01:08.367 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:08.368 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> loading metadata...
17:01:08.371 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - TEST PASSED in 5 msec
17:01:08.371 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest -
17:01:08.467 [main ] INFO i.t.northernwind.util.test.SpringTestHelper - Beans: [org.springframework.context.annotation.internalConfigurationAnnotationProcessor, org.springframework.context.annotation.internalAutowiredAnnotationProcessor, org.springframework.context.annotation.internalCommonAnnotationProcessor, org.springframework.context.event.internalEventListenerProcessor, org.springframework.context.event.internalEventListenerFactory, org.springframework.context.config.internalBeanConfigurerAspect, underTest, metadataLoader, metadataInterpolators]
17:01:08.470 [main ] TRACE org.imajine.image.op.ImplementationFactoryRegistry - findImplementation(CreateOp(10, 10, BYTE, [D@4e08acf9), null, canConvert: false)
17:01:08.478 [main ] TRACE org.imajine.image.op.ImplementationFactoryRegistry - >>>> found implementation from ImplementationFactory(modelClass=class java.awt.image.BufferedImage)
17:01:08.479 [main ] DEBUG org.imajine.image.op.OperationImplementation - executing CreateOp(10, 10, BYTE, [D@4e08acf9)
May 08, 2023 5:01:08 PM org.imajine.image.java2d.CreateJ2DOp execute
INFO: CreateJ2DOp.execute(CreateOp(10, 10, BYTE, [D@4e08acf9), EditableImage(imageModelHolder=SerializableImageModelHolder[null], attributeMapByName={}))
17:01:08.482 [main ] DEBUG org.imajine.image.op.OperationImplementation - >>>> CreateOp(10, 10, BYTE, [D@4e08acf9) done in 3 msec
17:01:08.491 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:00Z[GMT]
17:01:08.492 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ****************************************************************************
17:01:08.492 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - TEST "must reload metadata after expiration time when file has been changed"
17:01:08.492 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ****************************************************************************
17:01:08.493 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:08.494 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> loading metadata...
17:01:08.497 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:10:00.000000001Z[GMT]
17:01:08.497 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:08.497 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>>>>>> expiring metadata: file 2013-05-20T20:10:00.000000002Z[GMT] > metadata 2013-05-20T20:00Z[GMT]
17:01:08.497 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> loading metadata...
17:01:08.498 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:20:00.000000001Z[GMT]
17:01:08.500 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:20:00.000000002Z[GMT]
17:01:08.500 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:08.501 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>>>>>> expiring metadata: file 2013-05-20T20:20:00.000000003Z[GMT] > metadata 2013-05-20T20:10:00.000000001Z[GMT]
17:01:08.506 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> loading metadata...
17:01:08.507 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:30:00.000000002Z[GMT]
17:01:08.508 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:30:00.000000003Z[GMT]
17:01:08.508 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:08.509 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>>>>>> expiring metadata: file 2013-05-20T20:30:00.000000004Z[GMT] > metadata 2013-05-20T20:20:00.000000002Z[GMT]
17:01:08.510 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> loading metadata...
17:01:08.510 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:40:00.000000003Z[GMT]
17:01:08.513 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:40:00.000000004Z[GMT]
17:01:08.514 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:08.514 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>>>>>> expiring metadata: file 2013-05-20T20:40:00.000000005Z[GMT] > metadata 2013-05-20T20:30:00.000000003Z[GMT]
17:01:08.516 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> loading metadata...
17:01:08.516 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T20:50:00.000000004Z[GMT]
17:01:08.516 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T20:50:00.000000005Z[GMT]
17:01:08.517 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:08.517 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>>>>>> expiring metadata: file 2013-05-20T20:50:00.000000006Z[GMT] > metadata 2013-05-20T20:40:00.000000004Z[GMT]
17:01:08.517 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> loading metadata...
17:01:08.517 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T21:00:00.000000005Z[GMT]
17:01:08.518 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T21:00:00.000000006Z[GMT]
17:01:08.518 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:08.518 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>>>>>> expiring metadata: file 2013-05-20T21:00:00.000000007Z[GMT] > metadata 2013-05-20T20:50:00.000000005Z[GMT]
17:01:08.518 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> loading metadata...
17:01:08.519 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T21:10:00.000000006Z[GMT]
17:01:08.519 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T21:10:00.000000007Z[GMT]
17:01:08.519 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:08.519 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>>>>>> expiring metadata: file 2013-05-20T21:10:00.000000008Z[GMT] > metadata 2013-05-20T21:00:00.000000006Z[GMT]
17:01:08.519 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> loading metadata...
17:01:08.520 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T21:20:00.000000007Z[GMT]
17:01:08.520 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T21:20:00.000000008Z[GMT]
17:01:08.520 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:08.521 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>>>>>> expiring metadata: file 2013-05-20T21:20:00.000000009Z[GMT] > metadata 2013-05-20T21:10:00.000000007Z[GMT]
17:01:08.521 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> loading metadata...
17:01:08.521 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T21:30:00.000000008Z[GMT]
17:01:08.521 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - ==== Time set to 2013-05-20T21:30:00.000000009Z[GMT]
17:01:08.522 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - findMetadataById(mediaId, ...)
17:01:08.522 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>>>>>> expiring metadata: file 2013-05-20T21:30:00.000000010Z[GMT] > metadata 2013-05-20T21:20:00.000000008Z[GMT]
17:01:08.522 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadataCache - >>>> loading metadata...
17:01:08.525 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - >>>> next expiration time: 2013-05-20T21:40:00.000000009Z[GMT]
17:01:08.529 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - TEST PASSED in 38 msec
17:01:08.529 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest -
17:01:08.531 [main ] INFO i.t.n.f.media.impl.DefaultMetadataProviderTest - **********************************
17:01:08.532 [main ] INFO i.t.n.f.media.impl.DefaultMetadataProviderTest - TEST "must properly read metadata"
17:01:08.532 [main ] INFO i.t.n.f.media.impl.DefaultMetadataProviderTest - **********************************
17:01:08.537 [main ] TRACE org.imajine.image.op.ReadOp - ReadOp(target/test-classes/DefaultMetadataProviderTest/test-resources/20100102-0001.jpg, 0, 0, [METADATA])
17:01:08.537 [main ] INFO org.imajine.image.op.ReadOp - read(target/test-classes/DefaultMetadataProviderTest/test-resources/20100102-0001.jpg, 0)
17:01:08.537 [main ] TRACE org.imajine.image.op.ReadOp - createImageReader(target/test-classes/DefaultMetadataProviderTest/test-resources/20100102-0001.jpg, ReadOp.PluginBlackList(plugins=[com.sun.imageio.plugins.tiff.TIFFImageReader, com.sun.media.imageioimpl.plugins.jpeg.CLibJPEGImageReader, com.sun.media.imageioimpl.plugins.jpeg.CLibJPEGImageWriter]))
17:01:08.538 [main ] TRACE org.imajine.image.op.ReadOp - >>>> suffix is jpg
17:01:08.540 [main ] INFO org.imajine.image.op.ReadOp - createImageReader(FileChannelImageInputStream@4110765e, false, jpg)
RAWProcessor not found: java.lang.ClassNotFoundException: it.tidalwave.imageio.rawprocessor.RAWProcessorInstaller
17:01:08.714 [main ] INFO org.imajine.image.op.ReadOp - createImageReader(FileChannelImageInputStream@4110765e, javax.imageio.ImageIO$ImageReaderIterator@4996c99)
17:01:08.719 [main ] TRACE org.imajine.image.op.ReadOp - >>>> pre-testing reader: com.sun.imageio.plugins.jpeg.JPEGImageReader@12eedfee, vendor: Oracle Corporation
17:01:08.719 [main ] TRACE org.imajine.image.op.ReadOp - >>>> testing reader: com.sun.imageio.plugins.jpeg.JPEGImageReader@12eedfee, vendor: Oracle Corporation
17:01:08.719 [main ] TRACE org.imajine.image.op.ReadOp - >>>> returning reader: com.sun.imageio.plugins.jpeg.JPEGImageReader@12eedfee
17:01:08.720 [main ] TRACE org.imajine.image.EditableImage - loadMetadata(com.sun.imageio.plugins.jpeg.JPEGImageReader@12eedfee, 0)
17:01:08.753 [main ] TRACE org.imajine.image.EditableImage - >>>> metadata class: class com.sun.imageio.plugins.jpeg.JPEGMetadata
17:01:08.753 [main ] TRACE org.imajine.image.EditableImage - >>>> using special treatment for JPEG
17:01:08.753 [main ] INFO org.imajine.image.metadata.WorkaroundBM25 - Workaround for bug BM-25
17:01:08.876 [main ] DEBUG org.imajine.image.metadata.WorkaroundBM25 - >>>> EXIF metadata: EXIF@1[36 tags]
17:01:08.877 [main ] DEBUG org.imajine.image.metadata.WorkaroundBM25 - >>>> IPTC metadata: IPTC@2[13 tags]
17:01:08.879 [main ] DEBUG org.imajine.image.metadata.WorkaroundBM25 - >>>> XMP metadata: XMP@3[3 tags]
17:01:08.880 [main ] INFO i.t.n.f.media.impl.DefaultMetadataProviderTest - IMAGE: EditableImage(imageModelHolder=SerializableImageModelHolder[null], attributeMapByName={org.imajine.image.EditableImage.format=JPEG, org.imajine.image.EditableImage.mimeType=image/jpeg})
17:01:08.880 [main ] INFO i.t.n.f.media.impl.DefaultMetadataProviderTest - IPTC: IPTC@2[13 tags]
17:01:08.881 [main ] INFO i.t.n.f.media.impl.DefaultMetadataProviderTest - EXIF: EXIF@1[36 tags]
17:01:08.881 [main ] INFO i.t.n.f.media.impl.DefaultMetadataProviderTest - XMP: XMP@3[3 tags]
17:01:08.887 [main ] INFO i.t.n.f.media.impl.DefaultMetadataProviderTest - EXIF [33434] Exposure Time: 1/1000
17:01:08.887 [main ] INFO i.t.n.f.media.impl.DefaultMetadataProviderTest - EXIF [33437] F Number: 8/1
17:01:08.887 [main ] INFO i.t.n.f.media.impl.DefaultMetadataProviderTest - EXIF [34850] Exposure Program: 1
17:01:08.888 [main ] INFO i.t.n.f.media.impl.DefaultMetadataProviderTest - EXIF [34855] ISO Speed Ratings: 400
17:01:08.888 [main ] INFO i.t.n.f.media.impl.DefaultMetadataProviderTest - EXIF [36864] EXIF Version: [48, 50, 51, 48]
17:01:08.888 [main ] INFO i.t.n.f.media.impl.DefaultMetadataProviderTest - EXIF [36867] Date/Time Original: 2010:01:02 12:53:59
17:01:08.888 [main ] INFO i.t.n.f.media.impl.DefaultMetadataProviderTest - EXIF [36868] Date/Time Digitized: 2010:01:02 12:53:59
17:01:08.892 [main ] INFO i.t.n.f.media.impl.DefaultMetadataProviderTest - EXIF [37377] Shutter SpeedValue: 9965784/1000000
17:01:08.895 [main ] INFO i.t.n.f.media.impl.DefaultMetadataProviderTest - EXIF [37378] Aperture Value: 6/1
17:01:08.896 [main ] INFO i.t.n.f.media.impl.DefaultMetadataProviderTest - EXIF [37380] Exposure Bias Value: 0/6
17:01:08.896 [main ] INFO i.t.n.f.media.impl.DefaultMetadataProviderTest - EXIF [37381] Max Aperture Value: 30/10
17:01:08.896 [main ] INFO i.t.n.f.media.impl.DefaultMetadataProviderTest - EXIF [37382] Subject Distance: 3/100
17:01:08.896 [main ] INFO i.t.n.f.media.impl.DefaultMetadataProviderTest - EXIF [37383] Metering Mode: 5
17:01:08.899 [main ] INFO i.t.n.f.media.impl.DefaultMetadataProviderTest - EXIF [37384] Light Source: 9
17:01:08.899 [main ] INFO i.t.n.f.media.impl.DefaultMetadataProviderTest - EXIF [37385] Flash: 0
17:01:08.899 [main ] INFO i.t.n.f.media.impl.DefaultMetadataProviderTest - EXIF [37386] Focal Length: 1800/10
17:01:08.899 [main ] INFO i.t.n.f.media.impl.DefaultMetadataProviderTest - EXIF [37521] Subsec Time Original: 00
17:01:08.899 [main ] INFO i.t.n.f.media.impl.DefaultMetadataProviderTest - EXIF [37522] Subsec Time Digitized: 00
17:01:08.900 [main ] INFO i.t.n.f.media.impl.DefaultMetadataProviderTest - EXIF [41495] Sensing Method: 2
17:01:08.900 [main ] INFO i.t.n.f.media.impl.DefaultMetadataProviderTest - EXIF [41728] File Source: [3]
17:01:08.902 [main ] INFO i.t.n.f.media.impl.DefaultMetadataProviderTest - EXIF [41729] Scene Type: [1]
17:01:08.903 [main ] INFO i.t.n.f.media.impl.DefaultMetadataProviderTest - EXIF [41730] EXIF CFA Pattern: [2, 0, 2, 0, 1, 2, 0, 1]
17:01:08.903 [main ] INFO i.t.n.f.media.impl.DefaultMetadataProviderTest - EXIF [41985] Custom Rendered: 0
17:01:08.903 [main ] INFO i.t.n.f.media.impl.DefaultMetadataProviderTest - EXIF [41986] Exposure Mode: 1
17:01:08.906 [main ] INFO i.t.n.f.media.impl.DefaultMetadataProviderTest - EXIF [41987] White Balance: 1
17:01:08.906 [main ] INFO i.t.n.f.media.impl.DefaultMetadataProviderTest - EXIF [41988] Digital Zoom Ratio: 1/1
17:01:08.906 [main ] INFO i.t.n.f.media.impl.DefaultMetadataProviderTest - EXIF [41989] Focal Length 35mm Film: 270
17:01:08.906 [main ] INFO i.t.n.f.media.impl.DefaultMetadataProviderTest - EXIF [41990] Scene Capture Type: 0
17:01:08.906 [main ] INFO i.t.n.f.media.impl.DefaultMetadataProviderTest - EXIF [41991] Gain Control: 1
17:01:08.906 [main ] INFO i.t.n.f.media.impl.DefaultMetadataProviderTest - EXIF [41992] Contrast: 0
17:01:08.906 [main ] INFO i.t.n.f.media.impl.DefaultMetadataProviderTest - EXIF [41993] Saturation: 0
17:01:08.906 [main ] INFO i.t.n.f.media.impl.DefaultMetadataProviderTest - EXIF [41994] Sharpness: 0
17:01:08.907 [main ] INFO i.t.n.f.media.impl.DefaultMetadataProviderTest - EXIF [41996] Subject Distance Range: 0
17:01:08.907 [main ] INFO i.t.n.f.media.impl.DefaultMetadataProviderTest - EXIF [42033] null: 6147706
17:01:08.907 [main ] INFO i.t.n.f.media.impl.DefaultMetadataProviderTest - EXIF [42034] null: [1800/10, 1800/10, 28/10, 28/10]
17:01:08.911 [main ] INFO i.t.n.f.media.impl.DefaultMetadataProviderTest - EXIF [42036] null: 180.0 mm f/2.8
17:01:08.911 [main ] INFO i.t.n.f.media.impl.DefaultMetadataProviderTest - IPTC [346] null: %G
17:01:08.912 [main ] INFO i.t.n.f.media.impl.DefaultMetadataProviderTest - IPTC [512] null: 4
17:01:08.912 [main ] INFO i.t.n.f.media.impl.DefaultMetadataProviderTest - IPTC [517] null: Moutons autour de l'Île Saint-Honorat
17:01:08.933 [main ] INFO i.t.n.f.media.impl.DefaultMetadataProviderTest - IPTC [567] null: 2010-01-02T00:00:00
17:01:08.934 [main ] INFO i.t.n.f.media.impl.DefaultMetadataProviderTest - IPTC [572] null: 125359+0100
17:01:08.941 [main ] INFO i.t.n.f.media.impl.DefaultMetadataProviderTest - IPTC [574] null: 20100102
17:01:08.942 [main ] INFO i.t.n.f.media.impl.DefaultMetadataProviderTest - IPTC [575] null: 125359+0100
17:01:08.942 [main ] INFO i.t.n.f.media.impl.DefaultMetadataProviderTest - IPTC [592] null: Fabrizio Giudici
17:01:08.942 [main ] INFO i.t.n.f.media.impl.DefaultMetadataProviderTest - IPTC [597] null: Photographer
17:01:08.942 [main ] INFO i.t.n.f.media.impl.DefaultMetadataProviderTest - IPTC [622] null: Fabrizio Giudici
17:01:08.942 [main ] INFO i.t.n.f.media.impl.DefaultMetadataProviderTest - IPTC [627] null: Fabrizio Giudici
17:01:08.943 [main ] INFO i.t.n.f.media.impl.DefaultMetadataProviderTest - IPTC [628] null: © Copyright by Fabrizio Giudici. All rights reserved.
17:01:08.943 [main ] INFO i.t.n.f.media.impl.DefaultMetadataProviderTest - IPTC [634] null: Fabrizio Giudici
17:01:08.943 [main ] INFO i.t.n.f.media.impl.DefaultMetadataProviderTest - XMP [6] null: 1800/10 1800/10 28/10 28/10
17:01:08.943 [main ] INFO i.t.n.f.media.impl.DefaultMetadataProviderTest - XMP [7] null: 180.0 mm f/2.8
17:01:08.943 [main ] INFO i.t.n.f.media.impl.DefaultMetadataProviderTest - XMP [8] null: 6147706
17:01:08.966 [main ] INFO it.tidalwave.util.test.FileComparisonUtils - ******** Comparing files:
17:01:08.966 [main ] INFO it.tidalwave.util.test.FileComparisonUtils - >>>> path is: /var/jenkins_home/jobs/NorthernWind_Build_and_Test/workspace/modules/frontend/Media/target/
17:01:08.967 [main ] INFO it.tidalwave.util.test.FileComparisonUtils - >>>> exp is: test-classes/DefaultMetadataProviderTest/expected-results/MetadataDump-20100102-0001.txt
17:01:08.967 [main ] INFO it.tidalwave.util.test.FileComparisonUtils - >>>> act is: test-artifacts/DefaultMetadataProviderTest/MetadataDump-20100102-0001.txt
17:01:08.988 [main ] INFO i.t.n.f.media.impl.DefaultMetadataProviderTest - TEST PASSED in 457 msec
17:01:08.988 [main ] INFO i.t.n.f.media.impl.DefaultMetadataProviderTest -
17:01:09.181 [main ] INFO i.t.n.f.m.i.i.ScanningMetadataInterpolatorFactory - Scanning for metadata interpolators...
17:01:13.956 [main ] INFO i.t.n.f.m.i.i.ScanningMetadataInterpolatorFactory - >>>> added metadata interpolator: class it.tidalwave.northernwind.frontend.media.impl.interpolator.ShootingDataInterpolator
17:01:13.957 [main ] INFO i.t.n.f.m.i.i.ScanningMetadataInterpolatorFactory - >>>> added metadata interpolator: class it.tidalwave.northernwind.frontend.media.impl.interpolator.XmpDcTitleInterpolator
17:01:13.958 [main ] INFO i.t.northernwind.util.test.SpringTestHelper - Beans: [org.springframework.context.annotation.internalConfigurationAnnotationProcessor, org.springframework.context.annotation.internalAutowiredAnnotationProcessor, org.springframework.context.annotation.internalCommonAnnotationProcessor, org.springframework.context.event.internalEventListenerProcessor, org.springframework.context.event.internalEventListenerFactory, org.springframework.context.config.internalBeanConfigurerAspect, metadataInterpolators]
17:01:14.029 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataTest - ********************************************************************************************************************************************************************************************
17:01:14.029 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataTest - TEST "must properly interpolate metadata string"
17:01:14.029 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataTest - ARGS [MetadataTestBuilder(xmpDcTitle=The title 1, exifModel=Model1, exifFocalLength=7000000/100000, exifExposureTime=1/640, exifFNumber=1100000/100000, exifExposureBiasValue=-2/3, exifIsoSpeedRatings=100, xmpAuxLensId=1), Foo bar $shootingData$ foo bar $XMP.dc.title$ baz bar foo, Foo bar Model1 + Lens1 @ 70 mm, 1/640 sec @ ƒ/11, -0.67 EV, ISO 100 foo bar The title 1 baz bar foo]
17:01:14.029 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataTest - ********************************************************************************************************************************************************************************************
17:01:14.036 [main ] TRACE org.imajine.image.op.ImplementationFactoryRegistry - findImplementation(CreateOp(10, 10, BYTE, [D@60d4e243), null, canConvert: false)
17:01:14.037 [main ] TRACE org.imajine.image.op.ImplementationFactoryRegistry - >>>> found implementation from ImplementationFactory(modelClass=class java.awt.image.BufferedImage)
17:01:14.037 [main ] DEBUG org.imajine.image.op.OperationImplementation - executing CreateOp(10, 10, BYTE, [D@60d4e243)
May 08, 2023 5:01:14 PM org.imajine.image.java2d.CreateJ2DOp execute
INFO: CreateJ2DOp.execute(CreateOp(10, 10, BYTE, [D@60d4e243), EditableImage(imageModelHolder=SerializableImageModelHolder[null], attributeMapByName={}))
17:01:14.059 [main ] DEBUG org.imajine.image.op.OperationImplementation - >>>> CreateOp(10, 10, BYTE, [D@60d4e243) done in 22 msec
17:01:14.063 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadata - EXIF(test).Model: Model1
17:01:14.063 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadata - EXIF(test).Exposure Time: 1/640
17:01:14.063 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadata - EXIF(test).F Number: 1100000/100000
17:01:14.064 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadata - EXIF(test).ISO Speed Ratings: 100
17:01:14.064 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadata - EXIF(test).Exposure Bias Value: -2/3
17:01:14.064 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadata - EXIF(test).Focal Length: 7000000/100000
17:01:14.064 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadata - XMPprop(test).aux:LensID: 1
17:01:14.064 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadata - XMPprop(test).dc:title[1]: The title 1
17:01:14.086 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataTest - TEST PASSED in 123 msec
17:01:14.092 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataTest -
17:01:14.133 [main ] INFO i.t.n.f.m.i.i.ScanningMetadataInterpolatorFactory - Scanning for metadata interpolators...
17:01:18.423 [main ] INFO i.t.n.f.m.i.i.ScanningMetadataInterpolatorFactory - >>>> added metadata interpolator: class it.tidalwave.northernwind.frontend.media.impl.interpolator.ShootingDataInterpolator
17:01:18.424 [main ] INFO i.t.n.f.m.i.i.ScanningMetadataInterpolatorFactory - >>>> added metadata interpolator: class it.tidalwave.northernwind.frontend.media.impl.interpolator.XmpDcTitleInterpolator
17:01:18.429 [main ] INFO i.t.northernwind.util.test.SpringTestHelper - Beans: [org.springframework.context.annotation.internalConfigurationAnnotationProcessor, org.springframework.context.annotation.internalAutowiredAnnotationProcessor, org.springframework.context.annotation.internalCommonAnnotationProcessor, org.springframework.context.event.internalEventListenerProcessor, org.springframework.context.event.internalEventListenerFactory, org.springframework.context.config.internalBeanConfigurerAspect, metadataInterpolators]
17:01:18.449 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataTest - ********************************************************************************************************************************************************************************************
17:01:18.449 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataTest - TEST "must properly interpolate metadata string"
17:01:18.450 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataTest - ARGS [MetadataTestBuilder(xmpDcTitle=The title 2, exifModel=Model2, exifFocalLength=2000000/100000, exifExposureTime=1/20, exifFNumber=800000/100000, exifExposureBiasValue=1/3, exifIsoSpeedRatings=200, xmpAuxLensId=2), Foo bar $shootingData$ foo bar $XMP.dc.title$ baz bar foo, Foo bar Model2 + Lens2 @ 20 mm, 1/20 sec @ ƒ/8, +0.33 EV, ISO 200 foo bar The title 2 baz bar foo]
17:01:18.450 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataTest - ********************************************************************************************************************************************************************************************
17:01:18.454 [main ] TRACE org.imajine.image.op.ImplementationFactoryRegistry - findImplementation(CreateOp(10, 10, BYTE, [D@13dae445), null, canConvert: false)
17:01:18.454 [main ] TRACE org.imajine.image.op.ImplementationFactoryRegistry - >>>> found implementation from ImplementationFactory(modelClass=class java.awt.image.BufferedImage)
17:01:18.454 [main ] DEBUG org.imajine.image.op.OperationImplementation - executing CreateOp(10, 10, BYTE, [D@13dae445)
May 08, 2023 5:01:18 PM org.imajine.image.java2d.CreateJ2DOp execute
INFO: CreateJ2DOp.execute(CreateOp(10, 10, BYTE, [D@13dae445), EditableImage(imageModelHolder=SerializableImageModelHolder[null], attributeMapByName={}))
17:01:18.455 [main ] DEBUG org.imajine.image.op.OperationImplementation - >>>> CreateOp(10, 10, BYTE, [D@13dae445) done in 1 msec
17:01:18.456 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadata - EXIF(test).Model: Model2
17:01:18.456 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadata - EXIF(test).Exposure Time: 1/20
17:01:18.456 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadata - EXIF(test).F Number: 800000/100000
17:01:18.456 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadata - EXIF(test).ISO Speed Ratings: 200
17:01:18.456 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadata - EXIF(test).Exposure Bias Value: 1/3
17:01:18.456 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadata - EXIF(test).Focal Length: 2000000/100000
17:01:18.456 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadata - XMPprop(test).aux:LensID: 2
17:01:18.456 [main ] DEBUG i.t.n.frontend.media.impl.DefaultMetadata - XMPprop(test).dc:title[1]: The title 2
17:01:18.456 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataTest - TEST PASSED in 7 msec
17:01:18.456 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataTest -
17:01:18.647 [main ] INFO i.t.northernwind.util.test.SpringTestHelper - Beans: [org.springframework.context.annotation.internalConfigurationAnnotationProcessor, org.springframework.context.annotation.internalAutowiredAnnotationProcessor, org.springframework.context.annotation.internalCommonAnnotationProcessor, org.springframework.context.event.internalEventListenerProcessor, org.springframework.context.event.internalEventListenerFactory, org.springframework.context.config.internalBeanConfigurerAspect, underTest, metadataCache]
17:01:18.656 [main ] INFO i.t.n.f.m.impl.EmbeddedMediaMetadataProviderTest - *********************************************
17:01:18.656 [main ] INFO i.t.n.f.m.impl.EmbeddedMediaMetadataProviderTest - TEST "must return empty string when io error"
17:01:18.656 [main ] INFO i.t.n.f.m.impl.EmbeddedMediaMetadataProviderTest - *********************************************
17:01:18.672 [main ] DEBUG i.t.northernwind.aspect.DebugProfilingAspect - getMetadataString(mediaId)
17:01:18.673 [main ] WARN i.t.n.f.media.impl.EmbeddedMediaMetadataProvider - Unexpected I/O error for id mediaId: java.io.IOException: Cannot open file
17:01:18.673 [main ] DEBUG i.t.northernwind.aspect.DebugProfilingAspect - >>>> metadata retrieved in 1 msec
17:01:18.673 [main ] INFO i.t.n.f.m.impl.EmbeddedMediaMetadataProviderTest - TEST PASSED in 17 msec
17:01:18.673 [main ] INFO i.t.n.f.m.impl.EmbeddedMediaMetadataProviderTest -
17:01:18.777 [main ] INFO i.t.northernwind.util.test.SpringTestHelper - Beans: [org.springframework.context.annotation.internalConfigurationAnnotationProcessor, org.springframework.context.annotation.internalAutowiredAnnotationProcessor, org.springframework.context.annotation.internalCommonAnnotationProcessor, org.springframework.context.event.internalEventListenerProcessor, org.springframework.context.event.internalEventListenerFactory, org.springframework.context.config.internalBeanConfigurerAspect, underTest, metadataCache]
17:01:18.795 [main ] INFO i.t.n.f.m.impl.EmbeddedMediaMetadataProviderTest - ****************************************************
17:01:18.795 [main ] INFO i.t.n.f.m.impl.EmbeddedMediaMetadataProviderTest - TEST "must return empty string when media not found"
17:01:18.795 [main ] INFO i.t.n.f.m.impl.EmbeddedMediaMetadataProviderTest - ****************************************************
17:01:18.795 [main ] DEBUG i.t.northernwind.aspect.DebugProfilingAspect - getMetadataString(mediaId)
17:01:18.796 [main ] WARN i.t.n.f.media.impl.EmbeddedMediaMetadataProvider - Cannot find media for id mediaId: it.tidalwave.util.NotFoundException: Media not found
17:01:18.796 [main ] DEBUG i.t.northernwind.aspect.DebugProfilingAspect - >>>> metadata retrieved in 0 msec
17:01:18.796 [main ] INFO i.t.n.f.m.impl.EmbeddedMediaMetadataProviderTest - TEST PASSED in 1 msec
17:01:18.796 [main ] INFO i.t.n.f.m.impl.EmbeddedMediaMetadataProviderTest -
17:01:18.852 [main ] INFO i.t.northernwind.util.test.SpringTestHelper - Beans: [org.springframework.context.annotation.internalConfigurationAnnotationProcessor, org.springframework.context.annotation.internalAutowiredAnnotationProcessor, org.springframework.context.annotation.internalCommonAnnotationProcessor, org.springframework.context.event.internalEventListenerProcessor, org.springframework.context.event.internalEventListenerFactory, org.springframework.context.config.internalBeanConfigurerAspect, underTest, metadataCache]
17:01:18.854 [main ] INFO i.t.n.f.m.impl.EmbeddedMediaMetadataProviderTest - *****************************************************************
17:01:18.854 [main ] INFO i.t.n.f.m.impl.EmbeddedMediaMetadataProviderTest - TEST "must return the interpolated string when metadata is found"
17:01:18.854 [main ] INFO i.t.n.f.m.impl.EmbeddedMediaMetadataProviderTest - *****************************************************************
17:01:18.919 [main ] DEBUG i.t.northernwind.aspect.DebugProfilingAspect - getMetadataString(mediaId)
17:01:18.920 [main ] DEBUG i.t.northernwind.aspect.DebugProfilingAspect - >>>> metadata retrieved in 1 msec
17:01:18.920 [main ] INFO i.t.n.f.m.impl.EmbeddedMediaMetadataProviderTest - TEST PASSED in 66 msec
17:01:18.920 [main ] INFO i.t.n.f.m.impl.EmbeddedMediaMetadataProviderTest -
17:01:18.979 [main ] INFO i.t.n.f.m.i.i.ScanningMetadataInterpolatorFactory - Scanning for metadata interpolators...
17:01:23.186 [main ] INFO i.t.n.f.m.i.i.ScanningMetadataInterpolatorFactory - >>>> added metadata interpolator: class it.tidalwave.northernwind.frontend.media.impl.interpolator.ShootingDataInterpolator
17:01:23.194 [main ] INFO i.t.n.f.m.i.i.ScanningMetadataInterpolatorFactory - >>>> added metadata interpolator: class it.tidalwave.northernwind.frontend.media.impl.interpolator.XmpDcTitleInterpolator
17:01:23.195 [main ] INFO i.t.northernwind.util.test.SpringTestHelper - Beans: [org.springframework.context.annotation.internalConfigurationAnnotationProcessor, org.springframework.context.annotation.internalAutowiredAnnotationProcessor, org.springframework.context.annotation.internalCommonAnnotationProcessor, org.springframework.context.event.internalEventListenerProcessor, org.springframework.context.event.internalEventListenerFactory, org.springframework.context.config.internalBeanConfigurerAspect, underTest]
17:01:23.195 [main ] INFO i.t.n.f.m.i.ScanningMetadataInterpolatorFactoryTest - **************************************
17:01:23.196 [main ] INFO i.t.n.f.m.i.ScanningMetadataInterpolatorFactoryTest - TEST "must find all the interpolators"
17:01:23.196 [main ] INFO i.t.n.f.m.i.ScanningMetadataInterpolatorFactoryTest - **************************************
17:01:23.217 [main ] INFO i.t.n.f.m.i.ScanningMetadataInterpolatorFactoryTest - TEST PASSED in 22 msec
17:01:23.217 [main ] INFO i.t.n.f.m.i.ScanningMetadataInterpolatorFactoryTest -
17:01:23.354 [main ] INFO i.t.n.frontend.media.impl.DefaultMetadataCacheTest - FINISHED TESTS OF it.tidalwave.northernwind.frontend.media.impl.DefaultMetadataCacheTest
[INFO] Tests run: 11, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 20.566 s - in TestSuite
[INFO]
[INFO] Results:
[INFO]
[INFO] Tests run: 11, Failures: 0, Errors: 0, Skipped: 0
[INFO]
[JENKINS] Recording test results
[INFO]
[INFO] --- jar:3.2.0:jar (default-jar) @ it-tidalwave-northernwind-frontend-media ---
[INFO] Building jar: /var/jenkins_home/jobs/NorthernWind_Build_and_Test/workspace/modules/frontend/Media/target/it-tidalwave-northernwind-frontend-media-1.2-ALPHA-9-SNAPSHOT.010b15d1677e.jar
[INFO]
[INFO] --- jar:3.2.0:test-jar (default-test-jar) @ it-tidalwave-northernwind-frontend-media ---
[INFO] Building jar: /var/jenkins_home/jobs/NorthernWind_Build_and_Test/workspace/modules/frontend/Media/target/it-tidalwave-northernwind-frontend-media-1.2-ALPHA-9-SNAPSHOT.010b15d1677e-tests.jar
[INFO]
[INFO] --- install:3.0.0-M1:install (default-install) @ it-tidalwave-northernwind-frontend-media ---
[INFO] Installing /var/jenkins_home/jobs/NorthernWind_Build_and_Test/workspace/modules/frontend/Media/target/it-tidalwave-northernwind-frontend-media-1.2-ALPHA-9-SNAPSHOT.010b15d1677e.jar to /var/jenkins_home/maven-repositories/0/it/tidalwave/northernwind/it-tidalwave-northernwind-frontend-media/1.2-ALPHA-9-SNAPSHOT/it-tidalwave-northernwind-frontend-media-1.2-ALPHA-9-SNAPSHOT.jar
[INFO] Installing /var/jenkins_home/jobs/NorthernWind_Build_and_Test/workspace/modules/frontend/Media/pom.xml to /var/jenkins_home/maven-repositories/0/it/tidalwave/northernwind/it-tidalwave-northernwind-frontend-media/1.2-ALPHA-9-SNAPSHOT/it-tidalwave-northernwind-frontend-media-1.2-ALPHA-9-SNAPSHOT.pom
[INFO] Installing /var/jenkins_home/jobs/NorthernWind_Build_and_Test/workspace/modules/frontend/Media/target/it-tidalwave-northernwind-frontend-media-1.2-ALPHA-9-SNAPSHOT.010b15d1677e-tests.jar to /var/jenkins_home/maven-repositories/0/it/tidalwave/northernwind/it-tidalwave-northernwind-frontend-media/1.2-ALPHA-9-SNAPSHOT/it-tidalwave-northernwind-frontend-media-1.2-ALPHA-9-SNAPSHOT-tests.jar
[JENKINS] Archiving disabled
![[Jenkins]](/ci/static/0840cea4/images/svgs/logo.svg){kind=logo}
