[2024-10-21T10:40:16.813Z] 
[2024-10-21T10:40:16.814Z] C:\Jenkins\agent\workspace\lockable-resources-plugin_PR-214>mvn --batch-mode --show-version --errors --no-transfer-progress --update-snapshots -Dmaven.repo.local=C:/Jenkins/agent/workspace/lockable-resources-plugin_PR-214@tmp/m2repo -Dmaven.test.failure.ignore -Dspotbugs.failOnError=false -Dcheckstyle.failOnViolation=false -Dcheckstyle.failsOnError=false -Dpmd.failOnViolation=false -Dset.changelist -DforkCount=1C clean install 
[2024-10-21T10:40:17.225Z] -XX:ConcGCThreads=1 -XX:G1ConcRefinementThreads=4 -XX:GCDrainStackTargetSize=64 -XX:InitialHeapSize=134200512 -XX:MarkStackSize=4194304 -XX:MaxHeapSize=2147208192 -XX:MinHeapSize=6815736 -XX:+PrintCommandLineFlags -XX:ReservedCodeCacheSize=251658240 -XX:+SegmentedCodeCache -XX:+UseCompressedClassPointers -XX:+UseCompressedOops -XX:+UseG1GC -XX:-UseLargePagesIndividualAllocation 
[2024-10-21T10:40:18.445Z] Apache Maven 3.9.9 (8e8579a9e76f7d015ee5ec7bfcdc97d260186937)
[2024-10-21T10:40:18.448Z] Maven home: C:\tools\apache-maven-3.9.9
[2024-10-21T10:40:18.448Z] Java version: 17.0.12, vendor: Eclipse Adoptium, runtime: C:\tools\jdk-17
[2024-10-21T10:40:18.449Z] Default locale: en_US, platform encoding: UTF-8
[2024-10-21T10:40:18.451Z] OS name: "windows server 2019", version: "10.0", arch: "amd64", family: "windows"
[2024-10-21T10:40:26.312Z] [INFO] Error stacktraces are turned on.
[2024-10-21T10:40:27.360Z] [INFO] Setting: -Dchangelist=1334.v8a_c241734b_55 -DscmTag=8ac241734b559d3180559b149aa4644723d9db93 -Dproject.build.outputTimestamp=2024-10-21T10:34:51Z
[2024-10-21T10:40:27.362Z] [INFO] Setting: -DgitHubRepo=cfoote/lockable-resources-plugin
[2024-10-21T10:40:27.367Z] [INFO] Scanning for projects...
[2024-10-21T10:40:35.982Z] [INFO] 
[2024-10-21T10:40:35.991Z] [INFO] ----------------< org.6wind.jenkins:lockable-resources >----------------
[2024-10-21T10:40:36.018Z] [INFO] Building Lockable Resources plugin 1334.v8a_c241734b_55
[2024-10-21T10:40:36.049Z] [INFO]   from pom.xml
[2024-10-21T10:40:36.050Z] [INFO] --------------------------------[ hpi ]---------------------------------
[2024-10-21T10:40:50.649Z] [INFO] 
[2024-10-21T10:40:50.650Z] [INFO] --- clean:3.4.0:clean (default-clean) @ lockable-resources ---
[2024-10-21T10:40:50.856Z] [INFO] 
[2024-10-21T10:40:50.868Z] [INFO] --- hpi:3.57:validate (default-validate) @ lockable-resources ---
[2024-10-21T10:40:50.870Z] [INFO] Created marker file C:\Jenkins\agent\workspace\lockable-resources-plugin_PR-214\target\java-level\11
[2024-10-21T10:40:50.871Z] [INFO] 
[2024-10-21T10:40:50.872Z] [INFO] --- hpi:3.57:validate-hpi (default-validate-hpi) @ lockable-resources ---
[2024-10-21T10:40:53.428Z] [INFO] 
[2024-10-21T10:40:53.430Z] [INFO] --- enforcer:3.5.0:enforce (display-info) @ lockable-resources ---
[2024-10-21T10:40:54.146Z] [INFO] Rule 0: io.jenkins.tools.incrementals.enforcer.RequireExtensionVersion passed
[2024-10-21T10:40:54.147Z] [INFO] Rule 1: org.apache.maven.enforcer.rules.version.RequireMavenVersion passed
[2024-10-21T10:40:54.250Z] [INFO] Rule 2: org.apache.maven.enforcer.rules.version.RequireJavaVersion passed
[2024-10-21T10:40:56.004Z] [INFO] Rule 3: org.codehaus.mojo.extraenforcer.dependencies.EnforceBytecodeVersion passed
[2024-10-21T10:40:56.319Z] [INFO] Rule 4: org.apache.maven.enforcer.rules.dependency.BannedDependencies passed
[2024-10-21T10:40:56.320Z] [INFO] Ignoring requireUpperBoundDeps in org.ow2.asm:asm
[2024-10-21T10:40:56.320Z] [INFO] Ignoring requireUpperBoundDeps in org.ow2.asm:asm-tree
[2024-10-21T10:40:56.323Z] [INFO] Ignoring requireUpperBoundDeps in org.ow2.asm:asm-util
[2024-10-21T10:40:56.324Z] [INFO] Ignoring requireUpperBoundDeps in org.ow2.asm:asm-analysis
[2024-10-21T10:40:56.324Z] [INFO] Ignoring requireUpperBoundDeps in org.ow2.asm:asm-commons
[2024-10-21T10:40:56.325Z] [INFO] Rule 5: org.apache.maven.enforcer.rules.dependency.RequireUpperBoundDeps passed
[2024-10-21T10:40:56.327Z] [INFO] 
[2024-10-21T10:40:56.330Z] [INFO] --- enforcer:3.5.0:enforce (no-snapshots-in-release) @ lockable-resources ---
[2024-10-21T10:40:56.432Z] [INFO] Rule 0: org.apache.maven.enforcer.rules.dependency.RequireReleaseDeps passed
[2024-10-21T10:40:56.435Z] [INFO] 
[2024-10-21T10:40:56.438Z] [INFO] --- localizer:1.31:generate (default) @ lockable-resources ---
[2024-10-21T10:40:58.184Z] [INFO] 
[2024-10-21T10:40:58.197Z] [INFO] --- resources:3.3.1:resources (default-resources) @ lockable-resources ---
[2024-10-21T10:40:58.306Z] [INFO] Copying 82 resources from src\main\resources to target\classes
[2024-10-21T10:40:58.411Z] [INFO] 
[2024-10-21T10:40:58.414Z] [INFO] --- flatten:1.6.0:flatten (flatten) @ lockable-resources ---
[2024-10-21T10:40:58.619Z] [INFO] Generating flattened POM of project org.6wind.jenkins:lockable-resources:hpi:1334.v8a_c241734b_55...
[2024-10-21T10:40:58.828Z] [INFO] 
[2024-10-21T10:40:58.834Z] [INFO] --- compiler:3.13.0:compile (default-compile) @ lockable-resources ---
[2024-10-21T10:40:59.341Z] [INFO] Recompiling the module because of changed dependency.
[2024-10-21T10:40:59.369Z] [INFO] Compiling 24 source files with javac [debug parameters release 11] to target\classes
[2024-10-21T10:41:06.500Z] [INFO] org.jenkins.plugins.lockableresources.LockStep.DescriptorImpl indexed under hudson.Extension
[2024-10-21T10:41:06.501Z] [INFO] org.jenkins.plugins.lockableresources.LockStepResource.DescriptorImpl indexed under hudson.Extension
[2024-10-21T10:41:06.503Z] [INFO] org.jenkins.plugins.lockableresources.LockableResource.DescriptorImpl indexed under hudson.Extension
[2024-10-21T10:41:06.503Z] [INFO] org.jenkins.plugins.lockableresources.LockableResourceProperty.DescriptorImpl indexed under hudson.Extension
[2024-10-21T10:41:06.504Z] [INFO] org.jenkins.plugins.lockableresources.LockableResourcesManager indexed under hudson.Extension
[2024-10-21T10:41:06.504Z] [INFO] org.jenkins.plugins.lockableresources.RequiredResourcesProperty.DescriptorImpl indexed under hudson.Extension
[2024-10-21T10:41:06.504Z] [INFO] org.jenkins.plugins.lockableresources.actions.LockableResourcesRootAction indexed under hudson.Extension
[2024-10-21T10:41:06.514Z] [INFO] org.jenkins.plugins.lockableresources.actions.ResourceVariableNameAction.ResourceVariableNameActionEnvironmentContributor indexed under hudson.Extension
[2024-10-21T10:41:06.514Z] [INFO] org.jenkins.plugins.lockableresources.NodesMirror indexed under hudson.Extension
[2024-10-21T10:41:06.515Z] [INFO] org.jenkins.plugins.lockableresources.queue.LockRunListener indexed under hudson.Extension
[2024-10-21T10:41:06.517Z] [INFO] org.jenkins.plugins.lockableresources.queue.LockableResourcesQueueTaskDispatcher indexed under hudson.Extension
[2024-10-21T10:41:06.520Z] [WARNING] /C:/Jenkins/agent/workspace/lockable-resources-plugin_PR-214/src/main/java/org/jenkins/plugins/lockableresources/LockableResource.java:[234,17] deprecated item is not annotated with @Deprecated
[2024-10-21T10:41:06.522Z] [INFO] /C:/Jenkins/agent/workspace/lockable-resources-plugin_PR-214/src/main/java/org/jenkins/plugins/lockableresources/RequiredResourcesProperty.java: Some input files use or override a deprecated API.
[2024-10-21T10:41:06.522Z] [INFO] /C:/Jenkins/agent/workspace/lockable-resources-plugin_PR-214/src/main/java/org/jenkins/plugins/lockableresources/RequiredResourcesProperty.java: Recompile with -Xlint:deprecation for details.
[2024-10-21T10:41:06.523Z] [INFO] 
[2024-10-21T10:41:06.523Z] [INFO] --- access-modifier-checker:1.33:enforce (default-enforce) @ lockable-resources ---
[2024-10-21T10:41:07.251Z] [INFO] 
[2024-10-21T10:41:07.252Z] [INFO] --- hpi:3.57:insert-test (default-insert-test) @ lockable-resources ---
[2024-10-21T10:41:07.253Z] [INFO] 
[2024-10-21T10:41:07.253Z] [INFO] --- antrun:3.1.0:run (createTempDir) @ lockable-resources ---
[2024-10-21T10:41:07.866Z] [INFO] Executing tasks
[2024-10-21T10:41:07.868Z] [INFO]     [mkdir] Created dir: C:\Jenkins\agent\workspace\lockable-resources-plugin_PR-214\target\tmp
[2024-10-21T10:41:07.869Z] [INFO] Executed tasks
[2024-10-21T10:41:07.874Z] [INFO] 
[2024-10-21T10:41:07.877Z] [INFO] --- resources:3.3.1:testResources (default-testResources) @ lockable-resources ---
[2024-10-21T10:41:07.878Z] [INFO] Copying 3 resources from src\test\resources to target\test-classes
[2024-10-21T10:41:07.879Z] [INFO] 
[2024-10-21T10:41:07.879Z] [INFO] --- compiler:3.13.0:testCompile (default-testCompile) @ lockable-resources ---
[2024-10-21T10:41:07.983Z] [INFO] Recompiling the module because of changed dependency.
[2024-10-21T10:41:07.984Z] [INFO] Compiling 22 source files with javac [debug parameters release 11] to target\test-classes
[2024-10-21T10:41:12.226Z] [INFO] /C:/Jenkins/agent/workspace/lockable-resources-plugin_PR-214/src/test/java/org/jenkins/plugins/lockableresources/ConcurrentModificationExceptionTest.java: Some input files use or override a deprecated API.
[2024-10-21T10:41:12.227Z] [INFO] /C:/Jenkins/agent/workspace/lockable-resources-plugin_PR-214/src/test/java/org/jenkins/plugins/lockableresources/ConcurrentModificationExceptionTest.java: Recompile with -Xlint:deprecation for details.
[2024-10-21T10:41:12.227Z] [INFO] 
[2024-10-21T10:41:12.228Z] [INFO] --- hpi:3.57:test-hpl (default-test-hpl) @ lockable-resources ---
[2024-10-21T10:41:12.229Z] [INFO] Generating C:\Jenkins\agent\workspace\lockable-resources-plugin_PR-214\target\test-classes\the.hpl
[2024-10-21T10:41:14.374Z] [INFO] 
[2024-10-21T10:41:14.376Z] [INFO] --- hpi:3.57:resolve-test-dependencies (default-resolve-test-dependencies) @ lockable-resources ---
[2024-10-21T10:41:19.334Z] [INFO] 
[2024-10-21T10:41:19.335Z] [INFO] --- hpi:3.57:test-runtime (default-test-runtime) @ lockable-resources ---
[2024-10-21T10:41:19.335Z] [INFO] Setting jenkins.addOpens to --add-opens java.base/java.lang=ALL-UNNAMED --add-opens java.base/java.io=ALL-UNNAMED --add-opens java.base/java.util=ALL-UNNAMED
[2024-10-21T10:41:19.336Z] [INFO] Setting jenkins.insaneHook to --patch-module='java.base=C:\Jenkins\agent\workspace\lockable-resources-plugin_PR-214\target\patch-modules\org-netbeans-insane-hook.jar' --add-exports=java.base/org.netbeans.insane.hook=ALL-UNNAMED
[2024-10-21T10:41:19.342Z] [INFO] 
[2024-10-21T10:41:19.342Z] [INFO] --- surefire:3.5.0:test (default-test) @ lockable-resources ---
[2024-10-21T10:41:19.992Z] [INFO] Surefire report directory: C:\Jenkins\agent\workspace\lockable-resources-plugin_PR-214\target\surefire-reports
[2024-10-21T10:41:19.992Z] [INFO] Using auto detected provider org.apache.maven.surefire.junitplatform.JUnitPlatformProvider
[2024-10-21T10:41:20.401Z] [INFO] 
[2024-10-21T10:41:20.402Z] [INFO] -------------------------------------------------------
[2024-10-21T10:41:20.402Z] [INFO]  T E S T S
[2024-10-21T10:41:20.402Z] [INFO] -------------------------------------------------------
[2024-10-21T10:41:21.212Z] Running tests for org.6wind.jenkins:lockable-resources:1334.v8a_c241734b_55
[2024-10-21T10:41:26.784Z] [INFO] Running org.jenkins.plugins.lockableresources.ConfigurationAsCodeTest
[2024-10-21T10:41:26.891Z] [INFO] Running org.jenkins.plugins.lockableresources.DeclarativePipelineTest
[2024-10-21T10:41:26.892Z] [INFO] Running org.jenkins.plugins.lockableresources.ConcurrentModificationExceptionTest
[2024-10-21T10:41:26.996Z] [INFO] Running org.jenkins.plugins.lockableresources.FreeStyleProjectTest
[2024-10-21T10:42:55.404Z] [INFO] Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 87.99 s -- in org.jenkins.plugins.lockableresources.ConcurrentModificationExceptionTest
[2024-10-21T10:43:05.010Z] [INFO] Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 94.79 s -- in org.jenkins.plugins.lockableresources.ConfigurationAsCodeTest
[2024-10-21T10:43:17.476Z] [INFO] Running org.jenkins.plugins.lockableresources.InteroperabilityTest
[2024-10-21T10:43:18.788Z] [INFO] Running org.jenkins.plugins.lockableresources.InjectedTest
[2024-10-21T10:43:52.509Z] [INFO] Tests run: 5, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 145.6 s -- in org.jenkins.plugins.lockableresources.DeclarativePipelineTest
[2024-10-21T10:43:57.137Z] [INFO] Running org.jenkins.plugins.lockableresources.LockStepHardKillTest
[2024-10-21T10:44:16.512Z] [INFO] Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 58.99 s -- in org.jenkins.plugins.lockableresources.InteroperabilityTest
[2024-10-21T10:44:20.640Z] [INFO] Tests run: 70, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 61.83 s -- in org.jenkins.plugins.lockableresources.InjectedTest
[2024-10-21T10:44:21.158Z] [INFO] Running org.jenkins.plugins.lockableresources.LockStepTest
[2024-10-21T10:44:26.093Z] [INFO] Running org.jenkins.plugins.lockableresources.LockStepWithRestartTest
[2024-10-21T10:45:21.882Z] [INFO] Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 84.67 s -- in org.jenkins.plugins.lockableresources.LockStepHardKillTest
[2024-10-21T10:45:26.372Z] [INFO] Running org.jenkins.plugins.lockableresources.LockableResourceApiTest
[2024-10-21T10:46:56.984Z] [INFO] Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 90.61 s -- in org.jenkins.plugins.lockableresources.LockableResourceApiTest
[2024-10-21T10:47:01.341Z] [INFO] Running org.jenkins.plugins.lockableresources.LockableResourceManagerTest
[2024-10-21T10:47:23.765Z] [ERROR] Tests run: 15, Failures: 1, Errors: 0, Skipped: 0, Time elapsed: 356.5 s <<< FAILURE! -- in org.jenkins.plugins.lockableresources.FreeStyleProjectTest
[2024-10-21T10:47:23.854Z] [ERROR] org.jenkins.plugins.lockableresources.FreeStyleProjectTest.parallelResourceFromParameter -- Time elapsed: 9.967 s <<< FAILURE!
[2024-10-21T10:47:23.869Z] java.lang.AssertionError: 
[2024-10-21T10:47:23.870Z] 
[2024-10-21T10:47:23.870Z] Expected: a string containing "released lock on [resource1]"
[2024-10-21T10:47:23.873Z]      but: was "Started
[2024-10-21T10:47:23.873Z] Running as SYSTEM
[2024-10-21T10:47:23.874Z] [lockable-resources] acquired lock on [resource1]
[2024-10-21T10:47:23.874Z] Building in workspace C:\Jenkins\agent\workspace\lockable-resources-plugin_PR-214\target\tmp\j h11327397704836230550\workspace\f
[2024-10-21T10:47:23.874Z] Waiting...
[2024-10-21T10:47:23.875Z] Continue
[2024-10-21T10:47:23.875Z] Finished: SUCCESS
[2024-10-21T10:47:23.876Z] "
[2024-10-21T10:47:23.876Z] 	at org.hamcrest.MatcherAssert.assertThat(MatcherAssert.java:20)
[2024-10-21T10:47:23.876Z] 	at org.hamcrest.MatcherAssert.assertThat(MatcherAssert.java:6)
[2024-10-21T10:47:23.877Z] 	at org.jvnet.hudson.test.JenkinsRule.assertLogContains(JenkinsRule.java:1570)
[2024-10-21T10:47:23.877Z] 	at org.jvnet.hudson.test.JenkinsRule.waitForMessage(JenkinsRule.java:1630)
[2024-10-21T10:47:23.877Z] 	at org.jenkins.plugins.lockableresources.FreeStyleProjectTest.parallelResourceFromParameter(FreeStyleProjectTest.java:362)
[2024-10-21T10:47:23.878Z] 	at java.base/java.lang.reflect.Method.invoke(Method.java:569)
[2024-10-21T10:47:23.878Z] 	at org.jvnet.hudson.test.JenkinsRule$1.evaluate(JenkinsRule.java:655)
[2024-10-21T10:47:23.878Z] 	at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
[2024-10-21T10:47:23.879Z] 	at java.base/java.lang.Thread.run(Thread.java:840)
[2024-10-21T10:47:23.879Z] 	Suppressed: java.io.IOException: C:\Jenkins\agent\workspace\lockable-resources-plugin_PR-214\target\tmp\j h11327397704836230550\jobs\f\builds\3\atomic10894485190840161835tmp
[2024-10-21T10:47:23.880Z] 		at org.jvnet.hudson.test.TemporaryDirectoryAllocator.delete(TemporaryDirectoryAllocator.java:144)
[2024-10-21T10:47:23.882Z] 		at org.jvnet.hudson.test.TemporaryDirectoryAllocator.delete(TemporaryDirectoryAllocator.java:131)
[2024-10-21T10:47:23.882Z] 		at org.jvnet.hudson.test.TemporaryDirectoryAllocator.delete(TemporaryDirectoryAllocator.java:131)
[2024-10-21T10:47:23.883Z] 		at org.jvnet.hudson.test.TemporaryDirectoryAllocator.delete(TemporaryDirectoryAllocator.java:131)
[2024-10-21T10:47:23.883Z] 		at org.jvnet.hudson.test.TemporaryDirectoryAllocator.delete(TemporaryDirectoryAllocator.java:131)
[2024-10-21T10:47:23.884Z] 		at org.jvnet.hudson.test.TemporaryDirectoryAllocator.dispose(TemporaryDirectoryAllocator.java:99)
[2024-10-21T10:47:23.884Z] 		at org.jvnet.hudson.test.TestEnvironment.dispose(TestEnvironment.java:84)
[2024-10-21T10:47:23.884Z] 		at org.jvnet.hudson.test.JenkinsRule.after(JenkinsRule.java:535)
[2024-10-21T10:47:23.885Z] 		at org.jvnet.hudson.test.JenkinsRule$1.evaluate(JenkinsRule.java:673)
[2024-10-21T10:47:23.886Z] 		... 2 more
[2024-10-21T10:47:23.887Z] 	Caused by: java.nio.file.DirectoryNotEmptyException: C:\Jenkins\agent\workspace\lockable-resources-plugin_PR-214\target\tmp\j h11327397704836230550\jobs\f\builds\3
[2024-10-21T10:47:23.887Z] 		at java.base/sun.nio.fs.WindowsFileSystemProvider.implDelete(WindowsFileSystemProvider.java:272)
[2024-10-21T10:47:23.893Z] 		at java.base/sun.nio.fs.AbstractFileSystemProvider.deleteIfExists(AbstractFileSystemProvider.java:110)
[2024-10-21T10:47:23.893Z] 		at java.base/java.nio.file.Files.deleteIfExists(Files.java:1191)
[2024-10-21T10:47:23.895Z] 		at org.jvnet.hudson.test.TemporaryDirectoryAllocator.delete(TemporaryDirectoryAllocator.java:141)
[2024-10-21T10:47:23.895Z] 		... 10 more
[2024-10-21T10:47:23.896Z] 
[2024-10-21T10:47:28.467Z] [INFO] Running org.jenkins.plugins.lockableresources.LockableResourceRootActionSEC1361Test
[2024-10-21T10:48:00.145Z] [INFO] Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 58.75 s -- in org.jenkins.plugins.lockableresources.LockableResourceManagerTest
[2024-10-21T10:48:04.655Z] [INFO] Running org.jenkins.plugins.lockableresources.LockableResourceTest
[2024-10-21T10:48:04.776Z] [INFO] Tests run: 11, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.133 s -- in org.jenkins.plugins.lockableresources.LockableResourceTest
[2024-10-21T10:48:07.379Z] [INFO] Running org.jenkins.plugins.lockableresources.NodesMirrorTest
[2024-10-21T10:49:16.616Z] [INFO] Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 69.07 s -- in org.jenkins.plugins.lockableresources.NodesMirrorTest
[2024-10-21T10:49:22.732Z] [INFO] Running org.jenkins.plugins.lockableresources.PressureTest
[2024-10-21T10:49:25.695Z] [INFO] Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 117.1 s -- in org.jenkins.plugins.lockableresources.LockableResourceRootActionSEC1361Test
[2024-10-21T10:49:31.694Z] [INFO] Running org.jenkins.plugins.lockableresources.actions.LockableResourcesRootActionTest
[2024-10-21T10:50:05.803Z] Sending interrupt signal to process
[2024-10-21T10:50:10.984Z] Terminate batch job (Y/N)? 
[2024-10-21T10:50:25.804Z] After 20s process did not stop