You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@beam.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2019/08/19 08:14:14 UTC
Build failed in Jenkins: beam_PostCommit_Python37 #254
See <https://builds.apache.org/job/beam_PostCommit_Python37/254/display/redirect?page=changes>
Changes:
[iemejia] [BEAM-7882] Invoke Spark API incompatible methods by reflection
------------------------------------------
[...truncated 216.66 KB...]
namenode_1 | 19/08/19 08:12:26 INFO namenode.FSImage: Planning to load image: FSImageFile(file=/hadoop/dfs/name/current/fsimage_0000000000000000000, cpktTxId=0000000000000000000)
namenode_1 | 19/08/19 08:12:26 INFO namenode.FSImageFormatPBINode: Loading 1 INodes.
namenode_1 | 19/08/19 08:12:26 INFO namenode.FSImageFormatProtobuf: Loaded FSImage in 0 seconds.
namenode_1 | 19/08/19 08:12:26 INFO namenode.FSImage: Loaded image for txid 0 from /hadoop/dfs/name/current/fsimage_0000000000000000000
namenode_1 | 19/08/19 08:12:26 INFO namenode.FSNamesystem: Need to save fs image? false (staleImage=false, haEnabled=false, isRollingUpgrade=false)
namenode_1 | 19/08/19 08:12:26 INFO namenode.FSEditLog: Starting log segment at 1
namenode_1 | 19/08/19 08:12:27 INFO namenode.NameCache: initialized with 0 entries 0 lookups
namenode_1 | 19/08/19 08:12:27 INFO namenode.FSNamesystem: Finished loading FSImage in 353 msecs
datanode_1 | 19/08/19 08:12:27 INFO ipc.Client: Retrying connect to server: namenode/172.25.0.2:8020. Already tried 0 time(s); retry policy is RetryUpToMaximumCountWithFixedSleep(maxRetries=10, sleepTime=1000 MILLISECONDS)
namenode_1 | 19/08/19 08:12:27 INFO namenode.NameNode: RPC server is binding to 0.0.0.0:8020
namenode_1 | 19/08/19 08:12:27 INFO ipc.CallQueueManager: Using callQueue: class java.util.concurrent.LinkedBlockingQueue queueCapacity: 1000 scheduler: class org.apache.hadoop.ipc.DefaultRpcScheduler
namenode_1 | 19/08/19 08:12:27 INFO ipc.Server: Starting Socket Reader #1 for port 8020
namenode_1 | 19/08/19 08:12:27 INFO namenode.FSNamesystem: Registered FSNamesystemState MBean
namenode_1 | 19/08/19 08:12:27 INFO namenode.LeaseManager: Number of blocks under construction: 0
namenode_1 | 19/08/19 08:12:27 INFO blockmanagement.BlockManager: initializing replication queues
namenode_1 | 19/08/19 08:12:27 INFO hdfs.StateChange: STATE* Leaving safe mode after 0 secs
namenode_1 | 19/08/19 08:12:27 INFO hdfs.StateChange: STATE* Network topology has 0 racks and 0 datanodes
namenode_1 | 19/08/19 08:12:27 INFO hdfs.StateChange: STATE* UnderReplicatedBlocks has 0 blocks
namenode_1 | 19/08/19 08:12:27 INFO blockmanagement.BlockManager: Total number of blocks = 0
namenode_1 | 19/08/19 08:12:27 INFO blockmanagement.BlockManager: Number of invalid blocks = 0
namenode_1 | 19/08/19 08:12:27 INFO blockmanagement.BlockManager: Number of under-replicated blocks = 0
namenode_1 | 19/08/19 08:12:27 INFO blockmanagement.BlockManager: Number of over-replicated blocks = 0
namenode_1 | 19/08/19 08:12:27 INFO blockmanagement.BlockManager: Number of blocks being written = 0
namenode_1 | 19/08/19 08:12:27 INFO hdfs.StateChange: STATE* Replication Queue initialization scan for invalid, over- and under-replicated blocks completed in 12 msec
namenode_1 | 19/08/19 08:12:27 INFO ipc.Server: IPC Server Responder: starting
namenode_1 | 19/08/19 08:12:27 INFO ipc.Server: IPC Server listener on 8020: starting
namenode_1 | 19/08/19 08:12:27 INFO namenode.NameNode: NameNode RPC up at: namenode/172.25.0.2:8020
namenode_1 | 19/08/19 08:12:27 INFO namenode.FSNamesystem: Starting services required for active state
namenode_1 | 19/08/19 08:12:27 INFO namenode.FSDirectory: Initializing quota with 4 thread(s)
namenode_1 | 19/08/19 08:12:27 INFO namenode.FSDirectory: Quota initialization completed in 4 milliseconds
namenode_1 | name space=1
namenode_1 | storage space=0
namenode_1 | storage types=RAM_DISK=0, SSD=0, DISK=0, ARCHIVE=0
namenode_1 | 19/08/19 08:12:27 INFO blockmanagement.CacheReplicationMonitor: Starting CacheReplicationMonitor with interval 30000 milliseconds
datanode_1 | 19/08/19 08:12:28 INFO ipc.Client: Retrying connect to server: namenode/172.25.0.2:8020. Already tried 1 time(s); retry policy is RetryUpToMaximumCountWithFixedSleep(maxRetries=10, sleepTime=1000 MILLISECONDS)
datanode_1 | 19/08/19 08:12:28 INFO datanode.DataNode: Acknowledging ACTIVE Namenode during handshakeBlock pool <registering> (Datanode Uuid unassigned) service to namenode/172.25.0.2:8020
datanode_1 | 19/08/19 08:12:28 INFO common.Storage: Using 1 threads to upgrade data directories (dfs.datanode.parallel.volumes.load.threads.num=1, dataDirs=1)
datanode_1 | 19/08/19 08:12:28 INFO common.Storage: Lock on /hadoop/dfs/data/in_use.lock acquired by nodename 82@datanode
datanode_1 | 19/08/19 08:12:28 INFO common.Storage: Storage directory /hadoop/dfs/data is not formatted for namespace 1418625448. Formatting...
datanode_1 | 19/08/19 08:12:28 INFO common.Storage: Generated new storageID DS-2231bedb-b4f2-4fed-a7a5-eb471f5c1038 for directory /hadoop/dfs/data
datanode_1 | 19/08/19 08:12:28 INFO common.Storage: Analyzing storage directories for bpid BP-646680128-172.25.0.2-1566202344544
datanode_1 | 19/08/19 08:12:28 INFO common.Storage: Locking is disabled for /hadoop/dfs/data/current/BP-646680128-172.25.0.2-1566202344544
datanode_1 | 19/08/19 08:12:28 INFO common.Storage: Block pool storage directory /hadoop/dfs/data/current/BP-646680128-172.25.0.2-1566202344544 is not formatted for BP-646680128-172.25.0.2-1566202344544. Formatting ...
datanode_1 | 19/08/19 08:12:28 INFO common.Storage: Formatting block pool BP-646680128-172.25.0.2-1566202344544 directory /hadoop/dfs/data/current/BP-646680128-172.25.0.2-1566202344544/current
datanode_1 | 19/08/19 08:12:28 INFO datanode.DataNode: Setting up storage: nsid=1418625448;bpid=BP-646680128-172.25.0.2-1566202344544;lv=-57;nsInfo=lv=-63;cid=CID-89ba773f-c9f0-4b53-836f-c53e8620da1e;nsid=1418625448;c=1566202344544;bpid=BP-646680128-172.25.0.2-1566202344544;dnuuid=null
datanode_1 | 19/08/19 08:12:28 INFO datanode.DataNode: Generated and persisted new Datanode UUID 103d9f99-03df-4094-b6d7-c983b6e0d428
datanode_1 | 19/08/19 08:12:28 INFO impl.FsDatasetImpl: Added new volume: DS-2231bedb-b4f2-4fed-a7a5-eb471f5c1038
datanode_1 | 19/08/19 08:12:28 INFO impl.FsDatasetImpl: Added volume - /hadoop/dfs/data/current, StorageType: DISK
datanode_1 | 19/08/19 08:12:28 INFO impl.FsDatasetImpl: Registered FSDatasetState MBean
datanode_1 | 19/08/19 08:12:28 INFO impl.FsDatasetImpl: Volume reference is released.
datanode_1 | 19/08/19 08:12:28 INFO impl.FsDatasetImpl: Adding block pool BP-646680128-172.25.0.2-1566202344544
datanode_1 | 19/08/19 08:12:28 INFO impl.FsDatasetImpl: Scanning block pool BP-646680128-172.25.0.2-1566202344544 on volume /hadoop/dfs/data/current...
datanode_1 | 19/08/19 08:12:28 INFO impl.FsDatasetImpl: Time taken to scan block pool BP-646680128-172.25.0.2-1566202344544 on /hadoop/dfs/data/current: 32ms
datanode_1 | 19/08/19 08:12:28 INFO impl.FsDatasetImpl: Total time to scan all replicas for block pool BP-646680128-172.25.0.2-1566202344544: 34ms
datanode_1 | 19/08/19 08:12:28 INFO impl.FsDatasetImpl: Adding replicas to map for block pool BP-646680128-172.25.0.2-1566202344544 on volume /hadoop/dfs/data/current...
datanode_1 | 19/08/19 08:12:28 INFO impl.BlockPoolSlice: Replica Cache file: /hadoop/dfs/data/current/BP-646680128-172.25.0.2-1566202344544/current/replicas doesn't exist
datanode_1 | 19/08/19 08:12:28 INFO impl.FsDatasetImpl: Time to add replicas to map for block pool BP-646680128-172.25.0.2-1566202344544 on volume /hadoop/dfs/data/current: 1ms
datanode_1 | 19/08/19 08:12:28 INFO impl.FsDatasetImpl: Total time to add all replicas to map: 2ms
datanode_1 | 19/08/19 08:12:28 INFO datanode.VolumeScanner: Now scanning bpid BP-646680128-172.25.0.2-1566202344544 on volume /hadoop/dfs/data
datanode_1 | 19/08/19 08:12:28 INFO datanode.DirectoryScanner: Periodic Directory Tree Verification scan starting at 8/19/19 9:20 AM with interval of 21600000ms
datanode_1 | 19/08/19 08:12:28 INFO datanode.DataNode: Block pool BP-646680128-172.25.0.2-1566202344544 (Datanode Uuid 103d9f99-03df-4094-b6d7-c983b6e0d428) service to namenode/172.25.0.2:8020 beginning handshake with NN
datanode_1 | 19/08/19 08:12:28 INFO datanode.VolumeScanner: VolumeScanner(/hadoop/dfs/data, DS-2231bedb-b4f2-4fed-a7a5-eb471f5c1038): finished scanning block pool BP-646680128-172.25.0.2-1566202344544
datanode_1 | 19/08/19 08:12:28 INFO datanode.VolumeScanner: VolumeScanner(/hadoop/dfs/data, DS-2231bedb-b4f2-4fed-a7a5-eb471f5c1038): no suitable block pools found to scan. Waiting 1814399941 ms.
namenode_1 | 19/08/19 08:12:28 INFO hdfs.StateChange: BLOCK* registerDatanode: from DatanodeRegistration(172.25.0.3:50010, datanodeUuid=103d9f99-03df-4094-b6d7-c983b6e0d428, infoPort=50075, infoSecurePort=0, ipcPort=50020, storageInfo=lv=-57;cid=CID-89ba773f-c9f0-4b53-836f-c53e8620da1e;nsid=1418625448;c=1566202344544) storage 103d9f99-03df-4094-b6d7-c983b6e0d428
namenode_1 | 19/08/19 08:12:28 INFO net.NetworkTopology: Adding a new node: /default-rack/172.25.0.3:50010
namenode_1 | 19/08/19 08:12:28 INFO blockmanagement.BlockReportLeaseManager: Registered DN 103d9f99-03df-4094-b6d7-c983b6e0d428 (172.25.0.3:50010).
datanode_1 | 19/08/19 08:12:28 INFO datanode.DataNode: Block pool Block pool BP-646680128-172.25.0.2-1566202344544 (Datanode Uuid 103d9f99-03df-4094-b6d7-c983b6e0d428) service to namenode/172.25.0.2:8020 successfully registered with NN
datanode_1 | 19/08/19 08:12:28 INFO datanode.DataNode: For namenode namenode/172.25.0.2:8020 using BLOCKREPORT_INTERVAL of 21600000msec CACHEREPORT_INTERVAL of 10000msec Initial delay: 0msec; heartBeatInterval=3000
namenode_1 | 19/08/19 08:12:28 INFO blockmanagement.DatanodeDescriptor: Adding new storage ID DS-2231bedb-b4f2-4fed-a7a5-eb471f5c1038 for DN 172.25.0.3:50010
namenode_1 | 19/08/19 08:12:28 INFO BlockStateChange: BLOCK* processReport 0x28a727489c94aaa1: Processing first storage report for DS-2231bedb-b4f2-4fed-a7a5-eb471f5c1038 from datanode 103d9f99-03df-4094-b6d7-c983b6e0d428
namenode_1 | 19/08/19 08:12:28 INFO BlockStateChange: BLOCK* processReport 0x28a727489c94aaa1: from storage DS-2231bedb-b4f2-4fed-a7a5-eb471f5c1038 node DatanodeRegistration(172.25.0.3:50010, datanodeUuid=103d9f99-03df-4094-b6d7-c983b6e0d428, infoPort=50075, infoSecurePort=0, ipcPort=50020, storageInfo=lv=-57;cid=CID-89ba773f-c9f0-4b53-836f-c53e8620da1e;nsid=1418625448;c=1566202344544), blocks: 0, hasStaleStorage: false, processing time: 2 msecs, invalidatedBlocks: 0
datanode_1 | 19/08/19 08:12:28 INFO datanode.DataNode: Successfully sent block report 0x28a727489c94aaa1, containing 1 storage report(s), of which we sent 1. The reports had 0 total blocks and used 1 RPC(s). This took 4 msec to generate and 57 msecs for RPC and NN processing. Got back one command: FinalizeCommand/5.
datanode_1 | 19/08/19 08:12:28 INFO datanode.DataNode: Got finalize command for block pool BP-646680128-172.25.0.2-1566202344544
test_1 | INFO Instantiated configuration from '/app/sdks/python/apache_beam/io/hdfs_integration_test/hdfscli.cfg'.
test_1 | INFO Instantiated <InsecureClient(url='http://namenode:50070')>.
test_1 | INFO Uploading 'kinglear.txt' to '/'.
test_1 | DEBUG Resolved path '/' to '/'.
test_1 | INFO Listing '/'.
test_1 | DEBUG Resolved path '/' to '/'.
test_1 | DEBUG Resolved path '/' to '/'.
test_1 | DEBUG Starting new HTTP connection (1): namenode:50070
namenode_1 | Aug 19, 2019 8:13:12 AM com.sun.jersey.api.core.PackagesResourceConfig init
namenode_1 | INFO: Scanning for root resource and provider classes in the packages:
namenode_1 | org.apache.hadoop.hdfs.server.namenode.web.resources
namenode_1 | org.apache.hadoop.hdfs.web.resources
namenode_1 | Aug 19, 2019 8:13:13 AM com.sun.jersey.api.core.ScanningResourceConfig logClasses
namenode_1 | INFO: Root resource classes found:
namenode_1 | class org.apache.hadoop.hdfs.server.namenode.web.resources.NamenodeWebHdfsMethods
namenode_1 | Aug 19, 2019 8:13:13 AM com.sun.jersey.api.core.ScanningResourceConfig logClasses
namenode_1 | INFO: Provider classes found:
namenode_1 | class org.apache.hadoop.hdfs.web.resources.UserProvider
namenode_1 | class org.apache.hadoop.hdfs.web.resources.ExceptionHandler
namenode_1 | Aug 19, 2019 8:13:13 AM com.sun.jersey.server.impl.application.WebApplicationImpl _initiate
namenode_1 | INFO: Initiating Jersey application, version 'Jersey: 1.9 09/02/2011 11:17 AM'
namenode_1 | Aug 19, 2019 8:13:14 AM com.sun.jersey.spi.inject.Errors processErrorMessages
namenode_1 | WARNING: The following warnings have been detected with resource and/or provider classes:
namenode_1 | WARNING: A sub-resource method, public javax.ws.rs.core.Response org.apache.hadoop.hdfs.server.namenode.web.resources.NamenodeWebHdfsMethods.deleteRoot(org.apache.hadoop.security.UserGroupInformation,org.apache.hadoop.hdfs.web.resources.DelegationParam,org.apache.hadoop.hdfs.web.resources.UserParam,org.apache.hadoop.hdfs.web.resources.DoAsParam,org.apache.hadoop.hdfs.web.resources.DeleteOpParam,org.apache.hadoop.hdfs.web.resources.RecursiveParam,org.apache.hadoop.hdfs.web.resources.SnapshotNameParam) throws java.io.IOException,java.lang.InterruptedException, with URI template, "/", is treated as a resource method
namenode_1 | WARNING: A sub-resource method, public javax.ws.rs.core.Response org.apache.hadoop.hdfs.server.namenode.web.resources.NamenodeWebHdfsMethods.putRoot(org.apache.hadoop.security.UserGroupInformation,org.apache.hadoop.hdfs.web.resources.DelegationParam,org.apache.hadoop.hdfs.web.resources.UserParam,org.apache.hadoop.hdfs.web.resources.DoAsParam,org.apache.hadoop.hdfs.web.resources.PutOpParam,org.apache.hadoop.hdfs.web.resources.DestinationParam,org.apache.hadoop.hdfs.web.resources.OwnerParam,org.apache.hadoop.hdfs.web.resources.GroupParam,org.apache.hadoop.hdfs.web.resources.PermissionParam,org.apache.hadoop.hdfs.web.resources.OverwriteParam,org.apache.hadoop.hdfs.web.resources.BufferSizeParam,org.apache.hadoop.hdfs.web.resources.ReplicationParam,org.apache.hadoop.hdfs.web.resources.BlockSizeParam,org.apache.hadoop.hdfs.web.resources.ModificationTimeParam,org.apache.hadoop.hdfs.web.resources.AccessTimeParam,org.apache.hadoop.hdfs.web.resources.RenameOptionSetParam,org.apache.hadoop.hdfs.web.resources.CreateParentParam,org.apache.hadoop.hdfs.web.resources.TokenArgumentParam,org.apache.hadoop.hdfs.web.resources.AclPermissionParam,org.apache.hadoop.hdfs.web.resources.XAttrNameParam,org.apache.hadoop.hdfs.web.resources.XAttrValueParam,org.apache.hadoop.hdfs.web.resources.XAttrSetFlagParam,org.apache.hadoop.hdfs.web.resources.SnapshotNameParam,org.apache.hadoop.hdfs.web.resources.OldSnapshotNameParam,org.apache.hadoop.hdfs.web.resources.ExcludeDatanodesParam,org.apache.hadoop.hdfs.web.resources.CreateFlagParam,org.apache.hadoop.hdfs.web.resources.StoragePolicyParam) throws java.io.IOException,java.lang.InterruptedException, with URI template, "/", is treated as a resource method
namenode_1 | WARNING: A sub-resource method, public javax.ws.rs.core.Response org.apache.hadoop.hdfs.server.namenode.web.resources.NamenodeWebHdfsMethods.postRoot(org.apache.hadoop.security.UserGroupInformation,org.apache.hadoop.hdfs.web.resources.DelegationParam,org.apache.hadoop.hdfs.web.resources.UserParam,org.apache.hadoop.hdfs.web.resources.DoAsParam,org.apache.hadoop.hdfs.web.resources.PostOpParam,org.apache.hadoop.hdfs.web.resources.ConcatSourcesParam,org.apache.hadoop.hdfs.web.resources.BufferSizeParam,org.apache.hadoop.hdfs.web.resources.ExcludeDatanodesParam,org.apache.hadoop.hdfs.web.resources.NewLengthParam) throws java.io.IOException,java.lang.InterruptedException, with URI template, "/", is treated as a resource method
namenode_1 | WARNING: A sub-resource method, public javax.ws.rs.core.Response org.apache.hadoop.hdfs.server.namenode.web.resources.NamenodeWebHdfsMethods.getRoot(org.apache.hadoop.security.UserGroupInformation,org.apache.hadoop.hdfs.web.resources.DelegationParam,org.apache.hadoop.hdfs.web.resources.UserParam,org.apache.hadoop.hdfs.web.resources.DoAsParam,org.apache.hadoop.hdfs.web.resources.GetOpParam,org.apache.hadoop.hdfs.web.resources.OffsetParam,org.apache.hadoop.hdfs.web.resources.LengthParam,org.apache.hadoop.hdfs.web.resources.RenewerParam,org.apache.hadoop.hdfs.web.resources.BufferSizeParam,java.util.List,org.apache.hadoop.hdfs.web.resources.XAttrEncodingParam,org.apache.hadoop.hdfs.web.resources.ExcludeDatanodesParam,org.apache.hadoop.hdfs.web.resources.FsActionParam,org.apache.hadoop.hdfs.web.resources.TokenKindParam,org.apache.hadoop.hdfs.web.resources.TokenServiceParam) throws java.io.IOException,java.lang.InterruptedException, with URI template, "/", is treated as a resource method
test_1 | DEBUG http://namenode:50070 "GET /webhdfs/v1/?user.name=root&op=LISTSTATUS HTTP/1.1" 200 None
test_1 | DEBUG Uploading 1 files using 1 thread(s).
test_1 | DEBUG Uploading 'kinglear.txt' to '/kinglear.txt'.
test_1 | INFO Writing to '/kinglear.txt'.
test_1 | DEBUG Resolved path '/kinglear.txt' to '/kinglear.txt'.
test_1 | DEBUG http://namenode:50070 "PUT /webhdfs/v1/kinglear.txt?user.name=root&overwrite=True&op=CREATE HTTP/1.1" 307 0
test_1 | DEBUG Starting new HTTP connection (1): datanode:50075
datanode_1 | 19/08/19 08:13:14 INFO datanode.webhdfs: 172.25.0.4 PUT /webhdfs/v1/kinglear.txt?op=CREATE&user.name=root&namenoderpcaddress=namenode:8020&createflag=&createparent=true&overwrite=true&user.name=root 201
namenode_1 | 19/08/19 08:13:14 INFO hdfs.StateChange: BLOCK* allocate blk_1073741825_1001, replicas=172.25.0.3:50010 for /kinglear.txt
datanode_1 | 19/08/19 08:13:15 INFO datanode.DataNode: Receiving BP-646680128-172.25.0.2-1566202344544:blk_1073741825_1001 src: /172.25.0.3:52520 dest: /172.25.0.3:50010
datanode_1 | 19/08/19 08:13:15 INFO DataNode.clienttrace: src: /172.25.0.3:52520, dest: /172.25.0.3:50010, bytes: 157283, op: HDFS_WRITE, cliID: DFSClient_NONMAPREDUCE_-1666803247_67, offset: 0, srvID: 103d9f99-03df-4094-b6d7-c983b6e0d428, blockid: BP-646680128-172.25.0.2-1566202344544:blk_1073741825_1001, duration: 17576686
datanode_1 | 19/08/19 08:13:15 INFO datanode.DataNode: PacketResponder: BP-646680128-172.25.0.2-1566202344544:blk_1073741825_1001, type=LAST_IN_PIPELINE terminating
namenode_1 | 19/08/19 08:13:15 INFO namenode.FSNamesystem: BLOCK* blk_1073741825_1001 is COMMITTED but not COMPLETE(numNodes= 0 < minimum = 1) in file /kinglear.txt
namenode_1 | 19/08/19 08:13:15 INFO namenode.EditLogFileOutputStream: Nothing to flush
namenode_1 | 19/08/19 08:13:15 INFO hdfs.StateChange: DIR* completeFile: /kinglear.txt is closed by DFSClient_NONMAPREDUCE_-1666803247_67
test_1 | DEBUG Upload of 'kinglear.txt' to '/kinglear.txt' complete.
test_1 | /usr/local/lib/python3.7/site-packages/apache_beam/__init__.py:84: UserWarning: Some syntactic constructs of Python 3 are not yet fully supported by Apache Beam.
test_1 | 'Some syntactic constructs of Python 3 are not yet fully supported by '
test_1 | INFO:root:Missing pipeline option (runner). Executing pipeline using the default runner: DirectRunner.
test_1 | INFO:root:==================== <function annotate_downstream_side_inputs at 0x7efea6674170> ====================
test_1 | INFO:root:==================== <function fix_side_input_pcoll_coders at 0x7efea6674290> ====================
test_1 | INFO:root:==================== <function lift_combiners at 0x7efea6674320> ====================
test_1 | INFO:root:==================== <function expand_sdf at 0x7efea66743b0> ====================
test_1 | INFO:root:==================== <function expand_gbk at 0x7efea6674440> ====================
test_1 | INFO:root:==================== <function sink_flattens at 0x7efea6674560> ====================
test_1 | INFO:root:==================== <function greedily_fuse at 0x7efea66745f0> ====================
test_1 | INFO:root:==================== <function read_to_impulse at 0x7efea6674680> ====================
test_1 | INFO:root:==================== <function impulse_to_input at 0x7efea6674710> ====================
test_1 | INFO:root:==================== <function inject_timer_pcollections at 0x7efea66748c0> ====================
test_1 | INFO:root:==================== <function sort_stages at 0x7efea6674950> ====================
test_1 | INFO:root:==================== <function window_pcollection_coders at 0x7efea66749e0> ====================
test_1 | INFO:root:Running (((ref_AppliedPTransform_write/Write/WriteImpl/DoOnce/Read_16)+(ref_AppliedPTransform_write/Write/WriteImpl/InitializeWrite_17))+(ref_PCollection_PCollection_9/Write))+(ref_PCollection_PCollection_10/Write)
test_1 | INFO:root:Running (((ref_AppliedPTransform_read/Read_3)+(ref_AppliedPTransform_split_4))+(ref_AppliedPTransform_pair_with_one_5))+(group/Write)
datanode_1 | 19/08/19 08:13:18 INFO datanode.webhdfs: 172.25.0.4 GET /webhdfs/v1/kinglear.txt?op=OPEN&user.name=root&namenoderpcaddress=namenode:8020&length=157284&offset=0 200
test_1 | INFO:root:Running ((((((group/Read)+(ref_AppliedPTransform_count_10))+(ref_AppliedPTransform_format_11))+(ref_AppliedPTransform_write/Write/WriteImpl/WriteBundles_18))+(ref_AppliedPTransform_write/Write/WriteImpl/Pair_19))+(ref_AppliedPTransform_write/Write/WriteImpl/WindowInto(WindowIntoFn)_20))+(write/Write/WriteImpl/GroupByKey/Write)
test_1 | WARNING:root:Mime types are not supported. Got non-default mime_type: text/plain
datanode_1 | 19/08/19 08:13:21 INFO datanode.webhdfs: 172.25.0.4 PUT /webhdfs/v1/beam-temp-py-wordcount-integration-33b7f752c25911e98ab80242ac190004/a2fe14bb-7b72-4a39-8fbc-5a08f7520bc3.py-wordcount-integration?op=CREATE&user.name=root&namenoderpcaddress=namenode:8020&createflag=&createparent=true&overwrite=false&user.name=root 201
namenode_1 | 19/08/19 08:13:21 INFO hdfs.StateChange: BLOCK* allocate blk_1073741826_1002, replicas=172.25.0.3:50010 for /beam-temp-py-wordcount-integration-33b7f752c25911e98ab80242ac190004/a2fe14bb-7b72-4a39-8fbc-5a08f7520bc3.py-wordcount-integration
datanode_1 | 19/08/19 08:13:21 INFO datanode.DataNode: Receiving BP-646680128-172.25.0.2-1566202344544:blk_1073741826_1002 src: /172.25.0.3:52566 dest: /172.25.0.3:50010
datanode_1 | 19/08/19 08:13:21 INFO DataNode.clienttrace: src: /172.25.0.3:52566, dest: /172.25.0.3:50010, bytes: 48944, op: HDFS_WRITE, cliID: DFSClient_NONMAPREDUCE_-967481781_69, offset: 0, srvID: 103d9f99-03df-4094-b6d7-c983b6e0d428, blockid: BP-646680128-172.25.0.2-1566202344544:blk_1073741826_1002, duration: 4597042
datanode_1 | 19/08/19 08:13:21 INFO datanode.DataNode: PacketResponder: BP-646680128-172.25.0.2-1566202344544:blk_1073741826_1002, type=LAST_IN_PIPELINE terminating
namenode_1 | 19/08/19 08:13:21 INFO hdfs.StateChange: DIR* completeFile: /beam-temp-py-wordcount-integration-33b7f752c25911e98ab80242ac190004/a2fe14bb-7b72-4a39-8fbc-5a08f7520bc3.py-wordcount-integration is closed by DFSClient_NONMAPREDUCE_-967481781_69
test_1 | INFO:root:Running ((write/Write/WriteImpl/GroupByKey/Read)+(ref_AppliedPTransform_write/Write/WriteImpl/Extract_25))+(ref_PCollection_PCollection_17/Write)
test_1 | INFO:root:Running ((ref_PCollection_PCollection_9/Read)+(ref_AppliedPTransform_write/Write/WriteImpl/PreFinalize_26))+(ref_PCollection_PCollection_18/Write)
test_1 | INFO:root:Running (ref_PCollection_PCollection_9/Read)+(ref_AppliedPTransform_write/Write/WriteImpl/FinalizeWrite_27)
test_1 | INFO:root:Starting finalize_write threads with num_shards: 1 (skipped: 0), batches: 1, num_threads: 1
test_1 | INFO:root:Renamed 1 shards in 0.16 seconds.
test_1 | INFO:root:number of empty lines: 1663
test_1 | INFO:root:average word length: 4
hdfs_it-jenkins-beam_postcommit_python37-254_test_1 exited with code 0
Stopping hdfs_it-jenkins-beam_postcommit_python37-254_datanode_1 ...
Stopping hdfs_it-jenkins-beam_postcommit_python37-254_namenode_1 ...
Stopping hdfs_it-jenkins-beam_postcommit_python37-254_datanode_1 ... done
Stopping hdfs_it-jenkins-beam_postcommit_python37-254_namenode_1 ... done
Aborting on container exit...
real 1m33.337s
user 0m1.240s
sys 0m0.138s
+ finally
+ docker-compose -p hdfs_IT-jenkins-beam_PostCommit_Python37-254 --no-ansi down
Removing hdfs_it-jenkins-beam_postcommit_python37-254_test_1 ...
Removing hdfs_it-jenkins-beam_postcommit_python37-254_datanode_1 ...
Removing hdfs_it-jenkins-beam_postcommit_python37-254_namenode_1 ...
Removing hdfs_it-jenkins-beam_postcommit_python37-254_datanode_1 ... done
Removing hdfs_it-jenkins-beam_postcommit_python37-254_test_1 ... done
Removing hdfs_it-jenkins-beam_postcommit_python37-254_namenode_1 ... done
Removing network hdfs_it-jenkins-beam_postcommit_python37-254_test_net
real 0m0.800s
user 0m0.615s
sys 0m0.107s
> Task :sdks:python:test-suites:direct:py37:postCommitIT
>>> RUNNING integration tests with pipeline options: --runner=TestDirectRunner --project=apache-beam-testing --staging_location=gs://temp-storage-for-end-to-end-tests/staging-it --temp_location=gs://temp-storage-for-end-to-end-tests/temp-it --output=gs://temp-storage-for-end-to-end-tests/py-it-cloud/output --sdk_location=build/apache-beam.tar.gz --requirements_file=postcommit_requirements.txt --num_workers=1 --sleep_secs=20 --kms_key_name=projects/apache-beam-testing/locations/global/keyRings/beam-it/cryptoKeys/test --dataflow_kms_key=projects/apache-beam-testing/locations/global/keyRings/beam-it/cryptoKeys/test
>>> test options: --tests=apache_beam.examples.wordcount_it_test:WordCountIT.test_wordcount_it,apache_beam.io.gcp.pubsub_integration_test:PubSubIntegrationTest,apache_beam.io.gcp.big_query_query_to_table_it_test:BigQueryQueryToTableIT,apache_beam.io.gcp.bigquery_io_read_it_test,apache_beam.io.gcp.bigquery_read_it_test,apache_beam.io.gcp.bigquery_write_it_test,apache_beam.io.gcp.datastore.v1new.datastore_write_it_test --nocapture --processes=8 --process-timeout=4500
running nosetests
running egg_info
writing apache_beam.egg-info/PKG-INFO
writing dependency_links to apache_beam.egg-info/dependency_links.txt
writing entry points to apache_beam.egg-info/entry_points.txt
writing requirements to apache_beam.egg-info/requires.txt
writing top-level names to apache_beam.egg-info/top_level.txt
reading manifest file 'apache_beam.egg-info/SOURCES.txt'
reading manifest template 'MANIFEST.in'
setup.py:179: UserWarning: Some syntactic constructs of Python 3 are not yet fully supported by Apache Beam.
'Some syntactic constructs of Python 3 are not yet fully supported by '
<https://builds.apache.org/job/beam_PostCommit_Python37/ws/src/build/gradleenv/1398941891/lib/python3.7/site-packages/setuptools/dist.py>:474: UserWarning: Normalizing '2.16.0.dev' to '2.16.0.dev0'
normalized_version,
warning: no files found matching 'README.md'
warning: no files found matching 'NOTICE'
warning: no files found matching 'LICENSE'
writing manifest file 'apache_beam.egg-info/SOURCES.txt'
<https://builds.apache.org/job/beam_PostCommit_Python37/ws/src/sdks/python/apache_beam/__init__.py>:84: UserWarning: Some syntactic constructs of Python 3 are not yet fully supported by Apache Beam.
'Some syntactic constructs of Python 3 are not yet fully supported by '
<https://builds.apache.org/job/beam_PostCommit_Python37/ws/src/sdks/python/apache_beam/io/gcp/bigquery.py>:1142: BeamDeprecationWarning: options is deprecated since First stable release. References to <pipeline>.options will not be supported
self.table_reference.projectId = pcoll.pipeline.options.view_as(
<https://builds.apache.org/job/beam_PostCommit_Python37/ws/src/sdks/python/apache_beam/io/gcp/bigquery.py>:1142: BeamDeprecationWarning: options is deprecated since First stable release. References to <pipeline>.options will not be supported
self.table_reference.projectId = pcoll.pipeline.options.view_as(
<https://builds.apache.org/job/beam_PostCommit_Python37/ws/src/sdks/python/apache_beam/io/gcp/bigquery_file_loads.py>:642: BeamDeprecationWarning: options is deprecated since First stable release. References to <pipeline>.options will not be supported
temp_location = p.options.view_as(GoogleCloudOptions).temp_location
test_bigquery_read_1M_python (apache_beam.io.gcp.bigquery_io_read_it_test.BigqueryIOReadIT) ... ok
test_datastore_write_limit (apache_beam.io.gcp.datastore.v1new.datastore_write_it_test.DatastoreWriteIT) ... ok
test_big_query_read (apache_beam.io.gcp.bigquery_read_it_test.BigQueryReadIntegrationTests) ... ok
test_big_query_read_new_types (apache_beam.io.gcp.bigquery_read_it_test.BigQueryReadIntegrationTests) ... ok
test_streaming_data_only (apache_beam.io.gcp.pubsub_integration_test.PubSubIntegrationTest) ... ok
test_streaming_with_attributes (apache_beam.io.gcp.pubsub_integration_test.PubSubIntegrationTest) ... ok
test_big_query_write (apache_beam.io.gcp.bigquery_write_it_test.BigQueryWriteIntegrationTests) ... ok
test_big_query_write_new_types (apache_beam.io.gcp.bigquery_write_it_test.BigQueryWriteIntegrationTests) ... ok
test_big_query_write_schema_autodetect (apache_beam.io.gcp.bigquery_write_it_test.BigQueryWriteIntegrationTests) ... ok
test_big_query_write_without_schema (apache_beam.io.gcp.bigquery_write_it_test.BigQueryWriteIntegrationTests) ... ok
test_big_query_legacy_sql (apache_beam.io.gcp.big_query_query_to_table_it_test.BigQueryQueryToTableIT) ... ok
test_big_query_new_types (apache_beam.io.gcp.big_query_query_to_table_it_test.BigQueryQueryToTableIT) ... ok
test_big_query_standard_sql (apache_beam.io.gcp.big_query_query_to_table_it_test.BigQueryQueryToTableIT) ... ok
test_big_query_standard_sql_kms_key_native (apache_beam.io.gcp.big_query_query_to_table_it_test.BigQueryQueryToTableIT) ... SKIP: This test doesn't work on DirectRunner.
test_wordcount_it (apache_beam.examples.wordcount_it_test.WordCountIT) ... ok
----------------------------------------------------------------------
XML: nosetests-postCommitIT-direct-py37.xml
----------------------------------------------------------------------
XML: <https://builds.apache.org/job/beam_PostCommit_Python37/ws/src/sdks/python/nosetests.xml>
----------------------------------------------------------------------
Ran 15 tests in 23.850s
OK (SKIP=1)
FAILURE: Build failed with an exception.
* What went wrong:
Execution failed for task ':sdks:python:test-suites:dataflow:py37:setupVirtualenv'.
> Process 'command 'sh'' finished with non-zero exit value 1
* Try:
Run with --stacktrace option to get the stack trace. Run with --info or --debug option to get more log output. Run with --scan to get full insights.
* Get more help at https://help.gradle.org
Deprecated Gradle features were used in this build, making it incompatible with Gradle 6.0.
Use '--warning-mode all' to show the individual deprecation warnings.
See https://docs.gradle.org/5.2.1/userguide/command_line_interface.html#sec:command_line_warnings
BUILD FAILED in 4m 30s
63 actionable tasks: 46 executed, 17 from cache
Publishing build scan...
https://gradle.com/s/v4akdr7nnm7g4
Build step 'Invoke Gradle script' changed build result to FAILURE
Build step 'Invoke Gradle script' marked build as failure
---------------------------------------------------------------------
To unsubscribe, e-mail: builds-unsubscribe@beam.apache.org
For additional commands, e-mail: builds-help@beam.apache.org