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/20 22:29:07 UTC
Build failed in Jenkins: Pig-trunk-commit #865
See <https://builds.apache.org/job/Pig-trunk-commit/865/changes>
Changes:
[daijy] PIG-2175: Switch Pig wiki to use confluence
------------------------------------------
[...truncated 39819 lines...]
[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--6173355577045242468.txt_cleanupOnFailure_succeeded2 by DFSClient_-402372977
[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/20 20:28:54 WARN hdfs.StateChange: DIR* NameSystem.completeFile: failed to complete /tmp/TestStore-output--8318435738590698187.txt_cleanupOnFailure_succeeded because dir.getFileBlocks() is null and pendingFile is null
[junit] 11/07/20 20:28:54 INFO ipc.Server: IPC Server handler 8 on 54827, call complete(/tmp/TestStore-output--8318435738590698187.txt_cleanupOnFailure_succeeded, DFSClient_-402372977) from 127.0.0.1:48677: error: java.io.IOException: Could not complete write to file /tmp/TestStore-output--8318435738590698187.txt_cleanupOnFailure_succeeded by DFSClient_-402372977
[junit] java.io.IOException: Could not complete write to file /tmp/TestStore-output--8318435738590698187.txt_cleanupOnFailure_succeeded by DFSClient_-402372977
[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/20 20:28:54 ERROR hdfs.DFSClient: Exception closing file /tmp/TestStore-output--8318435738590698187.txt_cleanupOnFailure_succeeded : org.apache.hadoop.ipc.RemoteException: java.io.IOException: Could not complete write to file /tmp/TestStore-output--8318435738590698187.txt_cleanupOnFailure_succeeded by DFSClient_-402372977
[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--8318435738590698187.txt_cleanupOnFailure_succeeded by DFSClient_-402372977
[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/20 20:28:54 INFO ipc.Server: Stopping server on 34604
[junit] 11/07/20 20:28:54 INFO ipc.Server: IPC Server handler 1 on 34604: exiting
[junit] 11/07/20 20:28:54 INFO ipc.Server: IPC Server handler 0 on 34604: exiting
[junit] 11/07/20 20:28:54 INFO ipc.Server: Stopping IPC Server Responder
[junit] 11/07/20 20:28:54 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 1
[junit] 11/07/20 20:28:54 INFO ipc.Server: Stopping IPC Server listener on 34604
[junit] 11/07/20 20:28:54 WARN datanode.DataNode: DatanodeRegistration(127.0.0.1:43253, storageID=DS-1984411196-127.0.1.1-43253-1311193372417, infoPort=56585, ipcPort=34604):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/20 20:28:54 INFO ipc.Server: IPC Server handler 2 on 34604: exiting
[junit] 11/07/20 20:28:54 INFO datanode.DataNode: Deleting block blk_7997217196814725789_1123 file build/test/data/dfs/data/data4/current/blk_7997217196814725789
[junit] 11/07/20 20:28:54 INFO datanode.DataNode: Deleting block blk_8484644369041539496_1124 file build/test/data/dfs/data/data3/current/blk_8484644369041539496
[junit] 11/07/20 20:28:55 INFO datanode.DataNode: Deleting block blk_5883148209402507890_1122 file build/test/data/dfs/data/data6/current/blk_5883148209402507890
[junit] 11/07/20 20:28:55 INFO datanode.DataNode: Deleting block blk_7997217196814725789_1123 file build/test/data/dfs/data/data5/current/blk_7997217196814725789
[junit] 11/07/20 20:28:55 INFO datanode.DataNode: Deleting block blk_8484644369041539496_1124 file build/test/data/dfs/data/data6/current/blk_8484644369041539496
[junit] 11/07/20 20:28:55 INFO datanode.DataBlockScanner: Exiting DataBlockScanner thread.
[junit] 11/07/20 20:28:55 INFO datanode.DataNode: DatanodeRegistration(127.0.0.1:43253, storageID=DS-1984411196-127.0.1.1-43253-1311193372417, infoPort=56585, ipcPort=34604):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/20 20:28:55 INFO ipc.Server: Stopping server on 34604
[junit] 11/07/20 20:28:55 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0
[junit] Shutting down DataNode 2
[junit] 11/07/20 20:28:55 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0
[junit] 11/07/20 20:28:55 INFO ipc.Server: Stopping server on 57260
[junit] 11/07/20 20:28:55 INFO ipc.Server: IPC Server handler 0 on 57260: exiting
[junit] 11/07/20 20:28:55 INFO ipc.Server: IPC Server handler 2 on 57260: exiting
[junit] 11/07/20 20:28:55 INFO ipc.Server: Stopping IPC Server listener on 57260
[junit] 11/07/20 20:28:55 INFO ipc.Server: Stopping IPC Server Responder
[junit] 11/07/20 20:28:55 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 1
[junit] 11/07/20 20:28:55 INFO ipc.Server: IPC Server handler 1 on 57260: exiting
[junit] 11/07/20 20:28:55 WARN datanode.DataNode: DatanodeRegistration(127.0.0.1:37203, storageID=DS-1190561332-127.0.1.1-37203-1311193372123, infoPort=56661, ipcPort=57260):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/20 20:28:56 INFO datanode.DataBlockScanner: Exiting DataBlockScanner thread.
[junit] 11/07/20 20:28:56 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:37203 to delete blk_3079268705561693789_1121 blk_721618064306971440_1127 blk_6048780770867461787_1126
[junit] 11/07/20 20:28:56 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:54740 to delete blk_3079268705561693789_1121 blk_721618064306971440_1127 blk_6048780770867461787_1126
[junit] 11/07/20 20:28:56 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0
[junit] 11/07/20 20:28:56 INFO datanode.DataNode: DatanodeRegistration(127.0.0.1:37203, storageID=DS-1190561332-127.0.1.1-37203-1311193372123, infoPort=56661, ipcPort=57260):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/20 20:28:56 INFO ipc.Server: Stopping server on 57260
[junit] 11/07/20 20:28:56 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0
[junit] Shutting down DataNode 1
[junit] 11/07/20 20:28:56 INFO ipc.Server: Stopping server on 36459
[junit] 11/07/20 20:28:56 INFO ipc.Server: Stopping IPC Server Responder
[junit] 11/07/20 20:28:56 INFO ipc.Server: IPC Server handler 1 on 36459: exiting
[junit] 11/07/20 20:28:56 WARN datanode.DataNode: DatanodeRegistration(127.0.0.1:54740, storageID=DS-1916942665-127.0.1.1-54740-1311193371833, infoPort=35013, ipcPort=36459):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/20 20:28:56 INFO ipc.Server: IPC Server handler 0 on 36459: exiting
[junit] 11/07/20 20:28:56 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 1
[junit] 11/07/20 20:28:56 INFO datanode.DataBlockScanner: Exiting DataBlockScanner thread.
[junit] 11/07/20 20:28:56 INFO ipc.Server: IPC Server handler 2 on 36459: exiting
[junit] 11/07/20 20:28:56 INFO ipc.Server: Stopping IPC Server listener on 36459
[junit] 11/07/20 20:28:56 INFO datanode.DataNode: DatanodeRegistration(127.0.0.1:54740, storageID=DS-1916942665-127.0.1.1-54740-1311193371833, infoPort=35013, ipcPort=36459):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/20 20:28:56 INFO ipc.Server: Stopping server on 36459
[junit] 11/07/20 20:28:56 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0
[junit] Shutting down DataNode 0
[junit] 11/07/20 20:28:56 INFO ipc.Server: Stopping server on 40974
[junit] 11/07/20 20:28:56 INFO ipc.Server: IPC Server handler 0 on 40974: exiting
[junit] 11/07/20 20:28:56 INFO ipc.Server: Stopping IPC Server listener on 40974
[junit] 11/07/20 20:28:56 INFO ipc.Server: IPC Server handler 1 on 40974: exiting
[junit] 11/07/20 20:28:56 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 1
[junit] 11/07/20 20:28:56 INFO ipc.Server: Stopping IPC Server Responder
[junit] 11/07/20 20:28:56 WARN datanode.DataNode: DatanodeRegistration(127.0.0.1:34942, storageID=DS-338607779-127.0.1.1-34942-1311193371504, infoPort=34299, ipcPort=40974):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/20 20:28:56 INFO ipc.Server: IPC Server handler 2 on 40974: exiting
[junit] 11/07/20 20:28:57 INFO datanode.DataBlockScanner: Exiting DataBlockScanner thread.
[junit] 11/07/20 20:28:57 INFO datanode.DataNode: DatanodeRegistration(127.0.0.1:34942, storageID=DS-338607779-127.0.1.1-34942-1311193371504, infoPort=34299, ipcPort=40974):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/20 20:28:57 INFO ipc.Server: Stopping server on 40974
[junit] 11/07/20 20:28:57 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0
[junit] 11/07/20 20:28:57 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0
[junit] 11/07/20 20:28:57 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/20 20:28:57 INFO namenode.FSNamesystem: Number of transactions: 694 Total time for transactions(ms): 18Number of transactions batched in Syncs: 114 Number of syncs: 484 SyncTimes(ms): 3647 609
[junit] 11/07/20 20:28:57 WARN namenode.FSNamesystem: ReplicationMonitor thread received InterruptedException.java.lang.InterruptedException: sleep interrupted
[junit] 11/07/20 20:28:57 INFO ipc.Server: Stopping server on 54827
[junit] 11/07/20 20:28:57 INFO ipc.Server: IPC Server handler 1 on 54827: exiting
[junit] 11/07/20 20:28:57 INFO ipc.Server: Stopping IPC Server Responder
[junit] 11/07/20 20:28:57 INFO ipc.Server: Stopping IPC Server listener on 54827
[junit] 11/07/20 20:28:57 INFO ipc.Server: IPC Server handler 0 on 54827: exiting
[junit] 11/07/20 20:28:57 INFO ipc.Server: IPC Server handler 6 on 54827: exiting
[junit] 11/07/20 20:28:57 INFO ipc.Server: IPC Server handler 4 on 54827: exiting
[junit] 11/07/20 20:28:57 INFO ipc.Server: IPC Server handler 5 on 54827: exiting
[junit] 11/07/20 20:28:57 INFO ipc.Server: IPC Server handler 3 on 54827: exiting
[junit] 11/07/20 20:28:57 INFO ipc.Server: IPC Server handler 2 on 54827: exiting
[junit] 11/07/20 20:28:57 INFO ipc.Server: IPC Server handler 7 on 54827: exiting
[junit] 11/07/20 20:28:57 INFO ipc.Server: IPC Server handler 9 on 54827: exiting
[junit] 11/07/20 20:28:57 INFO ipc.Server: IPC Server handler 8 on 54827: exiting
[junit] Tests run: 17, Failures: 0, Errors: 0, Time elapsed: 363.051 sec
[junit] Running org.apache.pig.test.TestStringUDFs
[junit] 11/07/20 20:28:58 WARN builtin.SUBSTRING: java.lang.NullPointerException
[junit] 11/07/20 20:28:58 WARN builtin.SUBSTRING: java.lang.StringIndexOutOfBoundsException: String index out of range: -2
[junit] 11/07/20 20:28:58 WARN builtin.SUBSTRING: java.lang.StringIndexOutOfBoundsException: String index out of range: -1
[junit] 11/07/20 20:28:58 WARN builtin.SUBSTRING: java.lang.StringIndexOutOfBoundsException: String index out of range: -8
[junit] 11/07/20 20:28:58 WARN builtin.SUBSTRING: java.lang.StringIndexOutOfBoundsException: String index out of range: -2
[junit] 11/07/20 20:28:58 WARN builtin.INDEXOF: Failed to process input; error - null
[junit] 11/07/20 20:28:58 WARN builtin.LAST_INDEX_OF: Failed to process input; error - null
[junit] Tests run: 11, Failures: 0, Errors: 0, Time elapsed: 0.116 sec
[delete] Deleting directory /tmp/pig_junit_tmp1176941027
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 10 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
Build failed in Jenkins: Pig-trunk-commit #867
Posted by Apache Jenkins Server <je...@builds.apache.org>.
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
Build failed in Jenkins: Pig-trunk-commit #866
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Pig-trunk-commit/866/changes>
Changes:
[thejas] PIG-2146: POStore.getSchema() returns null because of which PigOutputCommitter
is not storing schema while cleanup
------------------------------------------
[...truncated 39831 lines...]
[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--3939521172535858951.txt_cleanupOnFailure_succeeded2 by DFSClient_-634647566
[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/20 23:28:36 WARN hdfs.StateChange: DIR* NameSystem.completeFile: failed to complete /tmp/TestStore-output-5551418102219430049.txt_cleanupOnFailure_succeeded1 because dir.getFileBlocks() is null and pendingFile is null
[junit] 11/07/20 23:28:36 INFO ipc.Server: IPC Server handler 4 on 40868, call complete(/tmp/TestStore-output-5551418102219430049.txt_cleanupOnFailure_succeeded1, DFSClient_-634647566) from 127.0.0.1:54942: error: java.io.IOException: Could not complete write to file /tmp/TestStore-output-5551418102219430049.txt_cleanupOnFailure_succeeded1 by DFSClient_-634647566
[junit] java.io.IOException: Could not complete write to file /tmp/TestStore-output-5551418102219430049.txt_cleanupOnFailure_succeeded1 by DFSClient_-634647566
[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/20 23:28:36 ERROR hdfs.DFSClient: Exception closing file /tmp/TestStore-output-5551418102219430049.txt_cleanupOnFailure_succeeded1 : org.apache.hadoop.ipc.RemoteException: java.io.IOException: Could not complete write to file /tmp/TestStore-output-5551418102219430049.txt_cleanupOnFailure_succeeded1 by DFSClient_-634647566
[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-5551418102219430049.txt_cleanupOnFailure_succeeded1 by DFSClient_-634647566
[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] Shutting down the Mini HDFS Cluster
[junit] Shutting down DataNode 3
[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/20 23:28:36 INFO ipc.Server: Stopping server on 46781
[junit] 11/07/20 23:28:36 INFO ipc.Server: IPC Server handler 2 on 46781: exiting
[junit] 11/07/20 23:28:36 INFO ipc.Server: IPC Server handler 1 on 46781: exiting
[junit] 11/07/20 23:28:36 INFO ipc.Server: Stopping IPC Server Responder
[junit] 11/07/20 23:28:36 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 1
[junit] 11/07/20 23:28:36 INFO ipc.Server: IPC Server handler 0 on 46781: exiting
[junit] 11/07/20 23:28:36 INFO ipc.Server: Stopping IPC Server listener on 46781
[junit] 11/07/20 23:28:36 WARN datanode.DataNode: DatanodeRegistration(127.0.0.1:36805, storageID=DS-195269808-127.0.1.1-36805-1311204154836, infoPort=45996, ipcPort=46781):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/20 23:28:36 INFO datanode.DataBlockScanner: Exiting DataBlockScanner thread.
[junit] 11/07/20 23:28:37 INFO datanode.DataNode: Deleting block blk_-3204924733222348700_1122 file build/test/data/dfs/data/data2/current/blk_-3204924733222348700
[junit] 11/07/20 23:28:37 INFO datanode.DataNode: Deleting block blk_6333618558934951744_1124 file build/test/data/dfs/data/data1/current/blk_6333618558934951744
[junit] 11/07/20 23:28:37 INFO datanode.DataNode: Deleting block blk_-3204924733222348700_1122 file build/test/data/dfs/data/data3/current/blk_-3204924733222348700
[junit] 11/07/20 23:28:37 INFO datanode.DataNode: Deleting block blk_6333618558934951744_1124 file build/test/data/dfs/data/data3/current/blk_6333618558934951744
[junit] 11/07/20 23:28:37 INFO datanode.DataNode: Deleting block blk_8636658259747225878_1123 file build/test/data/dfs/data/data4/current/blk_8636658259747225878
[junit] 11/07/20 23:28:37 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0
[junit] 11/07/20 23:28:37 INFO datanode.DataNode: DatanodeRegistration(127.0.0.1:36805, storageID=DS-195269808-127.0.1.1-36805-1311204154836, infoPort=45996, ipcPort=46781):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/20 23:28:37 INFO ipc.Server: Stopping server on 46781
[junit] 11/07/20 23:28:37 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0
[junit] Shutting down DataNode 2
[junit] 11/07/20 23:28:37 INFO ipc.Server: Stopping server on 43641
[junit] 11/07/20 23:28:37 INFO ipc.Server: IPC Server handler 2 on 43641: exiting
[junit] 11/07/20 23:28:37 INFO ipc.Server: IPC Server handler 0 on 43641: exiting
[junit] 11/07/20 23:28:37 INFO ipc.Server: Stopping IPC Server Responder
[junit] 11/07/20 23:28:37 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 1
[junit] 11/07/20 23:28:37 INFO ipc.Server: Stopping IPC Server listener on 43641
[junit] 11/07/20 23:28:37 WARN datanode.DataNode: DatanodeRegistration(127.0.0.1:36410, storageID=DS-750487208-127.0.1.1-36410-1311204154540, infoPort=60500, ipcPort=43641):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/20 23:28:37 INFO ipc.Server: IPC Server handler 1 on 43641: exiting
[junit] 11/07/20 23:28:37 INFO datanode.DataBlockScanner: Exiting DataBlockScanner thread.
[junit] 11/07/20 23:28:37 INFO datanode.DataNode: DatanodeRegistration(127.0.0.1:36410, storageID=DS-750487208-127.0.1.1-36410-1311204154540, infoPort=60500, ipcPort=43641):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/20 23:28:37 INFO ipc.Server: Stopping server on 43641
[junit] 11/07/20 23:28:37 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0
[junit] Shutting down DataNode 1
[junit] 11/07/20 23:28:38 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0
[junit] 11/07/20 23:28:38 INFO ipc.Server: Stopping server on 47299
[junit] 11/07/20 23:28:38 INFO ipc.Server: IPC Server handler 0 on 47299: exiting
[junit] 11/07/20 23:28:38 INFO ipc.Server: IPC Server handler 2 on 47299: exiting
[junit] 11/07/20 23:28:38 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 1
[junit] 11/07/20 23:28:38 INFO ipc.Server: Stopping IPC Server listener on 47299
[junit] 11/07/20 23:28:38 INFO ipc.Server: Stopping IPC Server Responder
[junit] 11/07/20 23:28:38 WARN datanode.DataNode: DatanodeRegistration(127.0.0.1:50569, storageID=DS-1228816136-127.0.1.1-50569-1311204154249, infoPort=59581, ipcPort=47299):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/20 23:28:38 INFO ipc.Server: IPC Server handler 1 on 47299: exiting
[junit] 11/07/20 23:28:38 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:50569 to delete blk_-6361483123941874151_1126 blk_-9092788064384877966_1127
[junit] 11/07/20 23:28:38 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:38335 to delete blk_-6361483123941874151_1126 blk_-9092788064384877966_1127 blk_2795447678487328647_1121
[junit] 11/07/20 23:28:39 INFO datanode.DataBlockScanner: Exiting DataBlockScanner thread.
[junit] 11/07/20 23:28:39 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0
[junit] 11/07/20 23:28:39 INFO datanode.DataNode: DatanodeRegistration(127.0.0.1:50569, storageID=DS-1228816136-127.0.1.1-50569-1311204154249, infoPort=59581, ipcPort=47299):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/20 23:28:39 INFO ipc.Server: Stopping server on 47299
[junit] 11/07/20 23:28:39 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0
[junit] Shutting down DataNode 0
[junit] 11/07/20 23:28:39 INFO ipc.Server: Stopping server on 41125
[junit] 11/07/20 23:28:39 INFO ipc.Server: IPC Server handler 0 on 41125: exiting
[junit] 11/07/20 23:28:39 INFO ipc.Server: IPC Server handler 1 on 41125: exiting
[junit] 11/07/20 23:28:39 INFO ipc.Server: IPC Server handler 2 on 41125: exiting
[junit] 11/07/20 23:28:39 INFO ipc.Server: Stopping IPC Server listener on 41125
[junit] 11/07/20 23:28:39 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 1
[junit] 11/07/20 23:28:39 INFO ipc.Server: Stopping IPC Server Responder
[junit] 11/07/20 23:28:39 WARN datanode.DataNode: DatanodeRegistration(127.0.0.1:38335, storageID=DS-1763994815-127.0.1.1-38335-1311204153938, infoPort=39626, ipcPort=41125):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/20 23:28:39 INFO datanode.DataBlockScanner: Exiting DataBlockScanner thread.
[junit] 11/07/20 23:28:40 INFO datanode.DataNode: DatanodeRegistration(127.0.0.1:38335, storageID=DS-1763994815-127.0.1.1-38335-1311204153938, infoPort=39626, ipcPort=41125):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/20 23:28:40 INFO ipc.Server: Stopping server on 41125
[junit] 11/07/20 23:28:40 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0
[junit] 11/07/20 23:28:40 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0
[junit] 11/07/20 23:28:40 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/20 23:28:40 INFO namenode.FSNamesystem: Number of transactions: 694 Total time for transactions(ms): 15Number of transactions batched in Syncs: 114 Number of syncs: 484 SyncTimes(ms): 4448 476
[junit] 11/07/20 23:28:40 WARN namenode.FSNamesystem: ReplicationMonitor thread received InterruptedException.java.lang.InterruptedException: sleep interrupted
[junit] 11/07/20 23:28:40 INFO ipc.Server: Stopping server on 40868
[junit] 11/07/20 23:28:40 INFO ipc.Server: Stopping IPC Server listener on 40868
[junit] 11/07/20 23:28:40 INFO ipc.Server: IPC Server handler 7 on 40868: exiting
[junit] 11/07/20 23:28:40 INFO ipc.Server: IPC Server handler 3 on 40868: exiting
[junit] 11/07/20 23:28:40 INFO ipc.Server: IPC Server handler 0 on 40868: exiting
[junit] 11/07/20 23:28:40 INFO ipc.Server: IPC Server handler 1 on 40868: exiting
[junit] 11/07/20 23:28:40 INFO ipc.Server: IPC Server handler 8 on 40868: exiting
[junit] 11/07/20 23:28:40 INFO ipc.Server: IPC Server handler 4 on 40868: exiting
[junit] 11/07/20 23:28:40 INFO ipc.Server: IPC Server handler 6 on 40868: exiting
[junit] 11/07/20 23:28:40 INFO ipc.Server: IPC Server handler 9 on 40868: exiting
[junit] 11/07/20 23:28:40 INFO ipc.Server: IPC Server handler 2 on 40868: exiting
[junit] 11/07/20 23:28:40 INFO ipc.Server: IPC Server handler 5 on 40868: exiting
[junit] 11/07/20 23:28:40 INFO ipc.Server: Stopping IPC Server Responder
[junit] Tests run: 17, Failures: 0, Errors: 0, Time elapsed: 363.663 sec
[junit] Running org.apache.pig.test.TestStringUDFs
[junit] 11/07/20 23:28:41 WARN builtin.SUBSTRING: java.lang.NullPointerException
[junit] 11/07/20 23:28:41 WARN builtin.SUBSTRING: java.lang.StringIndexOutOfBoundsException: String index out of range: -2
[junit] 11/07/20 23:28:41 WARN builtin.SUBSTRING: java.lang.StringIndexOutOfBoundsException: String index out of range: -1
[junit] 11/07/20 23:28:41 WARN builtin.SUBSTRING: java.lang.StringIndexOutOfBoundsException: String index out of range: -8
[junit] 11/07/20 23:28:41 WARN builtin.SUBSTRING: java.lang.StringIndexOutOfBoundsException: String index out of range: -2
[junit] 11/07/20 23:28:41 WARN builtin.INDEXOF: Failed to process input; error - null
[junit] 11/07/20 23:28:41 WARN builtin.LAST_INDEX_OF: Failed to process input; error - null
[junit] Tests run: 11, Failures: 0, Errors: 0, Time elapsed: 0.116 sec
[delete] Deleting directory /tmp/pig_junit_tmp665886679
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 10 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