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 2012/05/11 12:39:25 UTC
Build failed in Jenkins: Pig-trunk #1243
See <https://builds.apache.org/job/Pig-trunk/1243/changes>
Changes:
[daijy] PIG-2666: LoadFunc.setLocation() is not called when pig script only has Order By
[daijy] PIG-2666: LoadFunc.setLocation() is not called when pig script only has Order By
[daijy] PIG-2583: Add Grunt command to list the statements in cache
------------------------------------------
[...truncated 36459 lines...]
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:415)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:403)
[junit] at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:506)
[junit] at org.apache.hadoop.metrics2.util.MBeans.unregister(MBeans.java:71)
[junit] at org.apache.hadoop.hdfs.server.datanode.FSDataset.shutdown(FSDataset.java:1934)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataNode.shutdown(DataNode.java:788)
[junit] Shutting down DataNode 2
[junit] at org.apache.hadoop.hdfs.MiniDFSCluster.shutdownDataNodes(MiniDFSCluster.java:566)
[junit] at org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:550)
[junit] at org.apache.pig.test.MiniGenericCluster.shutdownMiniDfsClusters(MiniGenericCluster.java:87)
[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:129)
[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] 12/05/11 10:37:25 WARN datanode.FSDatasetAsyncDiskService: AsyncDiskService has already shut down.
[junit] 12/05/11 10:37:25 INFO mortbay.log: Stopped SelectChannelConnector@localhost:0
[junit] 12/05/11 10:37:25 INFO ipc.Server: Stopping server on 40670
[junit] 12/05/11 10:37:25 INFO ipc.Server: IPC Server handler 0 on 40670: exiting
[junit] 12/05/11 10:37:25 INFO ipc.Server: IPC Server handler 1 on 40670: exiting
[junit] 12/05/11 10:37:25 INFO ipc.Server: IPC Server handler 2 on 40670: exiting
[junit] 12/05/11 10:37:25 INFO ipc.Server: Stopping IPC Server listener on 40670
[junit] 12/05/11 10:37:25 INFO metrics.RpcInstrumentation: shut down
[junit] 12/05/11 10:37:25 INFO ipc.Server: Stopping IPC Server Responder
[junit] 12/05/11 10:37:25 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 1
[junit] 12/05/11 10:37:25 WARN datanode.DataNode: DatanodeRegistration(127.0.0.1:44296, storageID=DS-687041092-67.195.138.20-44296-1336732122791, infoPort=52925, ipcPort=40670):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:131)
[junit] at java.lang.Thread.run(Thread.java:662)
[junit]
[junit] 12/05/11 10:37:25 INFO datanode.DataNode: Exiting DataXceiveServer
[junit] 12/05/11 10:37:25 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:58518 to delete blk_-7224371296842915333_1073 blk_-4558438029969917005_1078
[junit] 12/05/11 10:37:25 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:47558 to delete blk_-7224371296842915333_1073
[junit] 12/05/11 10:37:25 INFO datanode.DataBlockScanner: Exiting DataBlockScanner thread.
[junit] 12/05/11 10:37:26 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0
[junit] 12/05/11 10:37:26 INFO datanode.DataNode: DatanodeRegistration(127.0.0.1:44296, storageID=DS-687041092-67.195.138.20-44296-1336732122791, infoPort=52925, ipcPort=40670):Finishing DataNode in: FSDataset{dirpath='<https://builds.apache.org/job/Pig-trunk/ws/trunk/build/test/data/dfs/data/data5/current,/home/jenkins/jenkins-slave/workspace/Pig-trunk/trunk/build/test/data/dfs/data/data6/current'}>
[junit] 12/05/11 10:37:26 INFO ipc.Server: Stopping server on 40670
[junit] 12/05/11 10:37:26 INFO metrics.RpcInstrumentation: shut down
[junit] 12/05/11 10:37:26 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0
[junit] 12/05/11 10:37:26 INFO datanode.FSDatasetAsyncDiskService: Shutting down all async disk service threads...
[junit] 12/05/11 10:37:26 INFO datanode.FSDatasetAsyncDiskService: All async disk service threads have been shut down.
[junit] 12/05/11 10:37:26 WARN util.MBeans: Hadoop:service=DataNode,name=FSDatasetState-UndefinedStorageId2030939983
[junit] javax.management.InstanceNotFoundException: Hadoop:service=DataNode,name=FSDatasetState-UndefinedStorageId2030939983
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getMBean(DefaultMBeanServerInterceptor.java:1094)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:415)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:403)
[junit] at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:506)
[junit] Shutting down DataNode 1
[junit] at org.apache.hadoop.metrics2.util.MBeans.unregister(MBeans.java:71)
[junit] at org.apache.hadoop.hdfs.server.datanode.FSDataset.shutdown(FSDataset.java:1934)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataNode.shutdown(DataNode.java:788)
[junit] at org.apache.hadoop.hdfs.MiniDFSCluster.shutdownDataNodes(MiniDFSCluster.java:566)
[junit] at org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:550)
[junit] at org.apache.pig.test.MiniGenericCluster.shutdownMiniDfsClusters(MiniGenericCluster.java:87)
[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:129)
[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] 12/05/11 10:37:26 WARN datanode.FSDatasetAsyncDiskService: AsyncDiskService has already shut down.
[junit] 12/05/11 10:37:26 INFO mortbay.log: Stopped SelectChannelConnector@localhost:0
[junit] 12/05/11 10:37:26 INFO ipc.Server: Stopping server on 60766
[junit] 12/05/11 10:37:26 INFO ipc.Server: IPC Server handler 0 on 60766: exiting
[junit] 12/05/11 10:37:26 INFO ipc.Server: IPC Server handler 1 on 60766: exiting
[junit] 12/05/11 10:37:26 INFO ipc.Server: IPC Server handler 2 on 60766: exiting
[junit] 12/05/11 10:37:26 INFO metrics.RpcInstrumentation: shut down
[junit] 12/05/11 10:37:26 INFO ipc.Server: Stopping IPC Server Responder
[junit] 12/05/11 10:37:26 INFO ipc.Server: Stopping IPC Server listener on 60766
[junit] 12/05/11 10:37:26 WARN datanode.DataNode: DatanodeRegistration(127.0.0.1:47558, storageID=DS-2036196994-67.195.138.20-47558-1336732122415, infoPort=37585, ipcPort=60766):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:131)
[junit] at java.lang.Thread.run(Thread.java:662)
[junit]
[junit] 12/05/11 10:37:26 INFO datanode.DataNode: Exiting DataXceiveServer
[junit] 12/05/11 10:37:26 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0
[junit] 12/05/11 10:37:26 INFO datanode.DataBlockScanner: Exiting DataBlockScanner thread.
[junit] 12/05/11 10:37:26 INFO datanode.DataNode: DatanodeRegistration(127.0.0.1:47558, storageID=DS-2036196994-67.195.138.20-47558-1336732122415, infoPort=37585, ipcPort=60766):Finishing DataNode in: FSDataset{dirpath='<https://builds.apache.org/job/Pig-trunk/ws/trunk/build/test/data/dfs/data/data3/current,/home/jenkins/jenkins-slave/workspace/Pig-trunk/trunk/build/test/data/dfs/data/data4/current'}>
[junit] 12/05/11 10:37:26 INFO ipc.Server: Stopping server on 60766
[junit] 12/05/11 10:37:26 INFO metrics.RpcInstrumentation: shut down
[junit] 12/05/11 10:37:26 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0
[junit] 12/05/11 10:37:26 INFO datanode.FSDatasetAsyncDiskService: Shutting down all async disk service threads...
[junit] 12/05/11 10:37:26 INFO datanode.FSDatasetAsyncDiskService: All async disk service threads have been shut down.
[junit] 12/05/11 10:37:26 WARN util.MBeans: Hadoop:service=DataNode,name=FSDatasetState-UndefinedStorageId370593995
[junit] javax.management.InstanceNotFoundException: Hadoop:service=DataNode,name=FSDatasetState-UndefinedStorageId370593995
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getMBean(DefaultMBeanServerInterceptor.java:1094)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:415)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:403)
[junit] at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:506)
[junit] at org.apache.hadoop.metrics2.util.MBeans.unregister(MBeans.java:71)
[junit] at org.apache.hadoop.hdfs.server.datanode.FSDataset.shutdown(FSDataset.java:1934)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataNode.shutdown(DataNode.java:788)
[junit] at org.apache.hadoop.hdfs.MiniDFSCluster.shutdownDataNodes(MiniDFSCluster.java:566)
[junit] at org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:550)
[junit] at org.apache.pig.test.MiniGenericCluster.shutdownMiniDfsClusters(MiniGenericCluster.java:87)
[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:129)
[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] 12/05/11 10:37:26 WARN datanode.FSDatasetAsyncDiskService: AsyncDiskService has already shut down.
[junit] Shutting down DataNode 0
[junit] 12/05/11 10:37:26 INFO mortbay.log: Stopped SelectChannelConnector@localhost:0
[junit] 12/05/11 10:37:26 INFO ipc.Server: Stopping server on 38182
[junit] 12/05/11 10:37:26 INFO ipc.Server: IPC Server handler 0 on 38182: exiting
[junit] 12/05/11 10:37:26 INFO ipc.Server: Stopping IPC Server listener on 38182
[junit] 12/05/11 10:37:26 INFO metrics.RpcInstrumentation: shut down
[junit] 12/05/11 10:37:26 INFO ipc.Server: Stopping IPC Server Responder
[junit] 12/05/11 10:37:26 INFO ipc.Server: IPC Server handler 1 on 38182: exiting
[junit] 12/05/11 10:37:26 INFO ipc.Server: IPC Server handler 2 on 38182: exiting
[junit] 12/05/11 10:37:26 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 1
[junit] 12/05/11 10:37:26 WARN datanode.DataNode: DatanodeRegistration(127.0.0.1:58518, storageID=DS-1332231507-67.195.138.20-58518-1336732121986, infoPort=49913, ipcPort=38182):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:131)
[junit] at java.lang.Thread.run(Thread.java:662)
[junit]
[junit] 12/05/11 10:37:26 INFO datanode.DataNode: Exiting DataXceiveServer
[junit] 12/05/11 10:37:27 INFO datanode.DataBlockScanner: Exiting DataBlockScanner thread.
[junit] 12/05/11 10:37:27 INFO datanode.DataNode: DatanodeRegistration(127.0.0.1:58518, storageID=DS-1332231507-67.195.138.20-58518-1336732121986, infoPort=49913, ipcPort=38182):Finishing DataNode in: FSDataset{dirpath='<https://builds.apache.org/job/Pig-trunk/ws/trunk/build/test/data/dfs/data/data1/current,/home/jenkins/jenkins-slave/workspace/Pig-trunk/trunk/build/test/data/dfs/data/data2/current'}>
[junit] 12/05/11 10:37:27 WARN util.MBeans: Hadoop:service=DataNode,name=DataNodeInfo
[junit] javax.management.InstanceNotFoundException: Hadoop:service=DataNode,name=DataNodeInfo
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getMBean(DefaultMBeanServerInterceptor.java:1094)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:415)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:403)
[junit] at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:506)
[junit] at org.apache.hadoop.metrics2.util.MBeans.unregister(MBeans.java:71)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataNode.unRegisterMXBean(DataNode.java:513)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataNode.shutdown(DataNode.java:726)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataNode.run(DataNode.java:1442)
[junit] at java.lang.Thread.run(Thread.java:662)
[junit] 12/05/11 10:37:27 INFO ipc.Server: Stopping server on 38182
[junit] 12/05/11 10:37:27 INFO metrics.RpcInstrumentation: shut down
[junit] 12/05/11 10:37:27 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0
[junit] 12/05/11 10:37:27 INFO datanode.FSDatasetAsyncDiskService: Shutting down all async disk service threads...
[junit] 12/05/11 10:37:27 INFO datanode.FSDatasetAsyncDiskService: All async disk service threads have been shut down.
[junit] 12/05/11 10:37:27 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0
[junit] 12/05/11 10:37:27 WARN util.MBeans: Hadoop:service=DataNode,name=FSDatasetState-UndefinedStorageId-437283612
[junit] javax.management.InstanceNotFoundException: Hadoop:service=DataNode,name=FSDatasetState-UndefinedStorageId-437283612
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getMBean(DefaultMBeanServerInterceptor.java:1094)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:415)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:403)
[junit] at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:506)
[junit] at org.apache.hadoop.metrics2.util.MBeans.unregister(MBeans.java:71)
[junit] at org.apache.hadoop.hdfs.server.datanode.FSDataset.shutdown(FSDataset.java:1934)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataNode.shutdown(DataNode.java:788)
[junit] at org.apache.hadoop.hdfs.MiniDFSCluster.shutdownDataNodes(MiniDFSCluster.java:566)
[junit] at org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:550)
[junit] at org.apache.pig.test.MiniGenericCluster.shutdownMiniDfsClusters(MiniGenericCluster.java:87)
[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:129)
[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] 12/05/11 10:37:27 WARN datanode.FSDatasetAsyncDiskService: AsyncDiskService has already shut down.
[junit] 12/05/11 10:37:27 INFO mortbay.log: Stopped SelectChannelConnector@localhost:0
[junit] 12/05/11 10:37:28 WARN namenode.FSNamesystem: ReplicationMonitor thread received InterruptedException.java.lang.InterruptedException: sleep interrupted
[junit] 12/05/11 10:37:28 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] 12/05/11 10:37:28 INFO namenode.FSNamesystem: Number of transactions: 382 Total time for transactions(ms): 10Number of transactions batched in Syncs: 151 Number of syncs: 287 SyncTimes(ms): 2909 206
[junit] 12/05/11 10:37:28 INFO ipc.Server: Stopping server on 59246
[junit] 12/05/11 10:37:28 INFO ipc.Server: IPC Server handler 0 on 59246: exiting
[junit] 12/05/11 10:37:28 INFO ipc.Server: IPC Server handler 1 on 59246: exiting
[junit] 12/05/11 10:37:28 INFO ipc.Server: IPC Server handler 2 on 59246: exiting
[junit] 12/05/11 10:37:28 INFO ipc.Server: IPC Server handler 3 on 59246: exiting
[junit] 12/05/11 10:37:28 INFO ipc.Server: IPC Server handler 5 on 59246: exiting
[junit] 12/05/11 10:37:28 INFO ipc.Server: IPC Server handler 4 on 59246: exiting
[junit] 12/05/11 10:37:28 INFO ipc.Server: IPC Server handler 9 on 59246: exiting
[junit] 12/05/11 10:37:28 INFO ipc.Server: IPC Server handler 8 on 59246: exiting
[junit] 12/05/11 10:37:28 INFO metrics.RpcInstrumentation: shut down
[junit] 12/05/11 10:37:28 INFO ipc.Server: Stopping IPC Server listener on 59246
[junit] 12/05/11 10:37:28 INFO ipc.Server: IPC Server handler 7 on 59246: exiting
[junit] 12/05/11 10:37:28 INFO ipc.Server: IPC Server handler 6 on 59246: exiting
[junit] 12/05/11 10:37:28 INFO ipc.Server: Stopping IPC Server Responder
[junit] Tests run: 17, Failures: 3, Errors: 3, Time elapsed: 519.566 sec
[junit] Test org.apache.pig.test.TestStore FAILED
[junit] Running org.apache.pig.test.TestStringUDFs
[junit] 12/05/11 10:37:29 WARN builtin.SUBSTRING: No logger object provided to UDF: org.apache.pig.builtin.SUBSTRING. java.lang.NullPointerException
[junit] 12/05/11 10:37:29 WARN builtin.SUBSTRING: No logger object provided to UDF: org.apache.pig.builtin.SUBSTRING. java.lang.StringIndexOutOfBoundsException: String index out of range: -2
[junit] 12/05/11 10:37:29 WARN builtin.SUBSTRING: No logger object provided to UDF: org.apache.pig.builtin.SUBSTRING. java.lang.StringIndexOutOfBoundsException: String index out of range: -1
[junit] 12/05/11 10:37:29 WARN builtin.SUBSTRING: No logger object provided to UDF: org.apache.pig.builtin.SUBSTRING. java.lang.StringIndexOutOfBoundsException: String index out of range: -8
[junit] 12/05/11 10:37:29 WARN builtin.SUBSTRING: No logger object provided to UDF: org.apache.pig.builtin.SUBSTRING. java.lang.StringIndexOutOfBoundsException: String index out of range: -2
[junit] 12/05/11 10:37:29 WARN builtin.INDEXOF: No logger object provided to UDF: org.apache.pig.builtin.INDEXOF. Failed to process input; error - null
[junit] 12/05/11 10:37:29 WARN builtin.LAST_INDEX_OF: No logger object provided to UDF: org.apache.pig.builtin.LAST_INDEX_OF. Failed to process input; error - null
[junit] Tests run: 11, Failures: 0, Errors: 0, Time elapsed: 0.101 sec
[delete] Deleting directory /tmp/pig_junit_tmp63065432
BUILD FAILED
<https://builds.apache.org/job/Pig-trunk/ws/trunk/build.xml>:787: The following error occurred while executing this line:
<https://builds.apache.org/job/Pig-trunk/ws/trunk/build.xml>:842: Tests failed!
Total time: 24 minutes 47 seconds
Build step 'Execute shell' marked build as failure
[FINDBUGS] Skipping publisher since build result is FAILURE
Recording test results
Publishing Javadoc
Archiving artifacts
Recording fingerprints
Jenkins build is back to normal : Pig-trunk #1250
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Pig-trunk/1250/changes>
Build failed in Jenkins: Pig-trunk #1249
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Pig-trunk/1249/changes>
Changes:
[daijy] PIG-2711: e2e harness: cache benchmark results between test runs
------------------------------------------
[...truncated 37827 lines...]
[junit] at org.apache.pig.test.MiniGenericCluster.shutdownMiniDfsClusters(MiniGenericCluster.java:87)
[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:129)
[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] 12/05/21 10:34:57 WARN datanode.FSDatasetAsyncDiskService: AsyncDiskService has already shut down.
[junit] 12/05/21 10:34:57 INFO mortbay.log: Stopped SelectChannelConnector@localhost:0
[junit] Shutting down DataNode 2
[junit] 12/05/21 10:34:58 INFO ipc.Server: Stopping server on 36943
[junit] 12/05/21 10:34:58 INFO ipc.Server: IPC Server handler 2 on 36943: exiting
[junit] 12/05/21 10:34:58 INFO metrics.RpcInstrumentation: shut down
[junit] 12/05/21 10:34:58 INFO ipc.Server: IPC Server handler 1 on 36943: exiting
[junit] 12/05/21 10:34:58 WARN datanode.DataNode: DatanodeRegistration(127.0.0.1:46582, storageID=DS-117222309-67.195.138.20-46582-1337595997762, infoPort=52991, ipcPort=36943):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:131)
[junit] at java.lang.Thread.run(Thread.java:662)
[junit]
[junit] 12/05/21 10:34:58 INFO datanode.DataNode: Exiting DataXceiveServer
[junit] 12/05/21 10:34:58 INFO ipc.Server: IPC Server handler 0 on 36943: exiting
[junit] 12/05/21 10:34:58 INFO ipc.Server: Stopping IPC Server Responder
[junit] 12/05/21 10:34:58 INFO ipc.Server: Stopping IPC Server listener on 36943
[junit] 12/05/21 10:34:58 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 1
[junit] 12/05/21 10:34:58 INFO datanode.DataBlockScanner: Exiting DataBlockScanner thread.
[junit] 12/05/21 10:34:58 INFO datanode.DataNode: DatanodeRegistration(127.0.0.1:46582, storageID=DS-117222309-67.195.138.20-46582-1337595997762, infoPort=52991, ipcPort=36943):Finishing DataNode in: FSDataset{dirpath='<https://builds.apache.org/job/Pig-trunk/ws/trunk/build/test/data/dfs/data/data5/current,/home/jenkins/jenkins-slave/workspace/Pig-trunk/trunk/build/test/data/dfs/data/data6/current'}>
[junit] 12/05/21 10:34:58 INFO ipc.Server: Stopping server on 36943
[junit] 12/05/21 10:34:58 INFO metrics.RpcInstrumentation: shut down
[junit] 12/05/21 10:34:58 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0
[junit] Shutting down DataNode 1
[junit] 12/05/21 10:34:58 INFO datanode.FSDatasetAsyncDiskService: Shutting down all async disk service threads...
[junit] 12/05/21 10:34:58 INFO datanode.FSDatasetAsyncDiskService: All async disk service threads have been shut down.
[junit] 12/05/21 10:34:58 WARN util.MBeans: Hadoop:service=DataNode,name=FSDatasetState-UndefinedStorageId243873997
[junit] javax.management.InstanceNotFoundException: Hadoop:service=DataNode,name=FSDatasetState-UndefinedStorageId243873997
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getMBean(DefaultMBeanServerInterceptor.java:1094)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:415)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:403)
[junit] at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:506)
[junit] at org.apache.hadoop.metrics2.util.MBeans.unregister(MBeans.java:71)
[junit] at org.apache.hadoop.hdfs.server.datanode.FSDataset.shutdown(FSDataset.java:1934)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataNode.shutdown(DataNode.java:788)
[junit] at org.apache.hadoop.hdfs.MiniDFSCluster.shutdownDataNodes(MiniDFSCluster.java:566)
[junit] at org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:550)
[junit] at org.apache.pig.test.MiniGenericCluster.shutdownMiniDfsClusters(MiniGenericCluster.java:87)
[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:129)
[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] 12/05/21 10:34:58 WARN datanode.FSDatasetAsyncDiskService: AsyncDiskService has already shut down.
[junit] 12/05/21 10:34:58 INFO mortbay.log: Stopped SelectChannelConnector@localhost:0
[junit] 12/05/21 10:34:58 INFO ipc.Server: Stopping server on 52046
[junit] 12/05/21 10:34:58 INFO ipc.Server: IPC Server handler 0 on 52046: exiting
[junit] 12/05/21 10:34:58 INFO ipc.Server: IPC Server handler 2 on 52046: exiting
[junit] 12/05/21 10:34:58 INFO ipc.Server: IPC Server handler 1 on 52046: exiting
[junit] 12/05/21 10:34:58 INFO metrics.RpcInstrumentation: shut down
[junit] 12/05/21 10:34:58 INFO ipc.Server: Stopping IPC Server Responder
[junit] 12/05/21 10:34:58 INFO ipc.Server: Stopping IPC Server listener on 52046
[junit] 12/05/21 10:34:58 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 1
[junit] 12/05/21 10:34:58 WARN datanode.DataNode: DatanodeRegistration(127.0.0.1:48274, storageID=DS-11463586-67.195.138.20-48274-1337595997414, infoPort=51462, ipcPort=52046):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:131)
[junit] at java.lang.Thread.run(Thread.java:662)
[junit]
[junit] 12/05/21 10:34:58 INFO datanode.DataNode: Exiting DataXceiveServer
[junit] 12/05/21 10:34:58 INFO datanode.DataNode: Scheduling block blk_-3946732308201533760_1078 file build/test/data/dfs/data/data2/current/blk_-3946732308201533760 for deletion
[junit] 12/05/21 10:34:58 INFO datanode.DataNode: Deleted block blk_-3946732308201533760_1078 at file build/test/data/dfs/data/data2/current/blk_-3946732308201533760
[junit] 12/05/21 10:34:58 INFO mapred.TaskTracker: Received 'KillJobAction' for job: job_20120521102638161_0012
[junit] 12/05/21 10:34:58 WARN mapred.TaskTracker: Unknown job job_20120521102638161_0012 being deleted.
[junit] 12/05/21 10:34:58 INFO mapred.TaskTracker: Received 'KillJobAction' for job: job_20120521102638161_0012
[junit] 12/05/21 10:34:58 WARN mapred.TaskTracker: Unknown job job_20120521102638161_0012 being deleted.
[junit] 12/05/21 10:34:58 INFO datanode.DataBlockScanner: Exiting DataBlockScanner thread.
[junit] 12/05/21 10:34:58 INFO mapred.TaskTracker: Received 'KillJobAction' for job: job_20120521102638161_0012
[junit] 12/05/21 10:34:58 WARN mapred.TaskTracker: Unknown job job_20120521102638161_0012 being deleted.
[junit] 12/05/21 10:34:58 INFO datanode.DataNode: DatanodeRegistration(127.0.0.1:48274, storageID=DS-11463586-67.195.138.20-48274-1337595997414, infoPort=51462, ipcPort=52046):Finishing DataNode in: FSDataset{dirpath='<https://builds.apache.org/job/Pig-trunk/ws/trunk/build/test/data/dfs/data/data3/current,/home/jenkins/jenkins-slave/workspace/Pig-trunk/trunk/build/test/data/dfs/data/data4/current'}>
[junit] 12/05/21 10:34:58 INFO ipc.Server: Stopping server on 52046
[junit] 12/05/21 10:34:58 INFO metrics.RpcInstrumentation: shut down
[junit] 12/05/21 10:34:58 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0
[junit] 12/05/21 10:34:58 INFO datanode.FSDatasetAsyncDiskService: Shutting down all async disk service threads...
[junit] 12/05/21 10:34:58 INFO datanode.FSDatasetAsyncDiskService: All async disk service threads have been shut down.
[junit] 12/05/21 10:34:59 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0
[junit] 12/05/21 10:34:59 WARN util.MBeans: Hadoop:service=DataNode,name=FSDatasetState-UndefinedStorageId1583333776
[junit] javax.management.InstanceNotFoundException: Hadoop:service=DataNode,name=FSDatasetState-UndefinedStorageId1583333776
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getMBean(DefaultMBeanServerInterceptor.java:1094)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:415)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:403)
[junit] at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:506)
[junit] at org.apache.hadoop.metrics2.util.MBeans.unregister(MBeans.java:71)
[junit] at org.apache.hadoop.hdfs.server.datanode.FSDataset.shutdown(FSDataset.java:1934)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataNode.shutdown(DataNode.java:788)
[junit] at org.apache.hadoop.hdfs.MiniDFSCluster.shutdownDataNodes(MiniDFSCluster.java:566)
[junit] at org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:550)
[junit] at org.apache.pig.test.MiniGenericCluster.shutdownMiniDfsClusters(MiniGenericCluster.java:87)
[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:129)
[junit] Shutting down DataNode 0
[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] 12/05/21 10:34:59 WARN datanode.FSDatasetAsyncDiskService: AsyncDiskService has already shut down.
[junit] 12/05/21 10:34:59 INFO mortbay.log: Stopped SelectChannelConnector@localhost:0
[junit] 12/05/21 10:34:59 INFO ipc.Server: Stopping server on 41163
[junit] 12/05/21 10:34:59 INFO ipc.Server: IPC Server handler 0 on 41163: exiting
[junit] 12/05/21 10:34:59 INFO ipc.Server: IPC Server handler 2 on 41163: exiting
[junit] 12/05/21 10:34:59 INFO ipc.Server: IPC Server handler 1 on 41163: exiting
[junit] 12/05/21 10:34:59 INFO metrics.RpcInstrumentation: shut down
[junit] 12/05/21 10:34:59 INFO ipc.Server: Stopping IPC Server Responder
[junit] 12/05/21 10:34:59 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 1
[junit] 12/05/21 10:34:59 INFO ipc.Server: Stopping IPC Server listener on 41163
[junit] 12/05/21 10:34:59 WARN datanode.DataNode: DatanodeRegistration(127.0.0.1:32927, storageID=DS-1830782791-67.195.138.20-32927-1337595996984, infoPort=33979, ipcPort=41163):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:131)
[junit] at java.lang.Thread.run(Thread.java:662)
[junit]
[junit] 12/05/21 10:34:59 INFO datanode.DataNode: Exiting DataXceiveServer
[junit] 12/05/21 10:34:59 INFO datanode.DataBlockScanner: Exiting DataBlockScanner thread.
[junit] 12/05/21 10:34:59 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:48274 to delete blk_-1795430742213646990_1073 blk_-3946732308201533760_1078
[junit] 12/05/21 10:34:59 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:32927 to delete blk_-1795430742213646990_1073
[junit] 12/05/21 10:35:00 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0
[junit] 12/05/21 10:35:00 INFO datanode.DataNode: DatanodeRegistration(127.0.0.1:32927, storageID=DS-1830782791-67.195.138.20-32927-1337595996984, infoPort=33979, ipcPort=41163):Finishing DataNode in: FSDataset{dirpath='<https://builds.apache.org/job/Pig-trunk/ws/trunk/build/test/data/dfs/data/data1/current,/home/jenkins/jenkins-slave/workspace/Pig-trunk/trunk/build/test/data/dfs/data/data2/current'}>
[junit] 12/05/21 10:35:00 WARN util.MBeans: Hadoop:service=DataNode,name=DataNodeInfo
[junit] javax.management.InstanceNotFoundException: Hadoop:service=DataNode,name=DataNodeInfo
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getMBean(DefaultMBeanServerInterceptor.java:1094)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:415)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:403)
[junit] at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:506)
[junit] at org.apache.hadoop.metrics2.util.MBeans.unregister(MBeans.java:71)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataNode.unRegisterMXBean(DataNode.java:513)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataNode.shutdown(DataNode.java:726)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataNode.run(DataNode.java:1442)
[junit] at java.lang.Thread.run(Thread.java:662)
[junit] 12/05/21 10:35:00 INFO ipc.Server: Stopping server on 41163
[junit] 12/05/21 10:35:00 INFO metrics.RpcInstrumentation: shut down
[junit] 12/05/21 10:35:00 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0
[junit] 12/05/21 10:35:00 INFO datanode.FSDatasetAsyncDiskService: Shutting down all async disk service threads...
[junit] 12/05/21 10:35:00 INFO datanode.FSDatasetAsyncDiskService: All async disk service threads have been shut down.
[junit] 12/05/21 10:35:00 WARN util.MBeans: Hadoop:service=DataNode,name=FSDatasetState-UndefinedStorageId1380897781
[junit] javax.management.InstanceNotFoundException: Hadoop:service=DataNode,name=FSDatasetState-UndefinedStorageId1380897781
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getMBean(DefaultMBeanServerInterceptor.java:1094)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:415)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:403)
[junit] at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:506)
[junit] at org.apache.hadoop.metrics2.util.MBeans.unregister(MBeans.java:71)
[junit] at org.apache.hadoop.hdfs.server.datanode.FSDataset.shutdown(FSDataset.java:1934)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataNode.shutdown(DataNode.java:788)
[junit] at org.apache.hadoop.hdfs.MiniDFSCluster.shutdownDataNodes(MiniDFSCluster.java:566)
[junit] at org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:550)
[junit] at org.apache.pig.test.MiniGenericCluster.shutdownMiniDfsClusters(MiniGenericCluster.java:87)
[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:129)
[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] 12/05/21 10:35:00 WARN datanode.FSDatasetAsyncDiskService: AsyncDiskService has already shut down.
[junit] 12/05/21 10:35:00 INFO mortbay.log: Stopped SelectChannelConnector@localhost:0
[junit] 12/05/21 10:35:00 WARN namenode.FSNamesystem: ReplicationMonitor thread received InterruptedException.java.lang.InterruptedException: sleep interrupted
[junit] 12/05/21 10:35:00 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] 12/05/21 10:35:00 INFO namenode.FSNamesystem: Number of transactions: 382 Total time for transactions(ms): 8Number of transactions batched in Syncs: 151 Number of syncs: 288 SyncTimes(ms): 2874 429
[junit] 12/05/21 10:35:00 INFO ipc.Server: Stopping server on 52998
[junit] 12/05/21 10:35:00 INFO ipc.Server: IPC Server handler 0 on 52998: exiting
[junit] 12/05/21 10:35:00 INFO ipc.Server: IPC Server handler 1 on 52998: exiting
[junit] 12/05/21 10:35:00 INFO ipc.Server: IPC Server handler 2 on 52998: exiting
[junit] 12/05/21 10:35:00 INFO ipc.Server: IPC Server handler 4 on 52998: exiting
[junit] 12/05/21 10:35:00 INFO ipc.Server: IPC Server handler 3 on 52998: exiting
[junit] 12/05/21 10:35:00 INFO ipc.Server: IPC Server handler 6 on 52998: exiting
[junit] 12/05/21 10:35:00 INFO ipc.Server: IPC Server handler 8 on 52998: exiting
[junit] 12/05/21 10:35:00 INFO ipc.Server: IPC Server handler 5 on 52998: exiting
[junit] 12/05/21 10:35:00 INFO ipc.Server: IPC Server handler 7 on 52998: exiting
[junit] 12/05/21 10:35:00 INFO ipc.Server: IPC Server handler 9 on 52998: exiting
[junit] 12/05/21 10:35:00 INFO ipc.Server: Stopping IPC Server listener on 52998
[junit] 12/05/21 10:35:00 INFO metrics.RpcInstrumentation: shut down
[junit] 12/05/21 10:35:00 INFO ipc.Server: Stopping IPC Server Responder
[junit] Tests run: 17, Failures: 3, Errors: 3, Time elapsed: 496.867 sec
[junit] Test org.apache.pig.test.TestStore FAILED
[junit] Running org.apache.pig.test.TestStringUDFs
[junit] 12/05/21 10:35:01 WARN builtin.SUBSTRING: No logger object provided to UDF: org.apache.pig.builtin.SUBSTRING. java.lang.NullPointerException
[junit] 12/05/21 10:35:01 WARN builtin.SUBSTRING: No logger object provided to UDF: org.apache.pig.builtin.SUBSTRING. java.lang.StringIndexOutOfBoundsException: String index out of range: -2
[junit] 12/05/21 10:35:01 WARN builtin.SUBSTRING: No logger object provided to UDF: org.apache.pig.builtin.SUBSTRING. java.lang.StringIndexOutOfBoundsException: String index out of range: -1
[junit] 12/05/21 10:35:01 WARN builtin.SUBSTRING: No logger object provided to UDF: org.apache.pig.builtin.SUBSTRING. java.lang.StringIndexOutOfBoundsException: String index out of range: -8
[junit] 12/05/21 10:35:01 WARN builtin.SUBSTRING: No logger object provided to UDF: org.apache.pig.builtin.SUBSTRING. java.lang.StringIndexOutOfBoundsException: String index out of range: -2
[junit] 12/05/21 10:35:01 WARN builtin.INDEXOF: No logger object provided to UDF: org.apache.pig.builtin.INDEXOF. Failed to process input; error - null
[junit] 12/05/21 10:35:01 WARN builtin.LAST_INDEX_OF: No logger object provided to UDF: org.apache.pig.builtin.LAST_INDEX_OF. Failed to process input; error - null
[junit] Tests run: 11, Failures: 0, Errors: 0, Time elapsed: 0.102 sec
[delete] Deleting directory /tmp/pig_junit_tmp948366972
BUILD FAILED
<https://builds.apache.org/job/Pig-trunk/ws/trunk/build.xml>:787: The following error occurred while executing this line:
<https://builds.apache.org/job/Pig-trunk/ws/trunk/build.xml>:842: Tests failed!
Total time: 22 minutes 55 seconds
Build step 'Execute shell' marked build as failure
[FINDBUGS] Skipping publisher since build result is FAILURE
Recording test results
Publishing Javadoc
Archiving artifacts
Recording fingerprints
Build failed in Jenkins: Pig-trunk #1248
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Pig-trunk/1248/changes>
Changes:
[julien] PIG-2693: LoadFunc.setLocation should be called before LoadMetadata.getStatistics (billgraham via julien)
------------------------------------------
[...truncated 38769 lines...]
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:415)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:403)
[junit] at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:506)
[junit] at org.apache.hadoop.metrics2.util.MBeans.unregister(MBeans.java:71)
[junit] at org.apache.hadoop.hdfs.server.datanode.FSDataset.shutdown(FSDataset.java:1934)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataNode.shutdown(DataNode.java:788)
[junit] at org.apache.hadoop.hdfs.MiniDFSCluster.shutdownDataNodes(MiniDFSCluster.java:566)
[junit] at org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:550)
[junit] at org.apache.pig.test.MiniGenericCluster.shutdownMiniDfsClusters(MiniGenericCluster.java:87)
[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:129)
[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] 12/05/18 23:01:19 WARN datanode.FSDatasetAsyncDiskService: AsyncDiskService has already shut down.
[junit] 12/05/18 23:01:19 INFO mortbay.log: Stopped SelectChannelConnector@localhost:0
[junit] 12/05/18 23:01:19 INFO ipc.Server: Stopping server on 50987
[junit] 12/05/18 23:01:19 INFO ipc.Server: IPC Server handler 0 on 50987: exiting
[junit] 12/05/18 23:01:19 INFO ipc.Server: Stopping IPC Server listener on 50987
[junit] 12/05/18 23:01:19 INFO ipc.Server: IPC Server handler 1 on 50987: exiting
[junit] 12/05/18 23:01:19 INFO metrics.RpcInstrumentation: shut down
[junit] 12/05/18 23:01:19 INFO ipc.Server: IPC Server handler 2 on 50987: exiting
[junit] 12/05/18 23:01:19 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 1
[junit] 12/05/18 23:01:19 INFO ipc.Server: Stopping IPC Server Responder
[junit] 12/05/18 23:01:19 WARN datanode.DataNode: DatanodeRegistration(127.0.0.1:59522, storageID=DS-1187610694-67.195.138.20-59522-1337381596198, infoPort=43780, ipcPort=50987):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:131)
[junit] at java.lang.Thread.run(Thread.java:662)
[junit]
[junit] 12/05/18 23:01:19 INFO datanode.DataNode: Exiting DataXceiveServer
[junit] 12/05/18 23:01:20 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:59522 to delete blk_-1666757792645533521_1073 blk_-500062698305449906_1078
[junit] 12/05/18 23:01:20 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:45254 to delete blk_-1666757792645533521_1073 blk_-500062698305449906_1078
[junit] 12/05/18 23:01:20 INFO datanode.DataBlockScanner: Exiting DataBlockScanner thread.
[junit] 12/05/18 23:01:20 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0
[junit] 12/05/18 23:01:20 INFO datanode.DataNode: DatanodeRegistration(127.0.0.1:59522, storageID=DS-1187610694-67.195.138.20-59522-1337381596198, infoPort=43780, ipcPort=50987):Finishing DataNode in: FSDataset{dirpath='<https://builds.apache.org/job/Pig-trunk/ws/trunk/build/test/data/dfs/data/data5/current,/home/jenkins/jenkins-slave/workspace/Pig-trunk/trunk/build/test/data/dfs/data/data6/current'}>
[junit] 12/05/18 23:01:20 INFO ipc.Server: Stopping server on 50987
[junit] 12/05/18 23:01:20 INFO metrics.RpcInstrumentation: shut down
[junit] 12/05/18 23:01:20 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0
[junit] 12/05/18 23:01:20 INFO datanode.FSDatasetAsyncDiskService: Shutting down all async disk service threads...
[junit] 12/05/18 23:01:20 INFO datanode.FSDatasetAsyncDiskService: All async disk service threads have been shut down.
[junit] 12/05/18 23:01:20 WARN util.MBeans: Hadoop:service=DataNode,name=FSDatasetState-UndefinedStorageId-1539273439
[junit] javax.management.InstanceNotFoundException: Hadoop:service=DataNode,name=FSDatasetState-UndefinedStorageId-1539273439
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getMBean(DefaultMBeanServerInterceptor.java:1094)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:415)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:403)
[junit] at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:506)
[junit] at org.apache.hadoop.metrics2.util.MBeans.unregister(MBeans.java:71)
[junit] at org.apache.hadoop.hdfs.server.datanode.FSDataset.shutdown(FSDataset.java:1934)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataNode.shutdown(DataNode.java:788)
[junit] at org.apache.hadoop.hdfs.MiniDFSCluster.shutdownDataNodes(MiniDFSCluster.java:566)
[junit] at org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:550)
[junit] at org.apache.pig.test.MiniGenericCluster.shutdownMiniDfsClusters(MiniGenericCluster.java:87)
[junit] Shutting down DataNode 1
[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:129)
[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] 12/05/18 23:01:20 WARN datanode.FSDatasetAsyncDiskService: AsyncDiskService has already shut down.
[junit] 12/05/18 23:01:20 INFO mortbay.log: Stopped SelectChannelConnector@localhost:0
[junit] 12/05/18 23:01:20 INFO ipc.Server: Stopping server on 42630
[junit] 12/05/18 23:01:20 INFO ipc.Server: IPC Server handler 0 on 42630: exiting
[junit] 12/05/18 23:01:20 INFO ipc.Server: IPC Server handler 1 on 42630: exiting
[junit] 12/05/18 23:01:20 INFO ipc.Server: IPC Server handler 2 on 42630: exiting
[junit] 12/05/18 23:01:20 INFO metrics.RpcInstrumentation: shut down
[junit] 12/05/18 23:01:20 INFO ipc.Server: Stopping IPC Server listener on 42630
[junit] 12/05/18 23:01:20 INFO ipc.Server: Stopping IPC Server Responder
[junit] 12/05/18 23:01:20 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 1
[junit] 12/05/18 23:01:20 WARN datanode.DataNode: DatanodeRegistration(127.0.0.1:45254, storageID=DS-1378864488-67.195.138.20-45254-1337381595834, infoPort=56844, ipcPort=42630):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:131)
[junit] at java.lang.Thread.run(Thread.java:662)
[junit]
[junit] 12/05/18 23:01:20 INFO datanode.DataNode: Exiting DataXceiveServer
[junit] 12/05/18 23:01:20 INFO datanode.DataBlockScanner: Exiting DataBlockScanner thread.
[junit] 12/05/18 23:01:21 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0
[junit] 12/05/18 23:01:21 INFO datanode.DataNode: DatanodeRegistration(127.0.0.1:45254, storageID=DS-1378864488-67.195.138.20-45254-1337381595834, infoPort=56844, ipcPort=42630):Finishing DataNode in: FSDataset{dirpath='<https://builds.apache.org/job/Pig-trunk/ws/trunk/build/test/data/dfs/data/data3/current,/home/jenkins/jenkins-slave/workspace/Pig-trunk/trunk/build/test/data/dfs/data/data4/current'}>
[junit] 12/05/18 23:01:21 INFO ipc.Server: Stopping server on 42630
[junit] 12/05/18 23:01:21 INFO metrics.RpcInstrumentation: shut down
[junit] 12/05/18 23:01:21 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0
[junit] 12/05/18 23:01:21 INFO datanode.FSDatasetAsyncDiskService: Shutting down all async disk service threads...
[junit] Shutting down DataNode 0
[junit] 12/05/18 23:01:21 INFO datanode.FSDatasetAsyncDiskService: All async disk service threads have been shut down.
[junit] 12/05/18 23:01:21 WARN util.MBeans: Hadoop:service=DataNode,name=FSDatasetState-UndefinedStorageId37265297
[junit] javax.management.InstanceNotFoundException: Hadoop:service=DataNode,name=FSDatasetState-UndefinedStorageId37265297
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getMBean(DefaultMBeanServerInterceptor.java:1094)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:415)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:403)
[junit] at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:506)
[junit] at org.apache.hadoop.metrics2.util.MBeans.unregister(MBeans.java:71)
[junit] at org.apache.hadoop.hdfs.server.datanode.FSDataset.shutdown(FSDataset.java:1934)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataNode.shutdown(DataNode.java:788)
[junit] at org.apache.hadoop.hdfs.MiniDFSCluster.shutdownDataNodes(MiniDFSCluster.java:566)
[junit] at org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:550)
[junit] at org.apache.pig.test.MiniGenericCluster.shutdownMiniDfsClusters(MiniGenericCluster.java:87)
[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:129)
[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] 12/05/18 23:01:21 WARN datanode.FSDatasetAsyncDiskService: AsyncDiskService has already shut down.
[junit] 12/05/18 23:01:21 INFO mortbay.log: Stopped SelectChannelConnector@localhost:0
[junit] 12/05/18 23:01:21 INFO ipc.Server: Stopping server on 34760
[junit] 12/05/18 23:01:21 INFO ipc.Server: IPC Server handler 0 on 34760: exiting
[junit] 12/05/18 23:01:21 INFO ipc.Server: IPC Server handler 2 on 34760: exiting
[junit] 12/05/18 23:01:21 INFO ipc.Server: IPC Server handler 1 on 34760: exiting
[junit] 12/05/18 23:01:21 INFO metrics.RpcInstrumentation: shut down
[junit] 12/05/18 23:01:21 INFO ipc.Server: Stopping IPC Server Responder
[junit] 12/05/18 23:01:21 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 1
[junit] 12/05/18 23:01:21 INFO ipc.Server: Stopping IPC Server listener on 34760
[junit] 12/05/18 23:01:21 WARN datanode.DataNode: DatanodeRegistration(127.0.0.1:49712, storageID=DS-82211886-67.195.138.20-49712-1337381595443, infoPort=45360, ipcPort=34760):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:131)
[junit] at java.lang.Thread.run(Thread.java:662)
[junit]
[junit] 12/05/18 23:01:21 INFO datanode.DataNode: Exiting DataXceiveServer
[junit] 12/05/18 23:01:21 INFO datanode.DataBlockScanner: Exiting DataBlockScanner thread.
[junit] 12/05/18 23:01:22 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0
[junit] 12/05/18 23:01:22 INFO datanode.DataNode: DatanodeRegistration(127.0.0.1:49712, storageID=DS-82211886-67.195.138.20-49712-1337381595443, infoPort=45360, ipcPort=34760):Finishing DataNode in: FSDataset{dirpath='<https://builds.apache.org/job/Pig-trunk/ws/trunk/build/test/data/dfs/data/data1/current,/home/jenkins/jenkins-slave/workspace/Pig-trunk/trunk/build/test/data/dfs/data/data2/current'}>
[junit] 12/05/18 23:01:22 WARN util.MBeans: Hadoop:service=DataNode,name=DataNodeInfo
[junit] javax.management.InstanceNotFoundException: Hadoop:service=DataNode,name=DataNodeInfo
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getMBean(DefaultMBeanServerInterceptor.java:1094)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:415)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:403)
[junit] at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:506)
[junit] at org.apache.hadoop.metrics2.util.MBeans.unregister(MBeans.java:71)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataNode.unRegisterMXBean(DataNode.java:513)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataNode.shutdown(DataNode.java:726)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataNode.run(DataNode.java:1442)
[junit] at java.lang.Thread.run(Thread.java:662)
[junit] 12/05/18 23:01:22 INFO ipc.Server: Stopping server on 34760
[junit] 12/05/18 23:01:22 INFO metrics.RpcInstrumentation: shut down
[junit] 12/05/18 23:01:22 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0
[junit] 12/05/18 23:01:22 INFO datanode.FSDatasetAsyncDiskService: Shutting down all async disk service threads...
[junit] 12/05/18 23:01:22 INFO datanode.FSDatasetAsyncDiskService: All async disk service threads have been shut down.
[junit] 12/05/18 23:01:22 WARN util.MBeans: Hadoop:service=DataNode,name=FSDatasetState-UndefinedStorageId-1737931210
[junit] javax.management.InstanceNotFoundException: Hadoop:service=DataNode,name=FSDatasetState-UndefinedStorageId-1737931210
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getMBean(DefaultMBeanServerInterceptor.java:1094)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:415)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:403)
[junit] at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:506)
[junit] at org.apache.hadoop.metrics2.util.MBeans.unregister(MBeans.java:71)
[junit] at org.apache.hadoop.hdfs.server.datanode.FSDataset.shutdown(FSDataset.java:1934)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataNode.shutdown(DataNode.java:788)
[junit] at org.apache.hadoop.hdfs.MiniDFSCluster.shutdownDataNodes(MiniDFSCluster.java:566)
[junit] at org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:550)
[junit] at org.apache.pig.test.MiniGenericCluster.shutdownMiniDfsClusters(MiniGenericCluster.java:87)
[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:129)
[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] 12/05/18 23:01:22 WARN datanode.FSDatasetAsyncDiskService: AsyncDiskService has already shut down.
[junit] 12/05/18 23:01:22 INFO mortbay.log: Stopped SelectChannelConnector@localhost:0
[junit] 12/05/18 23:01:23 WARN namenode.FSNamesystem: ReplicationMonitor thread received InterruptedException.java.lang.InterruptedException: sleep interrupted
[junit] 12/05/18 23:01:23 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] 12/05/18 23:01:23 INFO namenode.FSNamesystem: Number of transactions: 382 Total time for transactions(ms): 3Number of transactions batched in Syncs: 154 Number of syncs: 288 SyncTimes(ms): 2762 313
[junit] 12/05/18 23:01:23 INFO ipc.Server: Stopping server on 50807
[junit] 12/05/18 23:01:23 INFO ipc.Server: IPC Server handler 0 on 50807: exiting
[junit] 12/05/18 23:01:23 INFO ipc.Server: IPC Server handler 2 on 50807: exiting
[junit] 12/05/18 23:01:23 INFO ipc.Server: IPC Server handler 1 on 50807: exiting
[junit] 12/05/18 23:01:23 INFO ipc.Server: IPC Server handler 3 on 50807: exiting
[junit] 12/05/18 23:01:23 INFO ipc.Server: IPC Server handler 4 on 50807: exiting
[junit] 12/05/18 23:01:23 INFO ipc.Server: IPC Server handler 5 on 50807: exiting
[junit] 12/05/18 23:01:23 INFO ipc.Server: IPC Server handler 6 on 50807: exiting
[junit] 12/05/18 23:01:23 INFO ipc.Server: IPC Server handler 7 on 50807: exiting
[junit] 12/05/18 23:01:23 INFO ipc.Server: IPC Server handler 8 on 50807: exiting
[junit] 12/05/18 23:01:23 INFO ipc.Server: IPC Server handler 9 on 50807: exiting
[junit] 12/05/18 23:01:23 INFO ipc.Server: Stopping IPC Server listener on 50807
[junit] 12/05/18 23:01:23 INFO metrics.RpcInstrumentation: shut down
[junit] 12/05/18 23:01:23 INFO ipc.Server: Stopping IPC Server Responder
[junit] Tests run: 17, Failures: 3, Errors: 3, Time elapsed: 481.027 sec
[junit] Test org.apache.pig.test.TestStore FAILED
[junit] Running org.apache.pig.test.TestStringUDFs
[junit] 12/05/18 23:01:23 WARN builtin.SUBSTRING: No logger object provided to UDF: org.apache.pig.builtin.SUBSTRING. java.lang.NullPointerException
[junit] 12/05/18 23:01:24 WARN builtin.SUBSTRING: No logger object provided to UDF: org.apache.pig.builtin.SUBSTRING. java.lang.StringIndexOutOfBoundsException: String index out of range: -2
[junit] 12/05/18 23:01:24 WARN builtin.SUBSTRING: No logger object provided to UDF: org.apache.pig.builtin.SUBSTRING. java.lang.StringIndexOutOfBoundsException: String index out of range: -1
[junit] 12/05/18 23:01:24 WARN builtin.SUBSTRING: No logger object provided to UDF: org.apache.pig.builtin.SUBSTRING. java.lang.StringIndexOutOfBoundsException: String index out of range: -8
[junit] 12/05/18 23:01:24 WARN builtin.SUBSTRING: No logger object provided to UDF: org.apache.pig.builtin.SUBSTRING. java.lang.StringIndexOutOfBoundsException: String index out of range: -2
[junit] 12/05/18 23:01:24 WARN builtin.INDEXOF: No logger object provided to UDF: org.apache.pig.builtin.INDEXOF. Failed to process input; error - null
[junit] 12/05/18 23:01:24 WARN builtin.LAST_INDEX_OF: No logger object provided to UDF: org.apache.pig.builtin.LAST_INDEX_OF. Failed to process input; error - null
[junit] Tests run: 11, Failures: 0, Errors: 0, Time elapsed: 0.105 sec
[delete] Deleting directory /tmp/pig_junit_tmp1883241280
BUILD FAILED
<https://builds.apache.org/job/Pig-trunk/ws/trunk/build.xml>:787: The following error occurred while executing this line:
<https://builds.apache.org/job/Pig-trunk/ws/trunk/build.xml>:842: Tests failed!
Total time: 22 minutes 37 seconds
Build step 'Execute shell' marked build as failure
[FINDBUGS] Skipping publisher since build result is FAILURE
Recording test results
Publishing Javadoc
Archiving artifacts
Recording fingerprints
Build failed in Jenkins: Pig-trunk #1247
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Pig-trunk/1247/changes>
Changes:
[julien] PIG-2702: Make Pig local mode (and tests) faster by working around the hard coded sleep(5000) in hadoop's JobControl
------------------------------------------
[...truncated 38348 lines...]
[junit] at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:506)
[junit] at org.apache.hadoop.metrics2.util.MBeans.unregister(MBeans.java:71)
[junit] at org.apache.hadoop.hdfs.server.datanode.FSDataset.shutdown(FSDataset.java:1934)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataNode.shutdown(DataNode.java:788)
[junit] at org.apache.hadoop.hdfs.MiniDFSCluster.shutdownDataNodes(MiniDFSCluster.java:566)
[junit] at org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:550)
[junit] at org.apache.pig.test.MiniGenericCluster.shutdownMiniDfsClusters(MiniGenericCluster.java:87)
[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:129)
[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] 12/05/17 10:34:39 WARN datanode.FSDatasetAsyncDiskService: AsyncDiskService has already shut down.
[junit] Shutting down DataNode 2
[junit] 12/05/17 10:34:39 INFO mortbay.log: Stopped SelectChannelConnector@localhost:0
[junit] 12/05/17 10:34:39 INFO ipc.Server: Stopping server on 52977
[junit] 12/05/17 10:34:39 INFO ipc.Server: IPC Server handler 0 on 52977: exiting
[junit] 12/05/17 10:34:39 INFO ipc.Server: IPC Server handler 1 on 52977: exiting
[junit] 12/05/17 10:34:39 INFO ipc.Server: IPC Server handler 2 on 52977: exiting
[junit] 12/05/17 10:34:39 INFO ipc.Server: Stopping IPC Server listener on 52977
[junit] 12/05/17 10:34:39 INFO metrics.RpcInstrumentation: shut down
[junit] 12/05/17 10:34:39 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 1
[junit] 12/05/17 10:34:39 INFO ipc.Server: Stopping IPC Server Responder
[junit] 12/05/17 10:34:39 WARN datanode.DataNode: DatanodeRegistration(127.0.0.1:34593, storageID=DS-1970142414-67.195.138.20-34593-1337250393035, infoPort=50246, ipcPort=52977):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:131)
[junit] at java.lang.Thread.run(Thread.java:662)
[junit]
[junit] 12/05/17 10:34:39 INFO datanode.DataNode: Exiting DataXceiveServer
[junit] 12/05/17 10:34:39 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:46272 to delete blk_2397791439483215546_1073
[junit] 12/05/17 10:34:39 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:48190 to delete blk_-1057435823053773971_1078 blk_2397791439483215546_1073
[junit] 12/05/17 10:34:40 INFO datanode.DataBlockScanner: Exiting DataBlockScanner thread.
[junit] 12/05/17 10:34:40 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0
[junit] 12/05/17 10:34:40 INFO datanode.DataNode: DatanodeRegistration(127.0.0.1:34593, storageID=DS-1970142414-67.195.138.20-34593-1337250393035, infoPort=50246, ipcPort=52977):Finishing DataNode in: FSDataset{dirpath='<https://builds.apache.org/job/Pig-trunk/ws/trunk/build/test/data/dfs/data/data5/current,/home/jenkins/jenkins-slave/workspace/Pig-trunk/trunk/build/test/data/dfs/data/data6/current'}>
[junit] 12/05/17 10:34:40 INFO ipc.Server: Stopping server on 52977
[junit] 12/05/17 10:34:40 INFO metrics.RpcInstrumentation: shut down
[junit] 12/05/17 10:34:40 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0
[junit] 12/05/17 10:34:40 INFO datanode.FSDatasetAsyncDiskService: Shutting down all async disk service threads...
[junit] 12/05/17 10:34:40 INFO datanode.FSDatasetAsyncDiskService: All async disk service threads have been shut down.
[junit] 12/05/17 10:34:40 WARN util.MBeans: Hadoop:service=DataNode,name=FSDatasetState-UndefinedStorageId528286256
[junit] javax.management.InstanceNotFoundException: Hadoop:service=DataNode,name=FSDatasetState-UndefinedStorageId528286256
[junit] Shutting down DataNode 1
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getMBean(DefaultMBeanServerInterceptor.java:1094)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:415)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:403)
[junit] at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:506)
[junit] at org.apache.hadoop.metrics2.util.MBeans.unregister(MBeans.java:71)
[junit] at org.apache.hadoop.hdfs.server.datanode.FSDataset.shutdown(FSDataset.java:1934)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataNode.shutdown(DataNode.java:788)
[junit] at org.apache.hadoop.hdfs.MiniDFSCluster.shutdownDataNodes(MiniDFSCluster.java:566)
[junit] at org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:550)
[junit] at org.apache.pig.test.MiniGenericCluster.shutdownMiniDfsClusters(MiniGenericCluster.java:87)
[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:129)
[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] 12/05/17 10:34:40 WARN datanode.FSDatasetAsyncDiskService: AsyncDiskService has already shut down.
[junit] 12/05/17 10:34:40 INFO mortbay.log: Stopped SelectChannelConnector@localhost:0
[junit] 12/05/17 10:34:40 INFO ipc.Server: Stopping server on 57186
[junit] 12/05/17 10:34:40 INFO ipc.Server: IPC Server handler 0 on 57186: exiting
[junit] 12/05/17 10:34:40 INFO ipc.Server: IPC Server handler 2 on 57186: exiting
[junit] 12/05/17 10:34:40 INFO ipc.Server: IPC Server handler 1 on 57186: exiting
[junit] 12/05/17 10:34:40 INFO ipc.Server: Stopping IPC Server listener on 57186
[junit] 12/05/17 10:34:40 INFO metrics.RpcInstrumentation: shut down
[junit] 12/05/17 10:34:40 INFO ipc.Server: Stopping IPC Server Responder
[junit] 12/05/17 10:34:40 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 1
[junit] 12/05/17 10:34:40 WARN datanode.DataNode: DatanodeRegistration(127.0.0.1:41136, storageID=DS-1203828062-67.195.138.20-41136-1337250392674, infoPort=57788, ipcPort=57186):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:131)
[junit] at java.lang.Thread.run(Thread.java:662)
[junit]
[junit] 12/05/17 10:34:40 INFO datanode.DataNode: Exiting DataXceiveServer
[junit] 12/05/17 10:34:41 INFO datanode.DataNode: Scheduling block blk_2397791439483215546_1073 file build/test/data/dfs/data/data1/current/blk_2397791439483215546 for deletion
[junit] 12/05/17 10:34:41 INFO datanode.DataNode: Deleted block blk_2397791439483215546_1073 at file build/test/data/dfs/data/data1/current/blk_2397791439483215546
[junit] 12/05/17 10:34:41 INFO datanode.DataBlockScanner: Exiting DataBlockScanner thread.
[junit] 12/05/17 10:34:41 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0
[junit] 12/05/17 10:34:41 INFO datanode.DataNode: DatanodeRegistration(127.0.0.1:41136, storageID=DS-1203828062-67.195.138.20-41136-1337250392674, infoPort=57788, ipcPort=57186):Finishing DataNode in: FSDataset{dirpath='<https://builds.apache.org/job/Pig-trunk/ws/trunk/build/test/data/dfs/data/data3/current,/home/jenkins/jenkins-slave/workspace/Pig-trunk/trunk/build/test/data/dfs/data/data4/current'}>
[junit] 12/05/17 10:34:41 INFO ipc.Server: Stopping server on 57186
[junit] 12/05/17 10:34:41 INFO metrics.RpcInstrumentation: shut down
[junit] 12/05/17 10:34:41 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0
[junit] 12/05/17 10:34:41 INFO datanode.FSDatasetAsyncDiskService: Shutting down all async disk service threads...
[junit] 12/05/17 10:34:41 INFO datanode.FSDatasetAsyncDiskService: All async disk service threads have been shut down.
[junit] 12/05/17 10:34:41 WARN util.MBeans: Hadoop:service=DataNode,name=FSDatasetState-UndefinedStorageId-933422272
[junit] javax.management.InstanceNotFoundException: Hadoop:service=DataNode,name=FSDatasetState-UndefinedStorageId-933422272
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getMBean(DefaultMBeanServerInterceptor.java:1094)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:415)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:403)
[junit] at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:506)
[junit] at org.apache.hadoop.metrics2.util.MBeans.unregister(MBeans.java:71)
[junit] at org.apache.hadoop.hdfs.server.datanode.FSDataset.shutdown(FSDataset.java:1934)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataNode.shutdown(DataNode.java:788)
[junit] at org.apache.hadoop.hdfs.MiniDFSCluster.shutdownDataNodes(MiniDFSCluster.java:566)
[junit] at org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:550)
[junit] at org.apache.pig.test.MiniGenericCluster.shutdownMiniDfsClusters(MiniGenericCluster.java:87)
[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:129)
[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] 12/05/17 10:34:41 WARN datanode.FSDatasetAsyncDiskService: AsyncDiskService has already shut down.
[junit] Shutting down DataNode 0
[junit] 12/05/17 10:34:41 INFO mortbay.log: Stopped SelectChannelConnector@localhost:0
[junit] 12/05/17 10:34:41 INFO ipc.Server: Stopping server on 45659
[junit] 12/05/17 10:34:41 INFO ipc.Server: IPC Server handler 0 on 45659: exiting
[junit] 12/05/17 10:34:41 INFO ipc.Server: Stopping IPC Server listener on 45659
[junit] 12/05/17 10:34:41 INFO metrics.RpcInstrumentation: shut down
[junit] 12/05/17 10:34:41 INFO ipc.Server: Stopping IPC Server Responder
[junit] 12/05/17 10:34:41 WARN datanode.DataNode: DatanodeRegistration(127.0.0.1:46272, storageID=DS-1801895174-67.195.138.20-46272-1337250392216, infoPort=54504, ipcPort=45659):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:131)
[junit] at java.lang.Thread.run(Thread.java:662)
[junit]
[junit] 12/05/17 10:34:41 INFO ipc.Server: IPC Server handler 1 on 45659: exiting
[junit] 12/05/17 10:34:41 INFO ipc.Server: IPC Server handler 2 on 45659: exiting
[junit] 12/05/17 10:34:41 INFO datanode.DataNode: Exiting DataXceiveServer
[junit] 12/05/17 10:34:41 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 1
[junit] 12/05/17 10:34:41 INFO datanode.DataBlockScanner: Exiting DataBlockScanner thread.
[junit] 12/05/17 10:34:41 INFO datanode.DataNode: DatanodeRegistration(127.0.0.1:46272, storageID=DS-1801895174-67.195.138.20-46272-1337250392216, infoPort=54504, ipcPort=45659):Finishing DataNode in: FSDataset{dirpath='<https://builds.apache.org/job/Pig-trunk/ws/trunk/build/test/data/dfs/data/data1/current,/home/jenkins/jenkins-slave/workspace/Pig-trunk/trunk/build/test/data/dfs/data/data2/current'}>
[junit] 12/05/17 10:34:41 WARN util.MBeans: Hadoop:service=DataNode,name=DataNodeInfo
[junit] javax.management.InstanceNotFoundException: Hadoop:service=DataNode,name=DataNodeInfo
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getMBean(DefaultMBeanServerInterceptor.java:1094)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:415)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:403)
[junit] at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:506)
[junit] at org.apache.hadoop.metrics2.util.MBeans.unregister(MBeans.java:71)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataNode.unRegisterMXBean(DataNode.java:513)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataNode.shutdown(DataNode.java:726)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataNode.run(DataNode.java:1442)
[junit] at java.lang.Thread.run(Thread.java:662)
[junit] 12/05/17 10:34:41 INFO ipc.Server: Stopping server on 45659
[junit] 12/05/17 10:34:41 INFO metrics.RpcInstrumentation: shut down
[junit] 12/05/17 10:34:41 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0
[junit] 12/05/17 10:34:41 INFO datanode.FSDatasetAsyncDiskService: Shutting down all async disk service threads...
[junit] 12/05/17 10:34:41 INFO datanode.FSDatasetAsyncDiskService: All async disk service threads have been shut down.
[junit] 12/05/17 10:34:41 WARN util.MBeans: Hadoop:service=DataNode,name=FSDatasetState-UndefinedStorageId-26471557
[junit] javax.management.InstanceNotFoundException: Hadoop:service=DataNode,name=FSDatasetState-UndefinedStorageId-26471557
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getMBean(DefaultMBeanServerInterceptor.java:1094)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:415)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:403)
[junit] at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:506)
[junit] at org.apache.hadoop.metrics2.util.MBeans.unregister(MBeans.java:71)
[junit] at org.apache.hadoop.hdfs.server.datanode.FSDataset.shutdown(FSDataset.java:1934)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataNode.shutdown(DataNode.java:788)
[junit] at org.apache.hadoop.hdfs.MiniDFSCluster.shutdownDataNodes(MiniDFSCluster.java:566)
[junit] at org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:550)
[junit] at org.apache.pig.test.MiniGenericCluster.shutdownMiniDfsClusters(MiniGenericCluster.java:87)
[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:129)
[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] 12/05/17 10:34:41 WARN datanode.FSDatasetAsyncDiskService: AsyncDiskService has already shut down.
[junit] 12/05/17 10:34:41 INFO mortbay.log: Stopped SelectChannelConnector@localhost:0
[junit] 12/05/17 10:34:42 WARN namenode.FSNamesystem: ReplicationMonitor thread received InterruptedException.java.lang.InterruptedException: sleep interrupted
[junit] 12/05/17 10:34:42 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] 12/05/17 10:34:42 INFO namenode.FSNamesystem: Number of transactions: 382 Total time for transactions(ms): 5Number of transactions batched in Syncs: 148 Number of syncs: 289 SyncTimes(ms): 3733 289
[junit] 12/05/17 10:34:42 INFO ipc.Server: Stopping server on 48529
[junit] 12/05/17 10:34:42 INFO ipc.Server: IPC Server handler 0 on 48529: exiting
[junit] 12/05/17 10:34:42 INFO ipc.Server: IPC Server handler 1 on 48529: exiting
[junit] 12/05/17 10:34:42 INFO ipc.Server: IPC Server handler 2 on 48529: exiting
[junit] 12/05/17 10:34:42 INFO ipc.Server: IPC Server handler 4 on 48529: exiting
[junit] 12/05/17 10:34:42 INFO ipc.Server: IPC Server handler 3 on 48529: exiting
[junit] 12/05/17 10:34:42 INFO ipc.Server: IPC Server handler 8 on 48529: exiting
[junit] 12/05/17 10:34:42 INFO ipc.Server: IPC Server handler 6 on 48529: exiting
[junit] 12/05/17 10:34:42 INFO ipc.Server: IPC Server handler 7 on 48529: exiting
[junit] 12/05/17 10:34:42 INFO ipc.Server: Stopping IPC Server listener on 48529
[junit] 12/05/17 10:34:42 INFO ipc.Server: IPC Server handler 9 on 48529: exiting
[junit] 12/05/17 10:34:42 INFO ipc.Server: IPC Server handler 5 on 48529: exiting
[junit] 12/05/17 10:34:42 INFO ipc.Server: Stopping IPC Server Responder
[junit] 12/05/17 10:34:42 INFO metrics.RpcInstrumentation: shut down
[junit] Tests run: 17, Failures: 3, Errors: 3, Time elapsed: 483.211 sec
[junit] Test org.apache.pig.test.TestStore FAILED
[junit] Running org.apache.pig.test.TestStringUDFs
[junit] 12/05/17 10:34:42 WARN builtin.SUBSTRING: No logger object provided to UDF: org.apache.pig.builtin.SUBSTRING. java.lang.NullPointerException
[junit] 12/05/17 10:34:42 WARN builtin.SUBSTRING: No logger object provided to UDF: org.apache.pig.builtin.SUBSTRING. java.lang.StringIndexOutOfBoundsException: String index out of range: -2
[junit] 12/05/17 10:34:42 WARN builtin.SUBSTRING: No logger object provided to UDF: org.apache.pig.builtin.SUBSTRING. java.lang.StringIndexOutOfBoundsException: String index out of range: -1
[junit] 12/05/17 10:34:42 WARN builtin.SUBSTRING: No logger object provided to UDF: org.apache.pig.builtin.SUBSTRING. java.lang.StringIndexOutOfBoundsException: String index out of range: -8
[junit] 12/05/17 10:34:42 WARN builtin.SUBSTRING: No logger object provided to UDF: org.apache.pig.builtin.SUBSTRING. java.lang.StringIndexOutOfBoundsException: String index out of range: -2
[junit] 12/05/17 10:34:42 WARN builtin.INDEXOF: No logger object provided to UDF: org.apache.pig.builtin.INDEXOF. Failed to process input; error - null
[junit] 12/05/17 10:34:43 WARN builtin.LAST_INDEX_OF: No logger object provided to UDF: org.apache.pig.builtin.LAST_INDEX_OF. Failed to process input; error - null
[junit] Tests run: 11, Failures: 0, Errors: 0, Time elapsed: 0.108 sec
[delete] Deleting directory /tmp/pig_junit_tmp1454834579
BUILD FAILED
<https://builds.apache.org/job/Pig-trunk/ws/trunk/build.xml>:787: The following error occurred while executing this line:
<https://builds.apache.org/job/Pig-trunk/ws/trunk/build.xml>:842: Tests failed!
Total time: 22 minutes 38 seconds
Build step 'Execute shell' marked build as failure
[FINDBUGS] Skipping publisher since build result is FAILURE
Recording test results
Publishing Javadoc
Archiving artifacts
Recording fingerprints
Build failed in Jenkins: Pig-trunk #1246
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Pig-trunk/1246/changes>
Changes:
[daijy] PIG-2696: Enhance Job Stat to print out median map and reduce time
------------------------------------------
[...truncated 39167 lines...]
[junit] at org.apache.hadoop.hdfs.server.datanode.FSDataset.shutdown(FSDataset.java:1934)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataNode.shutdown(DataNode.java:788)
[junit] at org.apache.hadoop.hdfs.MiniDFSCluster.shutdownDataNodes(MiniDFSCluster.java:566)
[junit] at org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:550)
[junit] at org.apache.pig.test.MiniGenericCluster.shutdownMiniDfsClusters(MiniGenericCluster.java:87)
[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:129)
[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] 12/05/13 22:38:35 WARN datanode.FSDatasetAsyncDiskService: AsyncDiskService has already shut down.
[junit] 12/05/13 22:38:35 INFO mortbay.log: Stopped SelectChannelConnector@localhost:0
[junit] 12/05/13 22:38:35 INFO ipc.Server: Stopping server on 54897
[junit] 12/05/13 22:38:35 INFO ipc.Server: IPC Server handler 0 on 54897: exiting
[junit] 12/05/13 22:38:35 INFO ipc.Server: IPC Server handler 1 on 54897: exiting
[junit] 12/05/13 22:38:35 INFO ipc.Server: Stopping IPC Server listener on 54897
[junit] 12/05/13 22:38:35 INFO ipc.Server: IPC Server handler 2 on 54897: exiting
[junit] 12/05/13 22:38:35 INFO ipc.Server: Stopping IPC Server Responder
[junit] 12/05/13 22:38:35 INFO metrics.RpcInstrumentation: shut down
[junit] 12/05/13 22:38:35 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 1
[junit] 12/05/13 22:38:35 WARN datanode.DataNode: DatanodeRegistration(127.0.0.1:40884, storageID=DS-414366060-67.195.138.20-40884-1336948143028, infoPort=37081, ipcPort=54897):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:131)
[junit] at java.lang.Thread.run(Thread.java:662)
[junit]
[junit] 12/05/13 22:38:35 INFO datanode.DataNode: Exiting DataXceiveServer
[junit] 12/05/13 22:38:35 INFO datanode.DataNode: Scheduling block blk_2353655801151886748_1078 file build/test/data/dfs/data/data1/current/blk_2353655801151886748 for deletion
[junit] 12/05/13 22:38:35 INFO datanode.DataNode: Deleted block blk_2353655801151886748_1078 at file build/test/data/dfs/data/data1/current/blk_2353655801151886748
[junit] 12/05/13 22:38:35 INFO datanode.DataNode: Scheduling block blk_2353655801151886748_1078 file build/test/data/dfs/data/data3/current/blk_2353655801151886748 for deletion
[junit] 12/05/13 22:38:35 INFO datanode.DataNode: Deleted block blk_2353655801151886748_1078 at file build/test/data/dfs/data/data3/current/blk_2353655801151886748
[junit] 12/05/13 22:38:36 INFO datanode.DataBlockScanner: Exiting DataBlockScanner thread.
[junit] 12/05/13 22:38:36 INFO datanode.DataNode: DatanodeRegistration(127.0.0.1:40884, storageID=DS-414366060-67.195.138.20-40884-1336948143028, infoPort=37081, ipcPort=54897):Finishing DataNode in: FSDataset{dirpath='<https://builds.apache.org/job/Pig-trunk/ws/trunk/build/test/data/dfs/data/data5/current,/home/jenkins/jenkins-slave/workspace/Pig-trunk/trunk/build/test/data/dfs/data/data6/current'}>
[junit] 12/05/13 22:38:36 INFO ipc.Server: Stopping server on 54897
[junit] 12/05/13 22:38:36 INFO metrics.RpcInstrumentation: shut down
[junit] 12/05/13 22:38:36 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0
[junit] 12/05/13 22:38:36 INFO datanode.FSDatasetAsyncDiskService: Shutting down all async disk service threads...
[junit] 12/05/13 22:38:36 INFO datanode.FSDatasetAsyncDiskService: All async disk service threads have been shut down.
[junit] 12/05/13 22:38:36 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0
[junit] 12/05/13 22:38:36 WARN util.MBeans: Hadoop:service=DataNode,name=FSDatasetState-UndefinedStorageId-1495980964
[junit] Shutting down DataNode 1
[junit] javax.management.InstanceNotFoundException: Hadoop:service=DataNode,name=FSDatasetState-UndefinedStorageId-1495980964
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getMBean(DefaultMBeanServerInterceptor.java:1094)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:415)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:403)
[junit] at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:506)
[junit] at org.apache.hadoop.metrics2.util.MBeans.unregister(MBeans.java:71)
[junit] at org.apache.hadoop.hdfs.server.datanode.FSDataset.shutdown(FSDataset.java:1934)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataNode.shutdown(DataNode.java:788)
[junit] at org.apache.hadoop.hdfs.MiniDFSCluster.shutdownDataNodes(MiniDFSCluster.java:566)
[junit] at org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:550)
[junit] at org.apache.pig.test.MiniGenericCluster.shutdownMiniDfsClusters(MiniGenericCluster.java:87)
[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:129)
[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] 12/05/13 22:38:36 WARN datanode.FSDatasetAsyncDiskService: AsyncDiskService has already shut down.
[junit] 12/05/13 22:38:36 INFO mortbay.log: Stopped SelectChannelConnector@localhost:0
[junit] 12/05/13 22:38:36 INFO ipc.Server: Stopping server on 57577
[junit] 12/05/13 22:38:36 INFO ipc.Server: IPC Server handler 1 on 57577: exiting
[junit] 12/05/13 22:38:36 INFO ipc.Server: Stopping IPC Server listener on 57577
[junit] 12/05/13 22:38:36 INFO metrics.RpcInstrumentation: shut down
[junit] 12/05/13 22:38:36 INFO ipc.Server: IPC Server handler 2 on 57577: exiting
[junit] 12/05/13 22:38:36 INFO ipc.Server: IPC Server handler 0 on 57577: exiting
[junit] 12/05/13 22:38:36 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 1
[junit] 12/05/13 22:38:36 INFO ipc.Server: Stopping IPC Server Responder
[junit] 12/05/13 22:38:36 WARN datanode.DataNode: DatanodeRegistration(127.0.0.1:46726, storageID=DS-210843793-67.195.138.20-46726-1336948142649, infoPort=48761, ipcPort=57577):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:131)
[junit] at java.lang.Thread.run(Thread.java:662)
[junit]
[junit] 12/05/13 22:38:36 INFO datanode.DataNode: Exiting DataXceiveServer
[junit] 12/05/13 22:38:37 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:46726 to delete blk_-2267981106852012439_1073
[junit] 12/05/13 22:38:37 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:48040 to delete blk_2353655801151886748_1078
[junit] 12/05/13 22:38:37 INFO datanode.DataBlockScanner: Exiting DataBlockScanner thread.
[junit] 12/05/13 22:38:37 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0
[junit] 12/05/13 22:38:37 INFO datanode.DataNode: DatanodeRegistration(127.0.0.1:46726, storageID=DS-210843793-67.195.138.20-46726-1336948142649, infoPort=48761, ipcPort=57577):Finishing DataNode in: FSDataset{dirpath='<https://builds.apache.org/job/Pig-trunk/ws/trunk/build/test/data/dfs/data/data3/current,/home/jenkins/jenkins-slave/workspace/Pig-trunk/trunk/build/test/data/dfs/data/data4/current'}>
[junit] 12/05/13 22:38:37 INFO ipc.Server: Stopping server on 57577
[junit] 12/05/13 22:38:37 INFO metrics.RpcInstrumentation: shut down
[junit] 12/05/13 22:38:37 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0
[junit] 12/05/13 22:38:37 INFO datanode.FSDatasetAsyncDiskService: Shutting down all async disk service threads...
[junit] 12/05/13 22:38:37 INFO datanode.FSDatasetAsyncDiskService: All async disk service threads have been shut down.
[junit] 12/05/13 22:38:37 WARN util.MBeans: Hadoop:service=DataNode,name=FSDatasetState-UndefinedStorageId-16012443
[junit] javax.management.InstanceNotFoundException: Hadoop:service=DataNode,name=FSDatasetState-UndefinedStorageId-16012443
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getMBean(DefaultMBeanServerInterceptor.java:1094)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:415)
[junit] Shutting down DataNode 0
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:403)
[junit] at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:506)
[junit] at org.apache.hadoop.metrics2.util.MBeans.unregister(MBeans.java:71)
[junit] at org.apache.hadoop.hdfs.server.datanode.FSDataset.shutdown(FSDataset.java:1934)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataNode.shutdown(DataNode.java:788)
[junit] at org.apache.hadoop.hdfs.MiniDFSCluster.shutdownDataNodes(MiniDFSCluster.java:566)
[junit] at org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:550)
[junit] at org.apache.pig.test.MiniGenericCluster.shutdownMiniDfsClusters(MiniGenericCluster.java:87)
[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:129)
[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] 12/05/13 22:38:37 WARN datanode.FSDatasetAsyncDiskService: AsyncDiskService has already shut down.
[junit] 12/05/13 22:38:37 INFO mortbay.log: Stopped SelectChannelConnector@localhost:0
[junit] 12/05/13 22:38:37 INFO ipc.Server: Stopping server on 54048
[junit] 12/05/13 22:38:37 INFO ipc.Server: IPC Server handler 0 on 54048: exiting
[junit] 12/05/13 22:38:37 INFO ipc.Server: Stopping IPC Server listener on 54048
[junit] 12/05/13 22:38:37 INFO ipc.Server: IPC Server handler 2 on 54048: exiting
[junit] 12/05/13 22:38:37 INFO ipc.Server: IPC Server handler 1 on 54048: exiting
[junit] 12/05/13 22:38:37 INFO metrics.RpcInstrumentation: shut down
[junit] 12/05/13 22:38:37 INFO ipc.Server: Stopping IPC Server Responder
[junit] 12/05/13 22:38:37 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 1
[junit] 12/05/13 22:38:37 WARN datanode.DataNode: DatanodeRegistration(127.0.0.1:53868, storageID=DS-388055455-67.195.138.20-53868-1336948142250, infoPort=34466, ipcPort=54048):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:131)
[junit] at java.lang.Thread.run(Thread.java:662)
[junit]
[junit] 12/05/13 22:38:37 INFO datanode.DataNode: Exiting DataXceiveServer
[junit] 12/05/13 22:38:38 INFO datanode.DataBlockScanner: Exiting DataBlockScanner thread.
[junit] 12/05/13 22:38:38 INFO datanode.DataNode: DatanodeRegistration(127.0.0.1:53868, storageID=DS-388055455-67.195.138.20-53868-1336948142250, infoPort=34466, ipcPort=54048):Finishing DataNode in: FSDataset{dirpath='<https://builds.apache.org/job/Pig-trunk/ws/trunk/build/test/data/dfs/data/data1/current,/home/jenkins/jenkins-slave/workspace/Pig-trunk/trunk/build/test/data/dfs/data/data2/current'}>
[junit] 12/05/13 22:38:38 WARN util.MBeans: Hadoop:service=DataNode,name=DataNodeInfo
[junit] javax.management.InstanceNotFoundException: Hadoop:service=DataNode,name=DataNodeInfo
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getMBean(DefaultMBeanServerInterceptor.java:1094)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:415)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:403)
[junit] at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:506)
[junit] at org.apache.hadoop.metrics2.util.MBeans.unregister(MBeans.java:71)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataNode.unRegisterMXBean(DataNode.java:513)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataNode.shutdown(DataNode.java:726)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataNode.run(DataNode.java:1442)
[junit] at java.lang.Thread.run(Thread.java:662)
[junit] 12/05/13 22:38:38 INFO ipc.Server: Stopping server on 54048
[junit] 12/05/13 22:38:38 INFO metrics.RpcInstrumentation: shut down
[junit] 12/05/13 22:38:38 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0
[junit] 12/05/13 22:38:38 INFO datanode.FSDatasetAsyncDiskService: Shutting down all async disk service threads...
[junit] 12/05/13 22:38:38 INFO datanode.FSDatasetAsyncDiskService: All async disk service threads have been shut down.
[junit] 12/05/13 22:38:38 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0
[junit] 12/05/13 22:38:38 WARN util.MBeans: Hadoop:service=DataNode,name=FSDatasetState-UndefinedStorageId-1932991789
[junit] javax.management.InstanceNotFoundException: Hadoop:service=DataNode,name=FSDatasetState-UndefinedStorageId-1932991789
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getMBean(DefaultMBeanServerInterceptor.java:1094)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:415)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:403)
[junit] at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:506)
[junit] at org.apache.hadoop.metrics2.util.MBeans.unregister(MBeans.java:71)
[junit] at org.apache.hadoop.hdfs.server.datanode.FSDataset.shutdown(FSDataset.java:1934)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataNode.shutdown(DataNode.java:788)
[junit] at org.apache.hadoop.hdfs.MiniDFSCluster.shutdownDataNodes(MiniDFSCluster.java:566)
[junit] at org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:550)
[junit] at org.apache.pig.test.MiniGenericCluster.shutdownMiniDfsClusters(MiniGenericCluster.java:87)
[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:129)
[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] 12/05/13 22:38:38 WARN datanode.FSDatasetAsyncDiskService: AsyncDiskService has already shut down.
[junit] 12/05/13 22:38:38 INFO mortbay.log: Stopped SelectChannelConnector@localhost:0
[junit] 12/05/13 22:38:38 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] 12/05/13 22:38:38 INFO namenode.FSNamesystem: Number of transactions: 382 Total time for transactions(ms): 8Number of transactions batched in Syncs: 148 Number of syncs: 288 SyncTimes(ms): 3213 479
[junit] 12/05/13 22:38:38 WARN namenode.FSNamesystem: ReplicationMonitor thread received InterruptedException.java.lang.InterruptedException: sleep interrupted
[junit] 12/05/13 22:38:38 INFO ipc.Server: Stopping server on 45897
[junit] 12/05/13 22:38:38 INFO ipc.Server: IPC Server handler 0 on 45897: exiting
[junit] 12/05/13 22:38:38 INFO ipc.Server: IPC Server handler 1 on 45897: exiting
[junit] 12/05/13 22:38:38 INFO ipc.Server: IPC Server handler 2 on 45897: exiting
[junit] 12/05/13 22:38:38 INFO ipc.Server: IPC Server handler 3 on 45897: exiting
[junit] 12/05/13 22:38:38 INFO ipc.Server: IPC Server handler 4 on 45897: exiting
[junit] 12/05/13 22:38:38 INFO ipc.Server: IPC Server handler 5 on 45897: exiting
[junit] 12/05/13 22:38:38 INFO ipc.Server: IPC Server handler 7 on 45897: exiting
[junit] 12/05/13 22:38:38 INFO ipc.Server: IPC Server handler 9 on 45897: exiting
[junit] 12/05/13 22:38:38 INFO ipc.Server: Stopping IPC Server listener on 45897
[junit] 12/05/13 22:38:38 INFO ipc.Server: IPC Server handler 6 on 45897: exiting
[junit] 12/05/13 22:38:38 INFO ipc.Server: IPC Server handler 8 on 45897: exiting
[junit] 12/05/13 22:38:38 INFO ipc.Server: Stopping IPC Server Responder
[junit] 12/05/13 22:38:38 INFO metrics.RpcInstrumentation: shut down
[junit] Tests run: 17, Failures: 3, Errors: 3, Time elapsed: 569.967 sec
[junit] Test org.apache.pig.test.TestStore FAILED
[junit] Running org.apache.pig.test.TestStringUDFs
[junit] 12/05/13 22:38:39 WARN builtin.SUBSTRING: No logger object provided to UDF: org.apache.pig.builtin.SUBSTRING. java.lang.NullPointerException
[junit] 12/05/13 22:38:39 WARN builtin.SUBSTRING: No logger object provided to UDF: org.apache.pig.builtin.SUBSTRING. java.lang.StringIndexOutOfBoundsException: String index out of range: -2
[junit] 12/05/13 22:38:39 WARN builtin.SUBSTRING: No logger object provided to UDF: org.apache.pig.builtin.SUBSTRING. java.lang.StringIndexOutOfBoundsException: String index out of range: -1
[junit] 12/05/13 22:38:39 WARN builtin.SUBSTRING: No logger object provided to UDF: org.apache.pig.builtin.SUBSTRING. java.lang.StringIndexOutOfBoundsException: String index out of range: -8
[junit] 12/05/13 22:38:39 WARN builtin.SUBSTRING: No logger object provided to UDF: org.apache.pig.builtin.SUBSTRING. java.lang.StringIndexOutOfBoundsException: String index out of range: -2
[junit] 12/05/13 22:38:39 WARN builtin.INDEXOF: No logger object provided to UDF: org.apache.pig.builtin.INDEXOF. Failed to process input; error - null
[junit] 12/05/13 22:38:39 WARN builtin.LAST_INDEX_OF: No logger object provided to UDF: org.apache.pig.builtin.LAST_INDEX_OF. Failed to process input; error - null
[junit] Tests run: 11, Failures: 0, Errors: 0, Time elapsed: 0.108 sec
[delete] Deleting directory /tmp/pig_junit_tmp881883708
BUILD FAILED
<https://builds.apache.org/job/Pig-trunk/ws/trunk/build.xml>:787: The following error occurred while executing this line:
<https://builds.apache.org/job/Pig-trunk/ws/trunk/build.xml>:842: Tests failed!
Total time: 26 minutes 26 seconds
Build step 'Execute shell' marked build as failure
[FINDBUGS] Skipping publisher since build result is FAILURE
Recording test results
Publishing Javadoc
Archiving artifacts
Recording fingerprints
Build failed in Jenkins: Pig-trunk #1245
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Pig-trunk/1245/changes>
Changes:
[julien] PIG-2659: add source location of the aliases in the physical plan
------------------------------------------
[...truncated 38702 lines...]
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:403)
[junit] at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:506)
[junit] at org.apache.hadoop.metrics2.util.MBeans.unregister(MBeans.java:71)
[junit] at org.apache.hadoop.hdfs.server.datanode.FSDataset.shutdown(FSDataset.java:1934)
[junit] Shutting down DataNode 2
[junit] at org.apache.hadoop.hdfs.server.datanode.DataNode.shutdown(DataNode.java:788)
[junit] at org.apache.hadoop.hdfs.MiniDFSCluster.shutdownDataNodes(MiniDFSCluster.java:566)
[junit] at org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:550)
[junit] at org.apache.pig.test.MiniGenericCluster.shutdownMiniDfsClusters(MiniGenericCluster.java:87)
[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:129)
[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] 12/05/12 22:38:01 WARN datanode.FSDatasetAsyncDiskService: AsyncDiskService has already shut down.
[junit] 12/05/12 22:38:01 INFO mortbay.log: Stopped SelectChannelConnector@localhost:0
[junit] 12/05/12 22:38:01 INFO ipc.Server: Stopping server on 59660
[junit] 12/05/12 22:38:01 INFO ipc.Server: IPC Server handler 1 on 59660: exiting
[junit] 12/05/12 22:38:01 INFO ipc.Server: IPC Server handler 0 on 59660: exiting
[junit] 12/05/12 22:38:01 INFO metrics.RpcInstrumentation: shut down
[junit] 12/05/12 22:38:01 INFO ipc.Server: Stopping IPC Server listener on 59660
[junit] 12/05/12 22:38:01 INFO ipc.Server: IPC Server handler 2 on 59660: exiting
[junit] 12/05/12 22:38:01 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 1
[junit] 12/05/12 22:38:01 WARN datanode.DataNode: DatanodeRegistration(127.0.0.1:40822, storageID=DS-202579468-67.195.138.20-40822-1336861729318, infoPort=41365, ipcPort=59660):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:131)
[junit] at java.lang.Thread.run(Thread.java:662)
[junit]
[junit] 12/05/12 22:38:01 INFO ipc.Server: Stopping IPC Server Responder
[junit] 12/05/12 22:38:01 INFO datanode.DataNode: Exiting DataXceiveServer
[junit] 12/05/12 22:38:01 INFO datanode.DataBlockScanner: Exiting DataBlockScanner thread.
[junit] 12/05/12 22:38:01 INFO datanode.DataNode: DatanodeRegistration(127.0.0.1:40822, storageID=DS-202579468-67.195.138.20-40822-1336861729318, infoPort=41365, ipcPort=59660):Finishing DataNode in: FSDataset{dirpath='<https://builds.apache.org/job/Pig-trunk/ws/trunk/build/test/data/dfs/data/data5/current,/home/jenkins/jenkins-slave/workspace/Pig-trunk/trunk/build/test/data/dfs/data/data6/current'}>
[junit] 12/05/12 22:38:01 INFO ipc.Server: Stopping server on 59660
[junit] 12/05/12 22:38:01 INFO metrics.RpcInstrumentation: shut down
[junit] 12/05/12 22:38:01 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0
[junit] 12/05/12 22:38:01 INFO datanode.FSDatasetAsyncDiskService: Shutting down all async disk service threads...
[junit] 12/05/12 22:38:01 INFO datanode.FSDatasetAsyncDiskService: All async disk service threads have been shut down.
[junit] 12/05/12 22:38:02 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:58628 to delete blk_1805126292946114119_1073
[junit] 12/05/12 22:38:02 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:40822 to delete blk_1805126292946114119_1073
[junit] 12/05/12 22:38:02 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0
[junit] 12/05/12 22:38:02 WARN util.MBeans: Hadoop:service=DataNode,name=FSDatasetState-UndefinedStorageId-740811846
[junit] javax.management.InstanceNotFoundException: Hadoop:service=DataNode,name=FSDatasetState-UndefinedStorageId-740811846
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getMBean(DefaultMBeanServerInterceptor.java:1094)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:415)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:403)
[junit] at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:506)
[junit] at org.apache.hadoop.metrics2.util.MBeans.unregister(MBeans.java:71)
[junit] Shutting down DataNode 1
[junit] at org.apache.hadoop.hdfs.server.datanode.FSDataset.shutdown(FSDataset.java:1934)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataNode.shutdown(DataNode.java:788)
[junit] at org.apache.hadoop.hdfs.MiniDFSCluster.shutdownDataNodes(MiniDFSCluster.java:566)
[junit] at org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:550)
[junit] at org.apache.pig.test.MiniGenericCluster.shutdownMiniDfsClusters(MiniGenericCluster.java:87)
[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:129)
[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] 12/05/12 22:38:02 WARN datanode.FSDatasetAsyncDiskService: AsyncDiskService has already shut down.
[junit] 12/05/12 22:38:02 INFO mortbay.log: Stopped SelectChannelConnector@localhost:0
[junit] 12/05/12 22:38:02 INFO ipc.Server: Stopping server on 60633
[junit] 12/05/12 22:38:02 INFO ipc.Server: IPC Server handler 0 on 60633: exiting
[junit] 12/05/12 22:38:02 INFO ipc.Server: IPC Server handler 1 on 60633: exiting
[junit] 12/05/12 22:38:02 INFO ipc.Server: Stopping IPC Server listener on 60633
[junit] 12/05/12 22:38:02 INFO ipc.Server: IPC Server handler 2 on 60633: exiting
[junit] 12/05/12 22:38:02 INFO metrics.RpcInstrumentation: shut down
[junit] 12/05/12 22:38:02 INFO ipc.Server: Stopping IPC Server Responder
[junit] 12/05/12 22:38:02 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 1
[junit] 12/05/12 22:38:02 WARN datanode.DataNode: DatanodeRegistration(127.0.0.1:33224, storageID=DS-1061704292-67.195.138.20-33224-1336861728932, infoPort=40532, ipcPort=60633):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:131)
[junit] at java.lang.Thread.run(Thread.java:662)
[junit]
[junit] 12/05/12 22:38:02 INFO datanode.DataNode: Exiting DataXceiveServer
[junit] 12/05/12 22:38:03 INFO datanode.DataBlockScanner: Exiting DataBlockScanner thread.
[junit] 12/05/12 22:38:03 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0
[junit] 12/05/12 22:38:03 INFO datanode.DataNode: DatanodeRegistration(127.0.0.1:33224, storageID=DS-1061704292-67.195.138.20-33224-1336861728932, infoPort=40532, ipcPort=60633):Finishing DataNode in: FSDataset{dirpath='<https://builds.apache.org/job/Pig-trunk/ws/trunk/build/test/data/dfs/data/data3/current,/home/jenkins/jenkins-slave/workspace/Pig-trunk/trunk/build/test/data/dfs/data/data4/current'}>
[junit] 12/05/12 22:38:03 INFO ipc.Server: Stopping server on 60633
[junit] 12/05/12 22:38:03 INFO metrics.RpcInstrumentation: shut down
[junit] 12/05/12 22:38:03 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0
[junit] 12/05/12 22:38:03 INFO datanode.FSDatasetAsyncDiskService: Shutting down all async disk service threads...
[junit] 12/05/12 22:38:03 INFO datanode.FSDatasetAsyncDiskService: All async disk service threads have been shut down.
[junit] 12/05/12 22:38:03 WARN util.MBeans: Hadoop:service=DataNode,name=FSDatasetState-UndefinedStorageId1126670296
[junit] javax.management.InstanceNotFoundException: Hadoop:service=DataNode,name=FSDatasetState-UndefinedStorageId1126670296
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getMBean(DefaultMBeanServerInterceptor.java:1094)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:415)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:403)
[junit] at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:506)
[junit] at org.apache.hadoop.metrics2.util.MBeans.unregister(MBeans.java:71)
[junit] at org.apache.hadoop.hdfs.server.datanode.FSDataset.shutdown(FSDataset.java:1934)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataNode.shutdown(DataNode.java:788)
[junit] at org.apache.hadoop.hdfs.MiniDFSCluster.shutdownDataNodes(MiniDFSCluster.java:566)
[junit] at org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:550)
[junit] at org.apache.pig.test.MiniGenericCluster.shutdownMiniDfsClusters(MiniGenericCluster.java:87)
[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:129)
[junit] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit] Shutting down DataNode 0
[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] 12/05/12 22:38:03 WARN datanode.FSDatasetAsyncDiskService: AsyncDiskService has already shut down.
[junit] 12/05/12 22:38:03 INFO mortbay.log: Stopped SelectChannelConnector@localhost:0
[junit] 12/05/12 22:38:03 INFO ipc.Server: Stopping server on 37277
[junit] 12/05/12 22:38:03 INFO ipc.Server: IPC Server handler 0 on 37277: exiting
[junit] 12/05/12 22:38:03 INFO ipc.Server: IPC Server handler 1 on 37277: exiting
[junit] 12/05/12 22:38:03 INFO ipc.Server: Stopping IPC Server listener on 37277
[junit] 12/05/12 22:38:03 INFO metrics.RpcInstrumentation: shut down
[junit] 12/05/12 22:38:03 INFO ipc.Server: IPC Server handler 2 on 37277: exiting
[junit] 12/05/12 22:38:03 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 1
[junit] 12/05/12 22:38:03 INFO ipc.Server: Stopping IPC Server Responder
[junit] 12/05/12 22:38:03 WARN datanode.DataNode: DatanodeRegistration(127.0.0.1:34972, storageID=DS-750767416-67.195.138.20-34972-1336861728543, infoPort=57455, ipcPort=37277):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:131)
[junit] at java.lang.Thread.run(Thread.java:662)
[junit]
[junit] 12/05/12 22:38:03 INFO datanode.DataNode: Exiting DataXceiveServer
[junit] 12/05/12 22:38:03 INFO datanode.DataBlockScanner: Exiting DataBlockScanner thread.
[junit] 12/05/12 22:38:03 INFO datanode.DataNode: DatanodeRegistration(127.0.0.1:34972, storageID=DS-750767416-67.195.138.20-34972-1336861728543, infoPort=57455, ipcPort=37277):Finishing DataNode in: FSDataset{dirpath='<https://builds.apache.org/job/Pig-trunk/ws/trunk/build/test/data/dfs/data/data1/current,/home/jenkins/jenkins-slave/workspace/Pig-trunk/trunk/build/test/data/dfs/data/data2/current'}>
[junit] 12/05/12 22:38:03 WARN util.MBeans: Hadoop:service=DataNode,name=DataNodeInfo
[junit] javax.management.InstanceNotFoundException: Hadoop:service=DataNode,name=DataNodeInfo
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getMBean(DefaultMBeanServerInterceptor.java:1094)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:415)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:403)
[junit] at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:506)
[junit] at org.apache.hadoop.metrics2.util.MBeans.unregister(MBeans.java:71)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataNode.unRegisterMXBean(DataNode.java:513)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataNode.shutdown(DataNode.java:726)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataNode.run(DataNode.java:1442)
[junit] at java.lang.Thread.run(Thread.java:662)
[junit] 12/05/12 22:38:03 INFO ipc.Server: Stopping server on 37277
[junit] 12/05/12 22:38:03 INFO metrics.RpcInstrumentation: shut down
[junit] 12/05/12 22:38:03 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0
[junit] 12/05/12 22:38:03 INFO datanode.FSDatasetAsyncDiskService: Shutting down all async disk service threads...
[junit] 12/05/12 22:38:03 INFO datanode.FSDatasetAsyncDiskService: All async disk service threads have been shut down.
[junit] 12/05/12 22:38:04 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0
[junit] 12/05/12 22:38:04 WARN util.MBeans: Hadoop:service=DataNode,name=FSDatasetState-UndefinedStorageId440293821
[junit] javax.management.InstanceNotFoundException: Hadoop:service=DataNode,name=FSDatasetState-UndefinedStorageId440293821
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getMBean(DefaultMBeanServerInterceptor.java:1094)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:415)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:403)
[junit] at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:506)
[junit] at org.apache.hadoop.metrics2.util.MBeans.unregister(MBeans.java:71)
[junit] at org.apache.hadoop.hdfs.server.datanode.FSDataset.shutdown(FSDataset.java:1934)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataNode.shutdown(DataNode.java:788)
[junit] at org.apache.hadoop.hdfs.MiniDFSCluster.shutdownDataNodes(MiniDFSCluster.java:566)
[junit] at org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:550)
[junit] at org.apache.pig.test.MiniGenericCluster.shutdownMiniDfsClusters(MiniGenericCluster.java:87)
[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:129)
[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] 12/05/12 22:38:04 WARN datanode.FSDatasetAsyncDiskService: AsyncDiskService has already shut down.
[junit] 12/05/12 22:38:04 INFO mortbay.log: Stopped SelectChannelConnector@localhost:0
[junit] 12/05/12 22:38:04 WARN namenode.FSNamesystem: ReplicationMonitor thread received InterruptedException.java.lang.InterruptedException: sleep interrupted
[junit] 12/05/12 22:38:04 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] 12/05/12 22:38:04 INFO namenode.FSNamesystem: Number of transactions: 382 Total time for transactions(ms): 11Number of transactions batched in Syncs: 150 Number of syncs: 287 SyncTimes(ms): 4050 423
[junit] 12/05/12 22:38:04 INFO ipc.Server: Stopping server on 46389
[junit] 12/05/12 22:38:04 INFO ipc.Server: Stopping IPC Server listener on 46389
[junit] 12/05/12 22:38:04 INFO ipc.Server: IPC Server handler 2 on 46389: exiting
[junit] 12/05/12 22:38:04 INFO ipc.Server: IPC Server handler 4 on 46389: exiting
[junit] 12/05/12 22:38:04 INFO metrics.RpcInstrumentation: shut down
[junit] 12/05/12 22:38:04 INFO ipc.Server: IPC Server handler 1 on 46389: exiting
[junit] 12/05/12 22:38:04 INFO ipc.Server: IPC Server handler 9 on 46389: exiting
[junit] 12/05/12 22:38:04 INFO ipc.Server: IPC Server handler 8 on 46389: exiting
[junit] 12/05/12 22:38:04 INFO ipc.Server: IPC Server handler 6 on 46389: exiting
[junit] 12/05/12 22:38:04 INFO ipc.Server: IPC Server handler 3 on 46389: exiting
[junit] 12/05/12 22:38:04 INFO ipc.Server: IPC Server handler 7 on 46389: exiting
[junit] 12/05/12 22:38:04 INFO ipc.Server: Stopping IPC Server Responder
[junit] 12/05/12 22:38:04 INFO ipc.Server: IPC Server handler 0 on 46389: exiting
[junit] 12/05/12 22:38:04 INFO ipc.Server: IPC Server handler 5 on 46389: exiting
[junit] Tests run: 17, Failures: 3, Errors: 3, Time elapsed: 549.643 sec
[junit] Test org.apache.pig.test.TestStore FAILED
[junit] Running org.apache.pig.test.TestStringUDFs
[junit] 12/05/12 22:38:05 WARN builtin.SUBSTRING: No logger object provided to UDF: org.apache.pig.builtin.SUBSTRING. java.lang.NullPointerException
[junit] 12/05/12 22:38:05 WARN builtin.SUBSTRING: No logger object provided to UDF: org.apache.pig.builtin.SUBSTRING. java.lang.StringIndexOutOfBoundsException: String index out of range: -2
[junit] 12/05/12 22:38:05 WARN builtin.SUBSTRING: No logger object provided to UDF: org.apache.pig.builtin.SUBSTRING. java.lang.StringIndexOutOfBoundsException: String index out of range: -1
[junit] 12/05/12 22:38:05 WARN builtin.SUBSTRING: No logger object provided to UDF: org.apache.pig.builtin.SUBSTRING. java.lang.StringIndexOutOfBoundsException: String index out of range: -8
[junit] 12/05/12 22:38:05 WARN builtin.SUBSTRING: No logger object provided to UDF: org.apache.pig.builtin.SUBSTRING. java.lang.StringIndexOutOfBoundsException: String index out of range: -2
[junit] 12/05/12 22:38:05 WARN builtin.INDEXOF: No logger object provided to UDF: org.apache.pig.builtin.INDEXOF. Failed to process input; error - null
[junit] 12/05/12 22:38:05 WARN builtin.LAST_INDEX_OF: No logger object provided to UDF: org.apache.pig.builtin.LAST_INDEX_OF. Failed to process input; error - null
[junit] Tests run: 11, Failures: 0, Errors: 0, Time elapsed: 0.113 sec
[delete] Deleting directory /tmp/pig_junit_tmp1463950500
BUILD FAILED
<https://builds.apache.org/job/Pig-trunk/ws/trunk/build.xml>:787: The following error occurred while executing this line:
<https://builds.apache.org/job/Pig-trunk/ws/trunk/build.xml>:842: Tests failed!
Total time: 26 minutes 0 seconds
Build step 'Execute shell' marked build as failure
[FINDBUGS] Skipping publisher since build result is FAILURE
Recording test results
Publishing Javadoc
Archiving artifacts
Recording fingerprints
Build failed in Jenkins: Pig-trunk #1244
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Pig-trunk/1244/changes>
Changes:
[billgraham] PIG-2547: Easier UDFs: Convenient EvalFunc super-classes (billgraham, dvryaboy)
[jcoveney] PIG-2639: Utils.getSchemaFromString should automatically give name to all types, but fails on boolean (jcoveney)
------------------------------------------
[...truncated 36833 lines...]
[junit] at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:506)
[junit] at org.apache.hadoop.metrics2.util.MBeans.unregister(MBeans.java:71)
[junit] at org.apache.hadoop.hdfs.server.datanode.FSDataset.shutdown(FSDataset.java:1934)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataNode.shutdown(DataNode.java:788)
[junit] at org.apache.hadoop.hdfs.MiniDFSCluster.shutdownDataNodes(MiniDFSCluster.java:566)
[junit] at org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:550)
[junit] at org.apache.pig.test.MiniGenericCluster.shutdownMiniDfsClusters(MiniGenericCluster.java:87)
[junit] at org.apache.pig.test.MiniGenericCluster.shutdownMiniDfsAndMrClusters(MiniGenericCluster.java:77)
[junit] Shutting down DataNode 2
[junit] at org.apache.pig.test.MiniGenericCluster.shutDown(MiniGenericCluster.java:68)
[junit] at org.apache.pig.test.TestStore.oneTimeTearDown(TestStore.java:129)
[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] 12/05/12 10:37:14 WARN datanode.FSDatasetAsyncDiskService: AsyncDiskService has already shut down.
[junit] 12/05/12 10:37:14 INFO mortbay.log: Stopped SelectChannelConnector@localhost:0
[junit] 12/05/12 10:37:14 INFO ipc.Server: Stopping server on 40793
[junit] 12/05/12 10:37:14 INFO ipc.Server: IPC Server handler 1 on 40793: exiting
[junit] 12/05/12 10:37:14 INFO ipc.Server: IPC Server handler 0 on 40793: exiting
[junit] 12/05/12 10:37:14 INFO ipc.Server: IPC Server handler 2 on 40793: exiting
[junit] 12/05/12 10:37:14 INFO ipc.Server: Stopping IPC Server Responder
[junit] 12/05/12 10:37:14 INFO metrics.RpcInstrumentation: shut down
[junit] 12/05/12 10:37:14 INFO ipc.Server: Stopping IPC Server listener on 40793
[junit] 12/05/12 10:37:14 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 1
[junit] 12/05/12 10:37:14 WARN datanode.DataNode: DatanodeRegistration(127.0.0.1:55572, storageID=DS-1121860991-67.195.138.20-55572-1336818497415, infoPort=57976, ipcPort=40793):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:131)
[junit] at java.lang.Thread.run(Thread.java:662)
[junit]
[junit] 12/05/12 10:37:14 INFO datanode.DataNode: Exiting DataXceiveServer
[junit] 12/05/12 10:37:15 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:55572 to delete blk_2840035971815812872_1078 blk_1576359942253120846_1073
[junit] 12/05/12 10:37:15 INFO hdfs.StateChange: BLOCK* ask 127.0.0.1:44183 to delete blk_1576359942253120846_1073
[junit] 12/05/12 10:37:15 INFO datanode.DataBlockScanner: Exiting DataBlockScanner thread.
[junit] 12/05/12 10:37:15 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0
[junit] 12/05/12 10:37:15 INFO datanode.DataNode: DatanodeRegistration(127.0.0.1:55572, storageID=DS-1121860991-67.195.138.20-55572-1336818497415, infoPort=57976, ipcPort=40793):Finishing DataNode in: FSDataset{dirpath='<https://builds.apache.org/job/Pig-trunk/ws/trunk/build/test/data/dfs/data/data5/current,/home/jenkins/jenkins-slave/workspace/Pig-trunk/trunk/build/test/data/dfs/data/data6/current'}>
[junit] 12/05/12 10:37:15 INFO ipc.Server: Stopping server on 40793
[junit] 12/05/12 10:37:15 INFO metrics.RpcInstrumentation: shut down
[junit] 12/05/12 10:37:15 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0
[junit] 12/05/12 10:37:15 INFO datanode.FSDatasetAsyncDiskService: Shutting down all async disk service threads...
[junit] 12/05/12 10:37:15 INFO datanode.FSDatasetAsyncDiskService: All async disk service threads have been shut down.
[junit] 12/05/12 10:37:15 WARN util.MBeans: Hadoop:service=DataNode,name=FSDatasetState-UndefinedStorageId-1429877326
[junit] javax.management.InstanceNotFoundException: Hadoop:service=DataNode,name=FSDatasetState-UndefinedStorageId-1429877326
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getMBean(DefaultMBeanServerInterceptor.java:1094)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:415)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:403)
[junit] at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:506)
[junit] at org.apache.hadoop.metrics2.util.MBeans.unregister(MBeans.java:71)
[junit] Shutting down DataNode 1
[junit] at org.apache.hadoop.hdfs.server.datanode.FSDataset.shutdown(FSDataset.java:1934)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataNode.shutdown(DataNode.java:788)
[junit] at org.apache.hadoop.hdfs.MiniDFSCluster.shutdownDataNodes(MiniDFSCluster.java:566)
[junit] at org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:550)
[junit] at org.apache.pig.test.MiniGenericCluster.shutdownMiniDfsClusters(MiniGenericCluster.java:87)
[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:129)
[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] 12/05/12 10:37:15 WARN datanode.FSDatasetAsyncDiskService: AsyncDiskService has already shut down.
[junit] 12/05/12 10:37:15 INFO mortbay.log: Stopped SelectChannelConnector@localhost:0
[junit] 12/05/12 10:37:15 INFO ipc.Server: Stopping server on 45187
[junit] 12/05/12 10:37:15 INFO ipc.Server: IPC Server handler 0 on 45187: exiting
[junit] 12/05/12 10:37:15 INFO ipc.Server: Stopping IPC Server Responder
[junit] 12/05/12 10:37:15 INFO ipc.Server: IPC Server handler 2 on 45187: exiting
[junit] 12/05/12 10:37:15 INFO ipc.Server: Stopping IPC Server listener on 45187
[junit] 12/05/12 10:37:15 INFO metrics.RpcInstrumentation: shut down
[junit] 12/05/12 10:37:15 INFO ipc.Server: IPC Server handler 1 on 45187: exiting
[junit] 12/05/12 10:37:15 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 1
[junit] 12/05/12 10:37:15 WARN datanode.DataNode: DatanodeRegistration(127.0.0.1:43042, storageID=DS-744345635-67.195.138.20-43042-1336818497053, infoPort=57278, ipcPort=45187):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:131)
[junit] at java.lang.Thread.run(Thread.java:662)
[junit]
[junit] 12/05/12 10:37:15 INFO datanode.DataNode: Exiting DataXceiveServer
[junit] 12/05/12 10:37:16 INFO datanode.DataBlockScanner: Exiting DataBlockScanner thread.
[junit] 12/05/12 10:37:16 INFO datanode.DataNode: Scheduling block blk_1576359942253120846_1073 file build/test/data/dfs/data/data1/current/blk_1576359942253120846 for deletion
[junit] 12/05/12 10:37:16 INFO datanode.DataNode: Deleted block blk_1576359942253120846_1073 at file build/test/data/dfs/data/data1/current/blk_1576359942253120846
[junit] 12/05/12 10:37:16 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0
[junit] 12/05/12 10:37:16 INFO datanode.DataNode: DatanodeRegistration(127.0.0.1:43042, storageID=DS-744345635-67.195.138.20-43042-1336818497053, infoPort=57278, ipcPort=45187):Finishing DataNode in: FSDataset{dirpath='<https://builds.apache.org/job/Pig-trunk/ws/trunk/build/test/data/dfs/data/data3/current,/home/jenkins/jenkins-slave/workspace/Pig-trunk/trunk/build/test/data/dfs/data/data4/current'}>
[junit] 12/05/12 10:37:16 INFO ipc.Server: Stopping server on 45187
[junit] 12/05/12 10:37:16 INFO metrics.RpcInstrumentation: shut down
[junit] 12/05/12 10:37:16 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0
[junit] 12/05/12 10:37:16 INFO datanode.FSDatasetAsyncDiskService: Shutting down all async disk service threads...
[junit] 12/05/12 10:37:16 INFO datanode.FSDatasetAsyncDiskService: All async disk service threads have been shut down.
[junit] 12/05/12 10:37:16 WARN util.MBeans: Hadoop:service=DataNode,name=FSDatasetState-UndefinedStorageId-221035736
[junit] javax.management.InstanceNotFoundException: Hadoop:service=DataNode,name=FSDatasetState-UndefinedStorageId-221035736
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getMBean(DefaultMBeanServerInterceptor.java:1094)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:415)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:403)
[junit] at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:506)
[junit] at org.apache.hadoop.metrics2.util.MBeans.unregister(MBeans.java:71)
[junit] Shutting down DataNode 0
[junit] at org.apache.hadoop.hdfs.server.datanode.FSDataset.shutdown(FSDataset.java:1934)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataNode.shutdown(DataNode.java:788)
[junit] at org.apache.hadoop.hdfs.MiniDFSCluster.shutdownDataNodes(MiniDFSCluster.java:566)
[junit] at org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:550)
[junit] at org.apache.pig.test.MiniGenericCluster.shutdownMiniDfsClusters(MiniGenericCluster.java:87)
[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:129)
[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] 12/05/12 10:37:16 WARN datanode.FSDatasetAsyncDiskService: AsyncDiskService has already shut down.
[junit] 12/05/12 10:37:16 INFO mortbay.log: Stopped SelectChannelConnector@localhost:0
[junit] 12/05/12 10:37:17 INFO ipc.Server: Stopping server on 35974
[junit] 12/05/12 10:37:17 INFO ipc.Server: IPC Server handler 0 on 35974: exiting
[junit] 12/05/12 10:37:17 INFO ipc.Server: IPC Server handler 2 on 35974: exiting
[junit] 12/05/12 10:37:17 INFO metrics.RpcInstrumentation: shut down
[junit] 12/05/12 10:37:17 INFO ipc.Server: IPC Server handler 1 on 35974: exiting
[junit] 12/05/12 10:37:17 INFO ipc.Server: Stopping IPC Server listener on 35974
[junit] 12/05/12 10:37:17 WARN datanode.DataNode: DatanodeRegistration(127.0.0.1:44183, storageID=DS-1631206735-67.195.138.20-44183-1336818496654, infoPort=40445, ipcPort=35974):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:131)
[junit] at java.lang.Thread.run(Thread.java:662)
[junit]
[junit] 12/05/12 10:37:17 INFO ipc.Server: Stopping IPC Server Responder
[junit] 12/05/12 10:37:17 INFO datanode.DataNode: Exiting DataXceiveServer
[junit] 12/05/12 10:37:17 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 1
[junit] 12/05/12 10:37:17 INFO datanode.DataBlockScanner: Exiting DataBlockScanner thread.
[junit] 12/05/12 10:37:17 INFO datanode.DataNode: DatanodeRegistration(127.0.0.1:44183, storageID=DS-1631206735-67.195.138.20-44183-1336818496654, infoPort=40445, ipcPort=35974):Finishing DataNode in: FSDataset{dirpath='<https://builds.apache.org/job/Pig-trunk/ws/trunk/build/test/data/dfs/data/data1/current,/home/jenkins/jenkins-slave/workspace/Pig-trunk/trunk/build/test/data/dfs/data/data2/current'}>
[junit] 12/05/12 10:37:17 WARN util.MBeans: Hadoop:service=DataNode,name=DataNodeInfo
[junit] javax.management.InstanceNotFoundException: Hadoop:service=DataNode,name=DataNodeInfo
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getMBean(DefaultMBeanServerInterceptor.java:1094)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:415)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:403)
[junit] at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:506)
[junit] at org.apache.hadoop.metrics2.util.MBeans.unregister(MBeans.java:71)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataNode.unRegisterMXBean(DataNode.java:513)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataNode.shutdown(DataNode.java:726)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataNode.run(DataNode.java:1442)
[junit] at java.lang.Thread.run(Thread.java:662)
[junit] 12/05/12 10:37:17 INFO ipc.Server: Stopping server on 35974
[junit] 12/05/12 10:37:17 INFO metrics.RpcInstrumentation: shut down
[junit] 12/05/12 10:37:17 INFO datanode.DataNode: Waiting for threadgroup to exit, active threads is 0
[junit] 12/05/12 10:37:17 INFO datanode.FSDatasetAsyncDiskService: Shutting down all async disk service threads...
[junit] 12/05/12 10:37:17 INFO datanode.FSDatasetAsyncDiskService: All async disk service threads have been shut down.
[junit] 12/05/12 10:37:17 WARN util.MBeans: Hadoop:service=DataNode,name=FSDatasetState-UndefinedStorageId1646409404
[junit] javax.management.InstanceNotFoundException: Hadoop:service=DataNode,name=FSDatasetState-UndefinedStorageId1646409404
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getMBean(DefaultMBeanServerInterceptor.java:1094)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:415)
[junit] at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:403)
[junit] at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:506)
[junit] at org.apache.hadoop.metrics2.util.MBeans.unregister(MBeans.java:71)
[junit] at org.apache.hadoop.hdfs.server.datanode.FSDataset.shutdown(FSDataset.java:1934)
[junit] at org.apache.hadoop.hdfs.server.datanode.DataNode.shutdown(DataNode.java:788)
[junit] at org.apache.hadoop.hdfs.MiniDFSCluster.shutdownDataNodes(MiniDFSCluster.java:566)
[junit] at org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:550)
[junit] at org.apache.pig.test.MiniGenericCluster.shutdownMiniDfsClusters(MiniGenericCluster.java:87)
[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:129)
[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] 12/05/12 10:37:17 WARN datanode.FSDatasetAsyncDiskService: AsyncDiskService has already shut down.
[junit] 12/05/12 10:37:17 INFO mortbay.log: Stopped SelectChannelConnector@localhost:0
[junit] 12/05/12 10:37:17 WARN namenode.FSNamesystem: ReplicationMonitor thread received InterruptedException.java.lang.InterruptedException: sleep interrupted
[junit] 12/05/12 10:37:17 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] 12/05/12 10:37:17 INFO namenode.FSNamesystem: Number of transactions: 382 Total time for transactions(ms): 12Number of transactions batched in Syncs: 146 Number of syncs: 287 SyncTimes(ms): 2760 278
[junit] 12/05/12 10:37:17 INFO ipc.Server: Stopping server on 47170
[junit] 12/05/12 10:37:17 INFO ipc.Server: IPC Server handler 0 on 47170: exiting
[junit] 12/05/12 10:37:17 INFO ipc.Server: IPC Server handler 1 on 47170: exiting
[junit] 12/05/12 10:37:17 INFO ipc.Server: IPC Server handler 2 on 47170: exiting
[junit] 12/05/12 10:37:17 INFO ipc.Server: IPC Server handler 3 on 47170: exiting
[junit] 12/05/12 10:37:17 INFO ipc.Server: IPC Server handler 5 on 47170: exiting
[junit] 12/05/12 10:37:17 INFO ipc.Server: IPC Server handler 6 on 47170: exiting
[junit] 12/05/12 10:37:17 INFO ipc.Server: IPC Server handler 4 on 47170: exiting
[junit] 12/05/12 10:37:17 INFO ipc.Server: IPC Server handler 7 on 47170: exiting
[junit] 12/05/12 10:37:17 INFO ipc.Server: Stopping IPC Server listener on 47170
[junit] 12/05/12 10:37:17 INFO ipc.Server: IPC Server handler 8 on 47170: exiting
[junit] 12/05/12 10:37:17 INFO metrics.RpcInstrumentation: shut down
[junit] 12/05/12 10:37:17 INFO ipc.Server: IPC Server handler 9 on 47170: exiting
[junit] 12/05/12 10:37:17 INFO ipc.Server: Stopping IPC Server Responder
[junit] Tests run: 17, Failures: 3, Errors: 3, Time elapsed: 534.07 sec
[junit] Test org.apache.pig.test.TestStore FAILED
[junit] Running org.apache.pig.test.TestStringUDFs
[junit] 12/05/12 10:37:18 WARN builtin.SUBSTRING: No logger object provided to UDF: org.apache.pig.builtin.SUBSTRING. java.lang.NullPointerException
[junit] 12/05/12 10:37:18 WARN builtin.SUBSTRING: No logger object provided to UDF: org.apache.pig.builtin.SUBSTRING. java.lang.StringIndexOutOfBoundsException: String index out of range: -2
[junit] 12/05/12 10:37:18 WARN builtin.SUBSTRING: No logger object provided to UDF: org.apache.pig.builtin.SUBSTRING. java.lang.StringIndexOutOfBoundsException: String index out of range: -1
[junit] 12/05/12 10:37:18 WARN builtin.SUBSTRING: No logger object provided to UDF: org.apache.pig.builtin.SUBSTRING. java.lang.StringIndexOutOfBoundsException: String index out of range: -8
[junit] 12/05/12 10:37:18 WARN builtin.SUBSTRING: No logger object provided to UDF: org.apache.pig.builtin.SUBSTRING. java.lang.StringIndexOutOfBoundsException: String index out of range: -2
[junit] 12/05/12 10:37:18 WARN builtin.INDEXOF: No logger object provided to UDF: org.apache.pig.builtin.INDEXOF. Failed to process input; error - null
[junit] 12/05/12 10:37:18 WARN builtin.LAST_INDEX_OF: No logger object provided to UDF: org.apache.pig.builtin.LAST_INDEX_OF. Failed to process input; error - null
[junit] Tests run: 11, Failures: 0, Errors: 0, Time elapsed: 0.121 sec
[delete] Deleting directory /tmp/pig_junit_tmp525582026
BUILD FAILED
<https://builds.apache.org/job/Pig-trunk/ws/trunk/build.xml>:787: The following error occurred while executing this line:
<https://builds.apache.org/job/Pig-trunk/ws/trunk/build.xml>:842: Tests failed!
Total time: 25 minutes 14 seconds
Build step 'Execute shell' marked build as failure
[FINDBUGS] Skipping publisher since build result is FAILURE
Recording test results
Publishing Javadoc
Archiving artifacts
Recording fingerprints