You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@pig.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2011/07/21 03:29:25 UTC
Build failed in Jenkins: Pig-trunk-commit #867
See <https://builds.apache.org/job/Pig-trunk-commit/867/changes>
Changes:
[daijy] PIG-2125: Make Pig work with hadoop .NEXT (PIG-2125-5.patch)
------------------------------------------
[...truncated 39623 lines...]
[junit] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
[junit] at java.lang.reflect.Method.invoke(Method.java:597)
[junit] at org.apache.hadoop.ipc.RPC$Server.call(RPC.java:508)
[junit] at org.apache.hadoop.ipc.Server$Handler$1.run(Server.java:959)
[junit] at org.apache.hadoop.ipc.Server$Handler$1.run(Server.java:955)
[junit] at java.security.AccessController.doPrivileged(Native Method)
[junit] at javax.security.auth.Subject.doAs(Subject.java:396)
[junit] at org.apache.hadoop.ipc.Server$Handler.run(Server.java:953)
[junit]
[junit] org.apache.hadoop.ipc.RemoteException: java.io.IOException: Could not complete write to file /tmp/TestStore-output--809804008553611944.txt_cleanupOnFailure_succeeded2 by DFSClient_-700297148
[junit] at org.apache.hadoop.hdfs.server.namenode.NameNode.complete(NameNode.java:449)
[junit] at sun.reflect.GeneratedMethodAccessor18.invoke(Unknown Source)
[junit] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
[junit] at java.lang.reflect.Method.invoke(Method.java:597)
[junit] at org.apache.hadoop.ipc.RPC$Server.call(RPC.java:508)
[junit] at org.apache.hadoop.ipc.Server$Handler$1.run(Server.java:959)
[junit] at org.apache.hadoop.ipc.Server$Handler$1.run(Server.java:955)
[junit] at java.security.AccessController.doPrivileged(Native Method)
[junit] at javax.security.auth.Subject.doAs(Subject.java:396)
[junit] at org.apache.hadoop.ipc.Server$Handler.run(Server.java:953)
[junit]
[junit] at org.apache.hadoop.ipc.Client.call(Client.java:740)
[junit] at org.apache.hadoop.ipc.RPC$Invoker.invoke(RPC.java:220)
[junit] at $Proxy0.complete(Unknown Source)
[junit] at sun.reflect.GeneratedMethodAccessor18.invoke(Unknown Source)
[junit] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
[junit] at java.lang.reflect.Method.invoke(Method.java:597)
[junit] at org.apache.hadoop.io.retry.RetryInvocationHandler.invokeMethod(RetryInvocationHandler.java:82)
[junit] at org.apache.hadoop.io.retry.RetryInvocationHandler.invoke(RetryInvocationHandler.java:59)
[junit] at $Proxy0.complete(Unknown Source)
[junit] at org.apache.hadoop.hdfs.DFSClient$DFSOutputStream.closeInternal(DFSClient.java:3264)
[junit] at org.apache.hadoop.hdfs.DFSClient$DFSOutputStream.close(DFSClient.java:3188)
[junit] at org.apache.hadoop.hdfs.DFSClient$LeaseChecker.close(DFSClient.java:1043)
[junit] at org.apache.hadoop.hdfs.DFSClient.close(DFSClient.java:237)
[junit] at org.apache.hadoop.hdfs.DistributedFileSystem.close(DistributedFileSystem.java:269)
[junit] at org.apache.pig.test.MiniGenericCluster.shutdownMiniDfsClusters(MiniGenericCluster.java:83)
[junit] at org.apache.pig.test.MiniGenericCluster.shutdownMiniDfsAndMrClusters(MiniGenericCluster.java:77)
[junit] at org.apache.pig.test.MiniGenericCluster.shutDown(MiniGenericCluster.java:68)
[junit] at org.apache.pig.test.TestStore.oneTimeTearDown(TestStore.java:127)
[junit] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit] at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
[junit] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
[junit] at java.lang.reflect.Method.invoke(Method.java:597)
[junit] at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:44)
[junit] at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15)
[junit] at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:41)
[junit] at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:37)
[junit] at org.junit.runners.ParentRunner.run(ParentRunner.java:220)
[junit] at junit.framework.JUnit4TestAdapter.run(JUnit4TestAdapter.java:39)
[junit] at org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.run(JUnitTestRunner.java:420)
[junit] at org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.launch(JUnitTestRunner.java:911)
[junit] at org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.main(JUnitTestRunner.java:768)
[junit] 11/07/21 01:29:12 WARN hdfs.StateChange: DIR* NameSystem.completeFile: failed to complete /tmp/TestStore-output-4697537299435714937.txt_cleanupOnFailure_succeeded because dir.getFileBlocks() is null and pendingFile is null
[junit] 11/07/21 01:29:12 INFO ipc.Server: IPC Server handler 8 on 57699, call complete(/tmp/TestStore-output-4697537299435714937.txt_cleanupOnFailure_succeeded, DFSClient_-700297148) from 127.0.0.1:35943: error: java.io.IOException: Could not complete write to file /tmp/TestStore-output-4697537299435714937.txt_cleanupOnFailure_succeeded by DFSClient_-700297148
[junit] java.io.IOException: Could not complete write to file /tmp/TestStore-output-4697537299435714937.txt_cleanupOnFailure_succeeded by DFSClient_-700297148
[junit] at org.apache.hadoop.hdfs.server.namenode.NameNode.complete(NameNode.java:449)
[junit] at sun.reflect.GeneratedMethodAccessor18.invoke(Unknown Source)
[junit] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
[junit] at java.lang.reflect.Method.invoke(Method.java:597)
[junit] at org.apache.hadoop.ipc.RPC$Server.call(RPC.java:508)
[junit] at org.apache.hadoop.ipc.Server$Handler$1.run(Server.java:959)
[junit] at org.apache.hadoop.ipc.Server$Handler$1.run(Server.java:955)
[junit] at java.security.AccessController.doPrivileged(Native Method)
[junit] at javax.security.auth.Subject.doAs(Subject.java:396)
[junit] at org.apache.hadoop.ipc.Server$Handler.run(Server.java:953)
[junit] 11/07/21 01:29:12 ERROR hdfs.DFSClient: Exception closing file /tmp/TestStore-output-4697537299435714937.txt_cleanupOnFailure_succeeded : org.apache.hadoop.ipc.RemoteException: java.io.IOException: Could not complete write to file /tmp/TestStore-output-4697537299435714937.txt_cleanupOnFailure_succeeded by DFSClient_-700297148
[junit] at org.apache.hadoop.hdfs.server.namenode.NameNode.complete(NameNode.java:449)
[junit] at sun.reflect.GeneratedMethodAccessor18.invoke(Unknown Source)
[junit] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
[junit] at java.lang.reflect.Method.invoke(Method.java:597)
[junit] at org.apache.hadoop.ipc.RPC$Server.call(RPC.java:508)
[junit] at org.apache.hadoop.ipc.Server$Handler$1.run(Server.java:959)
[junit] at org.apache.hadoop.ipc.Server$Handler$1.run(Server.java:955)
[junit] at java.security.AccessController.doPrivileged(Native Method)
[junit] at javax.security.auth.Subject.doAs(Subject.java:396)
[junit] at org.apache.hadoop.ipc.Server$Handler.run(Server.java:953)
[junit]
[junit] org.apache.hadoop.ipc.RemoteException: java.io.IOException: Could not complete write to file /tmp/TestStore-output-4697537299435714937.txt_cleanupOnFailure_succeeded by DFSClient_-700297148
[junit] at org.apache.hadoop.hdfs.server.namenode.NameNode.complete(NameNode.java:449)
[junit] at sun.reflect.GeneratedMethodAccessor18.invoke(Unknown Source)
[junit] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
[junit] at java.lang.reflect.Method.invoke(Method.java:597)
[junit] at org.apache.hadoop.ipc.RPC$Server.call(RPC.java:508)
[junit] at org.apache.hadoop.ipc.Server$Handler$1.run(Server.java:959)
[junit] at org.apache.hadoop.ipc.Server$Handler$1.run(Server.java:955)
[junit] at java.security.AccessController.doPrivileged(Native Method)
[junit] at javax.security.auth.Subject.doAs(Subject.java:396)
[junit] at org.apache.hadoop.ipc.Server$Handler.run(Server.java:953)
[junit]
[junit] at org.apache.hadoop.ipc.Client.call(Client.java:740)
[junit] at org.apache.hadoop.ipc.RPC$Invoker.invoke(RPC.java:220)
[junit] at $Proxy0.complete(Unknown Source)
[junit] at sun.reflect.GeneratedMethodAccessor18.invoke(Unknown Source)
[junit] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
[junit] at java.lang.reflect.Method.invoke(Method.java:597)
[junit] at org.apache.hadoop.io.retry.RetryInvocationHandler.invokeMethod(RetryInvocationHandler.java:82)
[junit] at org.apache.hadoop.io.retry.RetryInvocationHandler.invoke(RetryInvocationHandler.java:59)
[junit] at $Proxy0.complete(Unknown Source)
[junit] at org.apache.hadoop.hdfs.DFSClient$DFSOutputStream.closeInternal(DFSClient.java:3264)
[junit] at org.apache.hadoop.hdfs.DFSClient$DFSOutputStream.close(DFSClient.java:3188)
[junit] at org.apache.hadoop.hdfs.DFSClient$LeaseChecker.close(DFSClient.java:1043)
[junit] at org.apache.hadoop.hdfs.DFSClient.close(DFSClient.java:237)
[junit] at org.apache.hadoop.hdfs.DistributedFileSystem.close(DistributedFileSystem.java:269)
[junit] at org.apache.pig.test.MiniGenericCluster.shutdownMiniDfsClusters(MiniGenericCluster.java:83)
[junit] at org.apache.pig.test.MiniGenericCluster.shutdownMiniDfsAndMrClusters(MiniGenericCluster.java:77)
[junit] at org.apache.pig.test.MiniGenericCluster.shutDown(MiniGenericCluster.java:68)
[junit] at org.apache.pig.test.TestStore.oneTimeTearDown(TestStore.java:127)
[junit] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit] at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
[junit] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
[junit] at java.lang.reflect.Method.invoke(Method.java:597)
[junit] at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:44)
[junit] at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15)
[junit] at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:41)
[junit] at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:37)
[junit] at org.junit.runners.ParentRunner.run(ParentRunner.java:220)
[junit] at junit.framework.JUnit4TestAdapter.run(JUnit4TestAdapter.java:39)
[junit] at org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.run(JUnitTestRunner.java:420)
[junit] at org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.launch(JUnitTestRunner.java:911)
[junit] at org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.main(JUnitTestRunner.java:768)
[junit] Shutting down the Mini HDFS Cluster
[junit] Shutting down DataNode 3
[junit] 11/07/21 01:29:12 INFO ipc.Server: Stopping server on 44010
[junit] 11/07/21 01:29:12 INFO ipc.Server: IPC Server handler 0 on 44010: exiting
[junit] 11/07/21 01:29:12 INFO ipc.Server: Stopping IPC Server listener on 44010
[junit] 11/07/21 01:29:12 INFO ipc.Server: Stopping IPC Server Responder
[junit] 11/07/21 01:29:12 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 1
[junit] 11/07/21 01:29:12 INFO ipc.Server: IPC Server handler 2 on 44010: exiting
[junit] 11/07/21 01:29:12 INFO ipc.Server: IPC Server handler 1 on 44010: exiting
[junit] 11/07/21 01:29:12 WARN datanode.DataNode: DatanodeRegistration(127.0.0.1:43159, storageID=DS-1656991554-127.0.1.1-43159-1311211385251, infoPort=43351, ipcPort=44010):DataXceiveServer: java.nio.channels.AsynchronousCloseException
[junit] at java.nio.channels.spi.AbstractInterruptibleChannel.end(AbstractInterruptibleChannel.java:185)
[junit] at sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:159)
[junit] at sun.nio.ch.ServerSocketAdaptor.accept(ServerSocketAdaptor.java:84)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataXceiverServer.run(DataXceiverServer.java:130)
[junit] at java.lang.Thread.run(Thread.java:662)
[junit]
[junit] 11/07/21 01:29:13 INFO datanode.DataBlockScanner: Exiting DataBlockScanner thread.
[junit] 11/07/21 01:29:13 INFO datanode.DataNode: Deleting block blk_1093319556596601540_1122 file build/test/data/dfs/data/data1/current/blk_1093319556596601540
[junit] 11/07/21 01:29:13 INFO datanode.DataNode: Deleting block blk_5260921591736181723_1123 file build/test/data/dfs/data/data2/current/blk_5260921591736181723
[junit] 11/07/21 01:29:13 INFO datanode.DataNode: Deleting block blk_5531389280537799325_1124 file build/test/data/dfs/data/data1/current/blk_5531389280537799325
[junit] 11/07/21 01:29:13 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0
[junit] 11/07/21 01:29:13 INFO datanode.DataNode: DatanodeRegistration(127.0.0.1:43159, storageID=DS-1656991554-127.0.1.1-43159-1311211385251, infoPort=43351, ipcPort=44010):Finishing DataNode in: FSDataset{dirpath='<https://builds.apache.org/job/Pig-trunk-commit/ws/trunk/build/test/data/dfs/data/data7/current,/grid/0/hudson/hudson-slave/workspace/Pig-trunk-commit/trunk/build/test/data/dfs/data/data8/current'}>
[junit] 11/07/21 01:29:13 INFO ipc.Server: Stopping server on 44010
[junit] 11/07/21 01:29:13 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0
[junit] Shutting down DataNode 2
[junit] 11/07/21 01:29:13 INFO ipc.Server: Stopping server on 60715
[junit] 11/07/21 01:29:13 INFO ipc.Server: IPC Server handler 0 on 60715: exiting
[junit] 11/07/21 01:29:13 INFO ipc.Server: Stopping IPC Server Responder
[junit] 11/07/21 01:29:13 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 1
[junit] 11/07/21 01:29:13 INFO ipc.Server: Stopping IPC Server listener on 60715
[junit] 11/07/21 01:29:13 WARN datanode.DataNode: DatanodeRegistration(127.0.0.1:40027, storageID=DS-1727606284-127.0.1.1-40027-1311211384948, infoPort=45050, ipcPort=60715):DataXceiveServer: java.nio.channels.AsynchronousCloseException
[junit] at java.nio.channels.spi.AbstractInterruptibleChannel.end(AbstractInterruptibleChannel.java:185)
[junit] at sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:159)
[junit] at sun.nio.ch.ServerSocketAdaptor.accept(ServerSocketAdaptor.java:84)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataXceiverServer.run(DataXceiverServer.java:130)
[junit] at java.lang.Thread.run(Thread.java:662)
[junit]
[junit] 11/07/21 01:29:13 INFO ipc.Server: IPC Server handler 1 on 60715: exiting
[junit] 11/07/21 01:29:13 INFO ipc.Server: IPC Server handler 2 on 60715: exiting
[junit] 11/07/21 01:29:13 INFO datanode.DataBlockScanner: Exiting DataBlockScanner thread.
[junit] 11/07/21 01:29:14 INFO datanode.DataNode: DatanodeRegistration(127.0.0.1:40027, storageID=DS-1727606284-127.0.1.1-40027-1311211384948, infoPort=45050, ipcPort=60715):Finishing DataNode in: FSDataset{dirpath='<https://builds.apache.org/job/Pig-trunk-commit/ws/trunk/build/test/data/dfs/data/data5/current,/grid/0/hudson/hudson-slave/workspace/Pig-trunk-commit/trunk/build/test/data/dfs/data/data6/current'}>
[junit] 11/07/21 01:29:14 INFO ipc.Server: Stopping server on 60715
[junit] 11/07/21 01:29:14 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0
[junit] Shutting down DataNode 1
[junit] 11/07/21 01:29:14 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0
[junit] 11/07/21 01:29:14 INFO ipc.Server: Stopping server on 36825
[junit] 11/07/21 01:29:14 INFO ipc.Server: IPC Server handler 0 on 36825: exiting
[junit] 11/07/21 01:29:14 INFO ipc.Server: Stopping IPC Server listener on 36825
[junit] 11/07/21 01:29:14 INFO ipc.Server: Stopping IPC Server Responder
[junit] 11/07/21 01:29:14 INFO ipc.Server: IPC Server handler 2 on 36825: exiting
[junit] 11/07/21 01:29:14 INFO ipc.Server: IPC Server handler 1 on 36825: exiting
[junit] 11/07/21 01:29:14 WARN datanode.DataNode: DatanodeRegistration(127.0.0.1:44770, storageID=DS-2108816015-127.0.1.1-44770-1311211384646, infoPort=44463, ipcPort=36825):DataXceiveServer: java.nio.channels.AsynchronousCloseException
[junit] at java.nio.channels.spi.AbstractInterruptibleChannel.end(AbstractInterruptibleChannel.java:185)
[junit] at sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:159)
[junit] at sun.nio.ch.ServerSocketAdaptor.accept(ServerSocketAdaptor.java:84)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataXceiverServer.run(DataXceiverServer.java:130)
[junit] at java.lang.Thread.run(Thread.java:662)
[junit]
[junit] 11/07/21 01:29:14 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 1
[junit] 11/07/21 01:29:14 INFO datanode.DataBlockScanner: Exiting DataBlockScanner thread.
[junit] 11/07/21 01:29:14 INFO datanode.DataNode: DatanodeRegistration(127.0.0.1:44770, storageID=DS-2108816015-127.0.1.1-44770-1311211384646, infoPort=44463, ipcPort=36825):Finishing DataNode in: FSDataset{dirpath='<https://builds.apache.org/job/Pig-trunk-commit/ws/trunk/build/test/data/dfs/data/data3/current,/grid/0/hudson/hudson-slave/workspace/Pig-trunk-commit/trunk/build/test/data/dfs/data/data4/current'}>
[junit] 11/07/21 01:29:14 INFO ipc.Server: Stopping server on 36825
[junit] 11/07/21 01:29:14 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0
[junit] Shutting down DataNode 0
[junit] 11/07/21 01:29:14 INFO ipc.Server: Stopping server on 59830
[junit] 11/07/21 01:29:14 INFO ipc.Server: Stopping IPC Server listener on 59830
[junit] 11/07/21 01:29:14 INFO ipc.Server: IPC Server handler 1 on 59830: exiting
[junit] 11/07/21 01:29:14 INFO ipc.Server: IPC Server handler 2 on 59830: exiting
[junit] 11/07/21 01:29:14 INFO ipc.Server: IPC Server handler 0 on 59830: exiting
[junit] 11/07/21 01:29:14 INFO ipc.Server: Stopping IPC Server Responder
[junit] 11/07/21 01:29:14 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 1
[junit] 11/07/21 01:29:14 WARN datanode.DataNode: DatanodeRegistration(127.0.0.1:37665, storageID=DS-1319285584-127.0.1.1-37665-1311211384319, infoPort=60397, ipcPort=59830):DataXceiveServer: java.nio.channels.AsynchronousCloseException
[junit] at java.nio.channels.spi.AbstractInterruptibleChannel.end(AbstractInterruptibleChannel.java:185)
[junit] at sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:159)
[junit] at sun.nio.ch.ServerSocketAdaptor.accept(ServerSocketAdaptor.java:84)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataXceiverServer.run(DataXceiverServer.java:130)
[junit] at java.lang.Thread.run(Thread.java:662)
[junit]
[junit] 11/07/21 01:29:15 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:37665 to delete blk_-8017141708210012487_1121 blk_-7695567180087290520_1127 blk_-8026282395271837867_1126
[junit] 11/07/21 01:29:15 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:43159 to delete blk_-7695567180087290520_1127 blk_-8026282395271837867_1126
[junit] 11/07/21 01:29:15 INFO datanode.DataBlockScanner: Exiting DataBlockScanner thread.
[junit] 11/07/21 01:29:15 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0
[junit] 11/07/21 01:29:15 INFO datanode.DataNode: DatanodeRegistration(127.0.0.1:37665, storageID=DS-1319285584-127.0.1.1-37665-1311211384319, infoPort=60397, ipcPort=59830):Finishing DataNode in: FSDataset{dirpath='<https://builds.apache.org/job/Pig-trunk-commit/ws/trunk/build/test/data/dfs/data/data1/current,/grid/0/hudson/hudson-slave/workspace/Pig-trunk-commit/trunk/build/test/data/dfs/data/data2/current'}>
[junit] 11/07/21 01:29:15 INFO ipc.Server: Stopping server on 59830
[junit] 11/07/21 01:29:15 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0
[junit] 11/07/21 01:29:15 WARN namenode.FSNamesystem: ReplicationMonitor thread received InterruptedException.java.lang.InterruptedException: sleep interrupted
[junit] 11/07/21 01:29:15 INFO namenode.FSNamesystem: Number of transactions: 694 Total time for transactions(ms): 13Number of transactions batched in Syncs: 114 Number of syncs: 484 SyncTimes(ms): 5359 479
[junit] 11/07/21 01:29:15 INFO namenode.DecommissionManager: Interrupted Monitor
[junit] java.lang.InterruptedException: sleep interrupted
[junit] at java.lang.Thread.sleep(Native Method)
[junit] at org.apache.hadoop.hdfs.server.namenode.DecommissionManager$Monitor.run(DecommissionManager.java:65)
[junit] at java.lang.Thread.run(Thread.java:662)
[junit] 11/07/21 01:29:15 INFO ipc.Server: Stopping server on 57699
[junit] 11/07/21 01:29:15 INFO ipc.Server: Stopping IPC Server listener on 57699
[junit] 11/07/21 01:29:15 INFO ipc.Server: IPC Server handler 0 on 57699: exiting
[junit] 11/07/21 01:29:15 INFO ipc.Server: IPC Server handler 1 on 57699: exiting
[junit] 11/07/21 01:29:15 INFO ipc.Server: Stopping IPC Server Responder
[junit] 11/07/21 01:29:15 INFO ipc.Server: IPC Server handler 9 on 57699: exiting
[junit] 11/07/21 01:29:15 INFO ipc.Server: IPC Server handler 4 on 57699: exiting
[junit] 11/07/21 01:29:15 INFO ipc.Server: IPC Server handler 2 on 57699: exiting
[junit] 11/07/21 01:29:15 INFO ipc.Server: IPC Server handler 3 on 57699: exiting
[junit] 11/07/21 01:29:15 INFO ipc.Server: IPC Server handler 6 on 57699: exiting
[junit] 11/07/21 01:29:15 INFO ipc.Server: IPC Server handler 5 on 57699: exiting
[junit] 11/07/21 01:29:15 INFO ipc.Server: IPC Server handler 7 on 57699: exiting
[junit] 11/07/21 01:29:15 INFO ipc.Server: IPC Server handler 8 on 57699: exiting
[junit] Tests run: 17, Failures: 0, Errors: 0, Time elapsed: 368.392 sec
[junit] Running org.apache.pig.test.TestStringUDFs
[junit] 11/07/21 01:29:16 WARN builtin.SUBSTRING: java.lang.NullPointerException
[junit] 11/07/21 01:29:16 WARN builtin.SUBSTRING: java.lang.StringIndexOutOfBoundsException: String index out of range: -2
[junit] 11/07/21 01:29:16 WARN builtin.SUBSTRING: java.lang.StringIndexOutOfBoundsException: String index out of range: -1
[junit] 11/07/21 01:29:16 WARN builtin.SUBSTRING: java.lang.StringIndexOutOfBoundsException: String index out of range: -8
[junit] 11/07/21 01:29:16 WARN builtin.SUBSTRING: java.lang.StringIndexOutOfBoundsException: String index out of range: -2
[junit] 11/07/21 01:29:16 WARN builtin.INDEXOF: Failed to process input; error - null
[junit] 11/07/21 01:29:16 WARN builtin.LAST_INDEX_OF: Failed to process input; error - null
[junit] Tests run: 11, Failures: 0, Errors: 0, Time elapsed: 0.122 sec
[delete] Deleting directory /tmp/pig_junit_tmp1751572052
BUILD FAILED
<https://builds.apache.org/job/Pig-trunk-commit/ws/trunk/build.xml>:664: The following error occurred while executing this line:
<https://builds.apache.org/job/Pig-trunk-commit/ws/trunk/build.xml>:719: Tests failed!
Total time: 21 minutes 14 seconds
[FINDBUGS] Skipping publisher since build result is FAILURE
Recording test results
Publishing Javadoc
Archiving artifacts
Recording fingerprints
Publishing Clover coverage report...
No Clover report will be published due to a Build Failure
Jenkins build is back to normal : Pig-trunk-commit #869
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Pig-trunk-commit/869/changes>
Build failed in Jenkins: Pig-trunk-commit #868
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Pig-trunk-commit/868/changes>
Changes:
[thejas] fix import in testcase added as part of - PIG-2146: POStore.getSchema() returns null because of which PigOutputCommitter
is not storing schema while cleanup
------------------------------------------
[...truncated 39692 lines...]
[junit] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
[junit] at java.lang.reflect.Method.invoke(Method.java:597)
[junit] at org.apache.hadoop.ipc.RPC$Server.call(RPC.java:508)
[junit] at org.apache.hadoop.ipc.Server$Handler$1.run(Server.java:959)
[junit] at org.apache.hadoop.ipc.Server$Handler$1.run(Server.java:955)
[junit] at java.security.AccessController.doPrivileged(Native Method)
[junit] at javax.security.auth.Subject.doAs(Subject.java:396)
[junit] at org.apache.hadoop.ipc.Server$Handler.run(Server.java:953)
[junit]
[junit] at org.apache.hadoop.ipc.Client.call(Client.java:740)
[junit] at org.apache.hadoop.ipc.RPC$Invoker.invoke(RPC.java:220)
[junit] at $Proxy0.complete(Unknown Source)
[junit] at sun.reflect.GeneratedMethodAccessor18.invoke(Unknown Source)
[junit] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
[junit] at java.lang.reflect.Method.invoke(Method.java:597)
[junit] at org.apache.hadoop.io.retry.RetryInvocationHandler.invokeMethod(RetryInvocationHandler.java:82)
[junit] at org.apache.hadoop.io.retry.RetryInvocationHandler.invoke(RetryInvocationHandler.java:59)
[junit] at $Proxy0.complete(Unknown Source)
[junit] at org.apache.hadoop.hdfs.DFSClient$DFSOutputStream.closeInternal(DFSClient.java:3264)
[junit] at org.apache.hadoop.hdfs.DFSClient$DFSOutputStream.close(DFSClient.java:3188)
[junit] at org.apache.hadoop.hdfs.DFSClient$LeaseChecker.close(DFSClient.java:1043)
[junit] at org.apache.hadoop.hdfs.DFSClient.close(DFSClient.java:237)
[junit] at org.apache.hadoop.hdfs.DistributedFileSystem.close(DistributedFileSystem.java:269)
[junit] at org.apache.pig.test.MiniGenericCluster.shutdownMiniDfsClusters(MiniGenericCluster.java:83)
[junit] at org.apache.pig.test.MiniGenericCluster.shutdownMiniDfsAndMrClusters(MiniGenericCluster.java:77)
[junit] at org.apache.pig.test.MiniGenericCluster.shutDown(MiniGenericCluster.java:68)
[junit] at org.apache.pig.test.TestStore.oneTimeTearDown(TestStore.java:127)
[junit] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit] at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
[junit] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
[junit] at java.lang.reflect.Method.invoke(Method.java:597)
[junit] at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:44)
[junit] at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15)
[junit] at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:41)
[junit] at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:37)
[junit] at org.junit.runners.ParentRunner.run(ParentRunner.java:220)
[junit] at junit.framework.JUnit4TestAdapter.run(JUnit4TestAdapter.java:39)
[junit] at org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.run(JUnitTestRunner.java:420)
[junit] at org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.launch(JUnitTestRunner.java:911)
[junit] at org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.main(JUnitTestRunner.java:768)
[junit] 11/07/21 02:23:43 WARN hdfs.StateChange: DIR* NameSystem.completeFile: failed to complete /tmp/TestStore-output-1881025561287049326.txt_cleanupOnFailure_succeeded because dir.getFileBlocks() is null and pendingFile is null
[junit] 11/07/21 02:23:43 INFO ipc.Server: IPC Server handler 7 on 37270, call complete(/tmp/TestStore-output-1881025561287049326.txt_cleanupOnFailure_succeeded, DFSClient_492464520) from 127.0.0.1:34834: error: java.io.IOException: Could not complete write to file /tmp/TestStore-output-1881025561287049326.txt_cleanupOnFailure_succeeded by DFSClient_492464520
[junit] java.io.IOException: Could not complete write to file /tmp/TestStore-output-1881025561287049326.txt_cleanupOnFailure_succeeded by DFSClient_492464520
[junit] at org.apache.hadoop.hdfs.server.namenode.NameNode.complete(NameNode.java:449)
[junit] at sun.reflect.GeneratedMethodAccessor18.invoke(Unknown Source)
[junit] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
[junit] at java.lang.reflect.Method.invoke(Method.java:597)
[junit] at org.apache.hadoop.ipc.RPC$Server.call(RPC.java:508)
[junit] at org.apache.hadoop.ipc.Server$Handler$1.run(Server.java:959)
[junit] at org.apache.hadoop.ipc.Server$Handler$1.run(Server.java:955)
[junit] at java.security.AccessController.doPrivileged(Native Method)
[junit] at javax.security.auth.Subject.doAs(Subject.java:396)
[junit] at org.apache.hadoop.ipc.Server$Handler.run(Server.java:953)
[junit] 11/07/21 02:23:43 ERROR hdfs.DFSClient: Exception closing file /tmp/TestStore-output-1881025561287049326.txt_cleanupOnFailure_succeeded : org.apache.hadoop.ipc.RemoteException: java.io.IOException: Could not complete write to file /tmp/TestStore-output-1881025561287049326.txt_cleanupOnFailure_succeeded by DFSClient_492464520
[junit] at org.apache.hadoop.hdfs.server.namenode.NameNode.complete(NameNode.java:449)
[junit] at sun.reflect.GeneratedMethodAccessor18.invoke(Unknown Source)
[junit] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
[junit] at java.lang.reflect.Method.invoke(Method.java:597)
[junit] at org.apache.hadoop.ipc.RPC$Server.call(RPC.java:508)
[junit] at org.apache.hadoop.ipc.Server$Handler$1.run(Server.java:959)
[junit] at org.apache.hadoop.ipc.Server$Handler$1.run(Server.java:955)
[junit] at java.security.AccessController.doPrivileged(Native Method)
[junit] at javax.security.auth.Subject.doAs(Subject.java:396)
[junit] at org.apache.hadoop.ipc.Server$Handler.run(Server.java:953)
[junit]
[junit] org.apache.hadoop.ipc.RemoteException: java.io.IOException: Could not complete write to file /tmp/TestStore-output-1881025561287049326.txt_cleanupOnFailure_succeeded by DFSClient_492464520
[junit] at org.apache.hadoop.hdfs.server.namenode.NameNode.complete(NameNode.java:449)
[junit] at sun.reflect.GeneratedMethodAccessor18.invoke(Unknown Source)
[junit] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
[junit] at java.lang.reflect.Method.invoke(Method.java:597)
[junit] at org.apache.hadoop.ipc.RPC$Server.call(RPC.java:508)
[junit] at org.apache.hadoop.ipc.Server$Handler$1.run(Server.java:959)
[junit] at org.apache.hadoop.ipc.Server$Handler$1.run(Server.java:955)
[junit] at java.security.AccessController.doPrivileged(Native Method)
[junit] at javax.security.auth.Subject.doAs(Subject.java:396)
[junit] at org.apache.hadoop.ipc.Server$Handler.run(Server.java:953)
[junit]
[junit] at org.apache.hadoop.ipc.Client.call(Client.java:740)
[junit] at org.apache.hadoop.ipc.RPC$Invoker.invoke(RPC.java:220)
[junit] at $Proxy0.complete(Unknown Source)
[junit] at sun.reflect.GeneratedMethodAccessor18.invoke(Unknown Source)
[junit] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
[junit] at java.lang.reflect.Method.invoke(Method.java:597)
[junit] at org.apache.hadoop.io.retry.RetryInvocationHandler.invokeMethod(RetryInvocationHandler.java:82)
[junit] at org.apache.hadoop.io.retry.RetryInvocationHandler.invoke(RetryInvocationHandler.java:59)
[junit] at $Proxy0.complete(Unknown Source)
[junit] at org.apache.hadoop.hdfs.DFSClient$DFSOutputStream.closeInternal(DFSClient.java:3264)
[junit] at org.apache.hadoop.hdfs.DFSClient$DFSOutputStream.close(DFSClient.java:3188)
[junit] at org.apache.hadoop.hdfs.DFSClient$LeaseChecker.close(DFSClient.java:1043)
[junit] at org.apache.hadoop.hdfs.DFSClient.close(DFSClient.java:237)
[junit] at org.apache.hadoop.hdfs.DistributedFileSystem.close(DistributedFileSystem.java:269)
[junit] at org.apache.pig.test.MiniGenericCluster.shutdownMiniDfsClusters(MiniGenericCluster.java:83)
[junit] at org.apache.pig.test.MiniGenericCluster.shutdownMiniDfsAndMrClusters(MiniGenericCluster.java:77)
[junit] at org.apache.pig.test.MiniGenericCluster.shutDown(MiniGenericCluster.java:68)
[junit] at org.apache.pig.test.TestStore.oneTimeTearDown(TestStore.java:127)
[junit] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit] at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
[junit] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
[junit] at java.lang.reflect.Method.invoke(Method.java:597)
[junit] at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:44)
[junit] at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15)
[junit] at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:41)
[junit] at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:37)
[junit] at org.junit.runners.ParentRunner.run(ParentRunner.java:220)
[junit] at junit.framework.JUnit4TestAdapter.run(JUnit4TestAdapter.java:39)
[junit] at org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.run(JUnitTestRunner.java:420)
[junit] at org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.launch(JUnitTestRunner.java:911)
[junit] at org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.main(JUnitTestRunner.java:768)
[junit] Shutting down the Mini HDFS Cluster
[junit] Shutting down DataNode 3
[junit] 11/07/21 02:23:43 INFO ipc.Server: Stopping server on 40620
[junit] 11/07/21 02:23:43 INFO ipc.Server: Stopping IPC Server listener on 40620
[junit] 11/07/21 02:23:43 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 1
[junit] 11/07/21 02:23:43 INFO ipc.Server: Stopping IPC Server Responder
[junit] 11/07/21 02:23:43 INFO ipc.Server: IPC Server handler 1 on 40620: exiting
[junit] 11/07/21 02:23:43 INFO ipc.Server: IPC Server handler 2 on 40620: exiting
[junit] 11/07/21 02:23:43 WARN datanode.DataNode: DatanodeRegistration(127.0.0.1:44546, storageID=DS-315745557-127.0.1.1-44546-1311214653834, infoPort=56124, ipcPort=40620):DataXceiveServer: java.nio.channels.AsynchronousCloseException
[junit] at java.nio.channels.spi.AbstractInterruptibleChannel.end(AbstractInterruptibleChannel.java:185)
[junit] at sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:159)
[junit] at sun.nio.ch.ServerSocketAdaptor.accept(ServerSocketAdaptor.java:84)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataXceiverServer.run(DataXceiverServer.java:130)
[junit] at java.lang.Thread.run(Thread.java:662)
[junit]
[junit] 11/07/21 02:23:43 INFO ipc.Server: IPC Server handler 0 on 40620: exiting
[junit] 11/07/21 02:23:43 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:42768 to delete blk_-1729953649775561754_1127 blk_5366304699694942645_1123 blk_-1516394721850560862_1124 blk_-2581817329938677680_1122
[junit] 11/07/21 02:23:43 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:46974 to delete blk_-1729953649775561754_1127 blk_5366304699694942645_1123 blk_-3429364585100481170_1126 blk_-1516394721850560862_1124 blk_-3572013218284291470_1121 blk_-2581817329938677680_1122
[junit] 11/07/21 02:23:43 INFO datanode.DataBlockScanner: Exiting DataBlockScanner thread.
[junit] 11/07/21 02:23:44 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0
[junit] 11/07/21 02:23:44 INFO datanode.DataNode: DatanodeRegistration(127.0.0.1:44546, storageID=DS-315745557-127.0.1.1-44546-1311214653834, infoPort=56124, ipcPort=40620):Finishing DataNode in: FSDataset{dirpath='<https://builds.apache.org/job/Pig-trunk-commit/ws/trunk/build/test/data/dfs/data/data7/current,/grid/0/hudson/hudson-slave/workspace/Pig-trunk-commit/trunk/build/test/data/dfs/data/data8/current'}>
[junit] 11/07/21 02:23:44 INFO ipc.Server: Stopping server on 40620
[junit] 11/07/21 02:23:44 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0
[junit] Shutting down DataNode 2
[junit] 11/07/21 02:23:44 INFO ipc.Server: Stopping server on 40850
[junit] 11/07/21 02:23:44 INFO ipc.Server: Stopping IPC Server listener on 40850
[junit] 11/07/21 02:23:44 INFO ipc.Server: IPC Server handler 0 on 40850: exiting
[junit] 11/07/21 02:23:44 INFO ipc.Server: IPC Server handler 1 on 40850: exiting
[junit] 11/07/21 02:23:44 INFO ipc.Server: IPC Server handler 2 on 40850: exiting
[junit] 11/07/21 02:23:44 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 1
[junit] 11/07/21 02:23:44 INFO ipc.Server: Stopping IPC Server Responder
[junit] 11/07/21 02:23:44 WARN datanode.DataNode: DatanodeRegistration(127.0.0.1:60345, storageID=DS-361767735-127.0.1.1-60345-1311214653504, infoPort=45852, ipcPort=40850):DataXceiveServer: java.nio.channels.AsynchronousCloseException
[junit] at java.nio.channels.spi.AbstractInterruptibleChannel.end(AbstractInterruptibleChannel.java:185)
[junit] at sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:159)
[junit] at sun.nio.ch.ServerSocketAdaptor.accept(ServerSocketAdaptor.java:84)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataXceiverServer.run(DataXceiverServer.java:130)
[junit] at java.lang.Thread.run(Thread.java:662)
[junit]
[junit] 11/07/21 02:23:44 INFO datanode.DataBlockScanner: Exiting DataBlockScanner thread.
[junit] 11/07/21 02:23:45 WARN datanode.DataNode: Unexpected error trying to delete block blk_-2581817329938677680_1122. BlockInfo not found in volumeMap.
[junit] 11/07/21 02:23:45 INFO datanode.DataNode: Deleting block blk_-1729953649775561754_1127 file build/test/data/dfs/data/data1/current/blk_-1729953649775561754
[junit] 11/07/21 02:23:45 INFO datanode.DataNode: Deleting block blk_-1516394721850560862_1124 file build/test/data/dfs/data/data2/current/blk_-1516394721850560862
[junit] 11/07/21 02:23:45 INFO datanode.DataNode: Deleting block blk_5366304699694942645_1123 file build/test/data/dfs/data/data1/current/blk_5366304699694942645
[junit] 11/07/21 02:23:45 WARN datanode.DataNode: Error processing datanode Command
[junit] java.io.IOException: Error in deleting blocks.
[junit] at org.apache.hadoop.hdfs.server.datanode.FSDataset.invalidate(FSDataset.java:1361)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataNode.processCommand(DataNode.java:868)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataNode.processCommand(DataNode.java:830)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataNode.offerService(DataNode.java:710)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataNode.run(DataNode.java:1186)
[junit] at java.lang.Thread.run(Thread.java:662)
[junit] 11/07/21 02:23:45 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0
[junit] 11/07/21 02:23:45 INFO datanode.DataNode: DatanodeRegistration(127.0.0.1:60345, storageID=DS-361767735-127.0.1.1-60345-1311214653504, infoPort=45852, ipcPort=40850):Finishing DataNode in: FSDataset{dirpath='<https://builds.apache.org/job/Pig-trunk-commit/ws/trunk/build/test/data/dfs/data/data5/current,/grid/0/hudson/hudson-slave/workspace/Pig-trunk-commit/trunk/build/test/data/dfs/data/data6/current'}>
[junit] 11/07/21 02:23:45 INFO ipc.Server: Stopping server on 40850
[junit] 11/07/21 02:23:45 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0
[junit] Shutting down DataNode 1
[junit] 11/07/21 02:23:45 INFO ipc.Server: Stopping server on 37532
[junit] 11/07/21 02:23:45 INFO ipc.Server: IPC Server handler 0 on 37532: exiting
[junit] 11/07/21 02:23:45 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 1
[junit] 11/07/21 02:23:45 INFO ipc.Server: Stopping IPC Server listener on 37532
[junit] 11/07/21 02:23:45 WARN datanode.DataNode: DatanodeRegistration(127.0.0.1:46974, storageID=DS-1998064875-127.0.1.1-46974-1311214653186, infoPort=50848, ipcPort=37532):DataXceiveServer: java.nio.channels.AsynchronousCloseException
[junit] at java.nio.channels.spi.AbstractInterruptibleChannel.end(AbstractInterruptibleChannel.java:185)
[junit] at sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:159)
[junit] at sun.nio.ch.ServerSocketAdaptor.accept(ServerSocketAdaptor.java:84)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataXceiverServer.run(DataXceiverServer.java:130)
[junit] at java.lang.Thread.run(Thread.java:662)
[junit]
[junit] 11/07/21 02:23:45 INFO ipc.Server: IPC Server handler 1 on 37532: exiting
[junit] 11/07/21 02:23:45 INFO ipc.Server: Stopping IPC Server Responder
[junit] 11/07/21 02:23:45 INFO ipc.Server: IPC Server handler 2 on 37532: exiting
[junit] 11/07/21 02:23:45 INFO datanode.DataBlockScanner: Exiting DataBlockScanner thread.
[junit] 11/07/21 02:23:45 INFO datanode.DataNode: DatanodeRegistration(127.0.0.1:46974, storageID=DS-1998064875-127.0.1.1-46974-1311214653186, infoPort=50848, ipcPort=37532):Finishing DataNode in: FSDataset{dirpath='<https://builds.apache.org/job/Pig-trunk-commit/ws/trunk/build/test/data/dfs/data/data3/current,/grid/0/hudson/hudson-slave/workspace/Pig-trunk-commit/trunk/build/test/data/dfs/data/data4/current'}>
[junit] 11/07/21 02:23:45 INFO ipc.Server: Stopping server on 37532
[junit] 11/07/21 02:23:45 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0
[junit] Shutting down DataNode 0
[junit] 11/07/21 02:23:46 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0
[junit] 11/07/21 02:23:46 INFO ipc.Server: Stopping server on 49205
[junit] 11/07/21 02:23:46 INFO ipc.Server: IPC Server handler 0 on 49205: exiting
[junit] 11/07/21 02:23:46 INFO ipc.Server: Stopping IPC Server Responder
[junit] 11/07/21 02:23:46 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 1
[junit] 11/07/21 02:23:46 INFO ipc.Server: IPC Server handler 2 on 49205: exiting
[junit] 11/07/21 02:23:46 INFO ipc.Server: IPC Server handler 1 on 49205: exiting
[junit] 11/07/21 02:23:46 WARN datanode.DataNode: DatanodeRegistration(127.0.0.1:42768, storageID=DS-1912636143-127.0.1.1-42768-1311214652854, infoPort=55694, ipcPort=49205):DataXceiveServer: java.nio.channels.AsynchronousCloseException
[junit] at java.nio.channels.spi.AbstractInterruptibleChannel.end(AbstractInterruptibleChannel.java:185)
[junit] at sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:159)
[junit] at sun.nio.ch.ServerSocketAdaptor.accept(ServerSocketAdaptor.java:84)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataXceiverServer.run(DataXceiverServer.java:130)
[junit] at java.lang.Thread.run(Thread.java:662)
[junit]
[junit] 11/07/21 02:23:46 INFO ipc.Server: Stopping IPC Server listener on 49205
[junit] 11/07/21 02:23:46 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:44546 to delete blk_5366304699694942645_1123 blk_-3429364585100481170_1126 blk_-3572013218284291470_1121 blk_-2581817329938677680_1122
[junit] 11/07/21 02:23:46 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:60345 to delete blk_-1729953649775561754_1127 blk_-3429364585100481170_1126 blk_-1516394721850560862_1124 blk_-3572013218284291470_1121
[junit] 11/07/21 02:23:46 INFO datanode.DataBlockScanner: Exiting DataBlockScanner thread.
[junit] 11/07/21 02:23:47 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0
[junit] 11/07/21 02:23:47 INFO datanode.DataNode: DatanodeRegistration(127.0.0.1:42768, storageID=DS-1912636143-127.0.1.1-42768-1311214652854, infoPort=55694, ipcPort=49205):Finishing DataNode in: FSDataset{dirpath='<https://builds.apache.org/job/Pig-trunk-commit/ws/trunk/build/test/data/dfs/data/data1/current,/grid/0/hudson/hudson-slave/workspace/Pig-trunk-commit/trunk/build/test/data/dfs/data/data2/current'}>
[junit] 11/07/21 02:23:47 INFO ipc.Server: Stopping server on 49205
[junit] 11/07/21 02:23:47 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0
[junit] 11/07/21 02:23:47 INFO namenode.DecommissionManager: Interrupted Monitor
[junit] java.lang.InterruptedException: sleep interrupted
[junit] at java.lang.Thread.sleep(Native Method)
[junit] at org.apache.hadoop.hdfs.server.namenode.DecommissionManager$Monitor.run(DecommissionManager.java:65)
[junit] at java.lang.Thread.run(Thread.java:662)
[junit] 11/07/21 02:23:47 INFO namenode.FSNamesystem: Number of transactions: 694 Total time for transactions(ms): 19Number of transactions batched in Syncs: 115 Number of syncs: 484 SyncTimes(ms): 6647 1055
[junit] 11/07/21 02:23:47 WARN namenode.FSNamesystem: ReplicationMonitor thread received InterruptedException.java.lang.InterruptedException: sleep interrupted
[junit] 11/07/21 02:23:47 INFO ipc.Server: Stopping server on 37270
[junit] 11/07/21 02:23:47 INFO ipc.Server: Stopping IPC Server listener on 37270
[junit] 11/07/21 02:23:47 INFO ipc.Server: IPC Server handler 2 on 37270: exiting
[junit] 11/07/21 02:23:47 INFO ipc.Server: IPC Server handler 7 on 37270: exiting
[junit] 11/07/21 02:23:47 INFO ipc.Server: IPC Server handler 8 on 37270: exiting
[junit] 11/07/21 02:23:47 INFO ipc.Server: IPC Server handler 4 on 37270: exiting
[junit] 11/07/21 02:23:47 INFO ipc.Server: IPC Server handler 0 on 37270: exiting
[junit] 11/07/21 02:23:47 INFO ipc.Server: IPC Server handler 6 on 37270: exiting
[junit] 11/07/21 02:23:47 INFO ipc.Server: IPC Server handler 9 on 37270: exiting
[junit] 11/07/21 02:23:47 INFO ipc.Server: IPC Server handler 3 on 37270: exiting
[junit] 11/07/21 02:23:47 INFO ipc.Server: IPC Server handler 1 on 37270: exiting
[junit] 11/07/21 02:23:47 INFO ipc.Server: IPC Server handler 5 on 37270: exiting
[junit] Tests run: 17, Failures: 0, Errors: 0, Time elapsed: 370.154 sec
[junit] 11/07/21 02:23:47 INFO ipc.Server: Stopping IPC Server Responder
[junit] Running org.apache.pig.test.TestStringUDFs
[junit] 11/07/21 02:23:48 WARN builtin.SUBSTRING: java.lang.NullPointerException
[junit] 11/07/21 02:23:48 WARN builtin.SUBSTRING: java.lang.StringIndexOutOfBoundsException: String index out of range: -2
[junit] 11/07/21 02:23:48 WARN builtin.SUBSTRING: java.lang.StringIndexOutOfBoundsException: String index out of range: -1
[junit] 11/07/21 02:23:48 WARN builtin.SUBSTRING: java.lang.StringIndexOutOfBoundsException: String index out of range: -8
[junit] 11/07/21 02:23:48 WARN builtin.SUBSTRING: java.lang.StringIndexOutOfBoundsException: String index out of range: -2
[junit] 11/07/21 02:23:48 WARN builtin.INDEXOF: Failed to process input; error - null
[junit] 11/07/21 02:23:48 WARN builtin.LAST_INDEX_OF: Failed to process input; error - null
[junit] Tests run: 11, Failures: 0, Errors: 0, Time elapsed: 0.106 sec
[delete] Deleting directory /tmp/pig_junit_tmp102120422
BUILD FAILED
<https://builds.apache.org/job/Pig-trunk-commit/ws/trunk/build.xml>:664: The following error occurred while executing this line:
<https://builds.apache.org/job/Pig-trunk-commit/ws/trunk/build.xml>:719: Tests failed!
Total time: 21 minutes 27 seconds
[FINDBUGS] Skipping publisher since build result is FAILURE
Recording test results
Publishing Javadoc
Archiving artifacts
Recording fingerprints
Publishing Clover coverage report...
No Clover report will be published due to a Build Failure