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/12/26 19:53:59 UTC
Build failed in Jenkins: beam_PostCommit_Python37 #1228
See <https://builds.apache.org/job/beam_PostCommit_Python37/1228/display/redirect?page=changes>
Changes:
[bhulette] [BEAM-9026] Clean up RuntimeValueProvider.runtime_options (#10457)
------------------------------------------
[...truncated 2.79 MB...]
namenode_1 | 19/12/26 19:52:12 INFO ipc.CallQueueManager: Using callQueue: class java.util.concurrent.LinkedBlockingQueue queueCapacity: 1000 scheduler: class org.apache.hadoop.ipc.DefaultRpcScheduler
namenode_1 | 19/12/26 19:52:12 INFO ipc.Server: Starting Socket Reader #1 for port 8020
namenode_1 | 19/12/26 19:52:12 INFO namenode.FSNamesystem: Registered FSNamesystemState MBean
namenode_1 | 19/12/26 19:52:12 INFO namenode.LeaseManager: Number of blocks under construction: 0
namenode_1 | 19/12/26 19:52:12 INFO blockmanagement.BlockManager: initializing replication queues
namenode_1 | 19/12/26 19:52:12 INFO hdfs.StateChange: STATE* Leaving safe mode after 0 secs
namenode_1 | 19/12/26 19:52:12 INFO hdfs.StateChange: STATE* Network topology has 0 racks and 0 datanodes
namenode_1 | 19/12/26 19:52:12 INFO hdfs.StateChange: STATE* UnderReplicatedBlocks has 0 blocks
namenode_1 | 19/12/26 19:52:12 INFO blockmanagement.BlockManager: Total number of blocks = 0
namenode_1 | 19/12/26 19:52:12 INFO blockmanagement.BlockManager: Number of invalid blocks = 0
namenode_1 | 19/12/26 19:52:12 INFO blockmanagement.BlockManager: Number of under-replicated blocks = 0
namenode_1 | 19/12/26 19:52:12 INFO blockmanagement.BlockManager: Number of over-replicated blocks = 0
namenode_1 | 19/12/26 19:52:12 INFO blockmanagement.BlockManager: Number of blocks being written = 0
namenode_1 | 19/12/26 19:52:12 INFO hdfs.StateChange: STATE* Replication Queue initialization scan for invalid, over- and under-replicated blocks completed in 16 msec
namenode_1 | 19/12/26 19:52:12 INFO ipc.Server: IPC Server Responder: starting
namenode_1 | 19/12/26 19:52:12 INFO ipc.Server: IPC Server listener on 8020: starting
namenode_1 | 19/12/26 19:52:12 INFO namenode.NameNode: NameNode RPC up at: namenode/172.30.0.2:8020
namenode_1 | 19/12/26 19:52:12 INFO namenode.FSNamesystem: Starting services required for active state
namenode_1 | 19/12/26 19:52:12 INFO namenode.FSDirectory: Initializing quota with 4 thread(s)
namenode_1 | 19/12/26 19:52:12 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/12/26 19:52:12 INFO blockmanagement.CacheReplicationMonitor: Starting CacheReplicationMonitor with interval 30000 milliseconds
datanode_1 | 19/12/26 19:52:12 INFO ipc.Client: Retrying connect to server: namenode/172.30.0.2:8020. Already tried 0 time(s); retry policy is RetryUpToMaximumCountWithFixedSleep(maxRetries=10, sleepTime=1000 MILLISECONDS)
datanode_1 | 19/12/26 19:52:12 INFO datanode.DataNode: Acknowledging ACTIVE Namenode during handshakeBlock pool <registering> (Datanode Uuid unassigned) service to namenode/172.30.0.2:8020
datanode_1 | 19/12/26 19:52:12 INFO common.Storage: Using 1 threads to upgrade data directories (dfs.datanode.parallel.volumes.load.threads.num=1, dataDirs=1)
datanode_1 | 19/12/26 19:52:12 INFO common.Storage: Lock on /hadoop/dfs/data/in_use.lock acquired by nodename 82@datanode
datanode_1 | 19/12/26 19:52:12 INFO common.Storage: Storage directory /hadoop/dfs/data is not formatted for namespace 2046694006. Formatting...
datanode_1 | 19/12/26 19:52:12 INFO common.Storage: Generated new storageID DS-f62eda2d-6c1f-4ae4-8175-1e2615cab340 for directory /hadoop/dfs/data
datanode_1 | 19/12/26 19:52:13 INFO common.Storage: Analyzing storage directories for bpid BP-2064555177-172.30.0.2-1577389928734
datanode_1 | 19/12/26 19:52:13 INFO common.Storage: Locking is disabled for /hadoop/dfs/data/current/BP-2064555177-172.30.0.2-1577389928734
datanode_1 | 19/12/26 19:52:13 INFO common.Storage: Block pool storage directory /hadoop/dfs/data/current/BP-2064555177-172.30.0.2-1577389928734 is not formatted for BP-2064555177-172.30.0.2-1577389928734. Formatting ...
datanode_1 | 19/12/26 19:52:13 INFO common.Storage: Formatting block pool BP-2064555177-172.30.0.2-1577389928734 directory /hadoop/dfs/data/current/BP-2064555177-172.30.0.2-1577389928734/current
datanode_1 | 19/12/26 19:52:13 INFO datanode.DataNode: Setting up storage: nsid=2046694006;bpid=BP-2064555177-172.30.0.2-1577389928734;lv=-57;nsInfo=lv=-63;cid=CID-a20c0c1f-6808-49ae-a83e-99968570bb18;nsid=2046694006;c=1577389928734;bpid=BP-2064555177-172.30.0.2-1577389928734;dnuuid=null
datanode_1 | 19/12/26 19:52:13 INFO datanode.DataNode: Generated and persisted new Datanode UUID 5e673f87-b2b1-423d-96df-657b94e85714
datanode_1 | 19/12/26 19:52:13 INFO impl.FsDatasetImpl: Added new volume: DS-f62eda2d-6c1f-4ae4-8175-1e2615cab340
datanode_1 | 19/12/26 19:52:13 INFO impl.FsDatasetImpl: Added volume - /hadoop/dfs/data/current, StorageType: DISK
datanode_1 | 19/12/26 19:52:13 INFO impl.FsDatasetImpl: Registered FSDatasetState MBean
datanode_1 | 19/12/26 19:52:13 INFO impl.FsDatasetImpl: Volume reference is released.
datanode_1 | 19/12/26 19:52:13 INFO impl.FsDatasetImpl: Adding block pool BP-2064555177-172.30.0.2-1577389928734
datanode_1 | 19/12/26 19:52:13 INFO impl.FsDatasetImpl: Scanning block pool BP-2064555177-172.30.0.2-1577389928734 on volume /hadoop/dfs/data/current...
datanode_1 | 19/12/26 19:52:13 INFO impl.FsDatasetImpl: Time taken to scan block pool BP-2064555177-172.30.0.2-1577389928734 on /hadoop/dfs/data/current: 17ms
datanode_1 | 19/12/26 19:52:13 INFO impl.FsDatasetImpl: Total time to scan all replicas for block pool BP-2064555177-172.30.0.2-1577389928734: 19ms
datanode_1 | 19/12/26 19:52:13 INFO impl.FsDatasetImpl: Adding replicas to map for block pool BP-2064555177-172.30.0.2-1577389928734 on volume /hadoop/dfs/data/current...
datanode_1 | 19/12/26 19:52:13 INFO impl.BlockPoolSlice: Replica Cache file: /hadoop/dfs/data/current/BP-2064555177-172.30.0.2-1577389928734/current/replicas doesn't exist
datanode_1 | 19/12/26 19:52:13 INFO impl.FsDatasetImpl: Time to add replicas to map for block pool BP-2064555177-172.30.0.2-1577389928734 on volume /hadoop/dfs/data/current: 1ms
datanode_1 | 19/12/26 19:52:13 INFO impl.FsDatasetImpl: Total time to add all replicas to map: 2ms
datanode_1 | 19/12/26 19:52:13 INFO datanode.VolumeScanner: Now scanning bpid BP-2064555177-172.30.0.2-1577389928734 on volume /hadoop/dfs/data
datanode_1 | 19/12/26 19:52:13 INFO datanode.VolumeScanner: VolumeScanner(/hadoop/dfs/data, DS-f62eda2d-6c1f-4ae4-8175-1e2615cab340): finished scanning block pool BP-2064555177-172.30.0.2-1577389928734
datanode_1 | 19/12/26 19:52:13 INFO datanode.DirectoryScanner: Periodic Directory Tree Verification scan starting at 12/27/19 1:10 AM with interval of 21600000ms
datanode_1 | 19/12/26 19:52:13 INFO datanode.DataNode: Block pool BP-2064555177-172.30.0.2-1577389928734 (Datanode Uuid 5e673f87-b2b1-423d-96df-657b94e85714) service to namenode/172.30.0.2:8020 beginning handshake with NN
datanode_1 | 19/12/26 19:52:13 INFO datanode.VolumeScanner: VolumeScanner(/hadoop/dfs/data, DS-f62eda2d-6c1f-4ae4-8175-1e2615cab340): no suitable block pools found to scan. Waiting 1814399952 ms.
namenode_1 | 19/12/26 19:52:13 INFO hdfs.StateChange: BLOCK* registerDatanode: from DatanodeRegistration(172.30.0.3:50010, datanodeUuid=5e673f87-b2b1-423d-96df-657b94e85714, infoPort=50075, infoSecurePort=0, ipcPort=50020, storageInfo=lv=-57;cid=CID-a20c0c1f-6808-49ae-a83e-99968570bb18;nsid=2046694006;c=1577389928734) storage 5e673f87-b2b1-423d-96df-657b94e85714
namenode_1 | 19/12/26 19:52:13 INFO net.NetworkTopology: Adding a new node: /default-rack/172.30.0.3:50010
namenode_1 | 19/12/26 19:52:13 INFO blockmanagement.BlockReportLeaseManager: Registered DN 5e673f87-b2b1-423d-96df-657b94e85714 (172.30.0.3:50010).
datanode_1 | 19/12/26 19:52:13 INFO datanode.DataNode: Block pool Block pool BP-2064555177-172.30.0.2-1577389928734 (Datanode Uuid 5e673f87-b2b1-423d-96df-657b94e85714) service to namenode/172.30.0.2:8020 successfully registered with NN
datanode_1 | 19/12/26 19:52:13 INFO datanode.DataNode: For namenode namenode/172.30.0.2:8020 using BLOCKREPORT_INTERVAL of 21600000msec CACHEREPORT_INTERVAL of 10000msec Initial delay: 0msec; heartBeatInterval=3000
namenode_1 | 19/12/26 19:52:13 INFO blockmanagement.DatanodeDescriptor: Adding new storage ID DS-f62eda2d-6c1f-4ae4-8175-1e2615cab340 for DN 172.30.0.3:50010
namenode_1 | 19/12/26 19:52:13 INFO BlockStateChange: BLOCK* processReport 0x95d2a57b31ceb2e4: Processing first storage report for DS-f62eda2d-6c1f-4ae4-8175-1e2615cab340 from datanode 5e673f87-b2b1-423d-96df-657b94e85714
namenode_1 | 19/12/26 19:52:13 INFO BlockStateChange: BLOCK* processReport 0x95d2a57b31ceb2e4: from storage DS-f62eda2d-6c1f-4ae4-8175-1e2615cab340 node DatanodeRegistration(172.30.0.3:50010, datanodeUuid=5e673f87-b2b1-423d-96df-657b94e85714, infoPort=50075, infoSecurePort=0, ipcPort=50020, storageInfo=lv=-57;cid=CID-a20c0c1f-6808-49ae-a83e-99968570bb18;nsid=2046694006;c=1577389928734), blocks: 0, hasStaleStorage: false, processing time: 2 msecs, invalidatedBlocks: 0
datanode_1 | 19/12/26 19:52:13 INFO datanode.DataNode: Successfully sent block report 0x95d2a57b31ceb2e4, containing 1 storage report(s), of which we sent 1. The reports had 0 total blocks and used 1 RPC(s). This took 5 msec to generate and 63 msecs for RPC and NN processing. Got back one command: FinalizeCommand/5.
datanode_1 | 19/12/26 19:52:13 INFO datanode.DataNode: Got finalize command for block pool BP-2064555177-172.30.0.2-1577389928734
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 | Dec 26, 2019 7:52:57 PM 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 | Dec 26, 2019 7:52:57 PM 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 | Dec 26, 2019 7:52:57 PM 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 | Dec 26, 2019 7:52:57 PM 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 | Dec 26, 2019 7:52:58 PM 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.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.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.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/12/26 19:52:59 INFO datanode.webhdfs: 172.30.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/12/26 19:52:59 INFO hdfs.StateChange: BLOCK* allocate blk_1073741825_1001, replicas=172.30.0.3:50010 for /kinglear.txt
datanode_1 | 19/12/26 19:52:59 INFO datanode.DataNode: Receiving BP-2064555177-172.30.0.2-1577389928734:blk_1073741825_1001 src: /172.30.0.3:36842 dest: /172.30.0.3:50010
datanode_1 | 19/12/26 19:52:59 INFO DataNode.clienttrace: src: /172.30.0.3:36842, dest: /172.30.0.3:50010, bytes: 157283, op: HDFS_WRITE, cliID: DFSClient_NONMAPREDUCE_-644044556_67, offset: 0, srvID: 5e673f87-b2b1-423d-96df-657b94e85714, blockid: BP-2064555177-172.30.0.2-1577389928734:blk_1073741825_1001, duration: 18841692
datanode_1 | 19/12/26 19:52:59 INFO datanode.DataNode: PacketResponder: BP-2064555177-172.30.0.2-1577389928734:blk_1073741825_1001, type=LAST_IN_PIPELINE terminating
namenode_1 | 19/12/26 19:52:59 INFO namenode.FSNamesystem: BLOCK* blk_1073741825_1001 is COMMITTED but not COMPLETE(numNodes= 0 < minimum = 1) in file /kinglear.txt
namenode_1 | 19/12/26 19:52:59 INFO namenode.EditLogFileOutputStream: Nothing to flush
namenode_1 | 19/12/26 19:52:59 INFO hdfs.StateChange: DIR* completeFile: /kinglear.txt is closed by DFSClient_NONMAPREDUCE_-644044556_67
test_1 | DEBUG Upload of 'kinglear.txt' to '/kinglear.txt' complete.
test_1 | INFO:root:Missing pipeline option (runner). Executing pipeline using the default runner: DirectRunner.
test_1 | INFO:apache_beam.runners.portability.fn_api_runner_transforms:==================== <function annotate_downstream_side_inputs at 0x7ff106882950> ====================
test_1 | INFO:apache_beam.runners.portability.fn_api_runner_transforms:==================== <function fix_side_input_pcoll_coders at 0x7ff106882a70> ====================
test_1 | INFO:apache_beam.runners.portability.fn_api_runner_transforms:==================== <function lift_combiners at 0x7ff106882b00> ====================
test_1 | INFO:apache_beam.runners.portability.fn_api_runner_transforms:==================== <function expand_sdf at 0x7ff106882b90> ====================
test_1 | INFO:apache_beam.runners.portability.fn_api_runner_transforms:==================== <function expand_gbk at 0x7ff106882c20> ====================
test_1 | INFO:apache_beam.runners.portability.fn_api_runner_transforms:==================== <function sink_flattens at 0x7ff106882d40> ====================
test_1 | INFO:apache_beam.runners.portability.fn_api_runner_transforms:==================== <function greedily_fuse at 0x7ff106882dd0> ====================
test_1 | INFO:apache_beam.runners.portability.fn_api_runner_transforms:==================== <function read_to_impulse at 0x7ff106882e60> ====================
test_1 | INFO:apache_beam.runners.portability.fn_api_runner_transforms:==================== <function impulse_to_input at 0x7ff106882ef0> ====================
test_1 | INFO:apache_beam.runners.portability.fn_api_runner_transforms:==================== <function inject_timer_pcollections at 0x7ff1068810e0> ====================
test_1 | INFO:apache_beam.runners.portability.fn_api_runner_transforms:==================== <function sort_stages at 0x7ff106881170> ====================
test_1 | INFO:apache_beam.runners.portability.fn_api_runner_transforms:==================== <function window_pcollection_coders at 0x7ff106881200> ====================
test_1 | INFO:apache_beam.runners.worker.statecache:Creating state cache with size 100
test_1 | INFO:apache_beam.runners.portability.fn_api_runner:Created Worker handler <apache_beam.runners.portability.fn_api_runner.EmbeddedWorkerHandler object at 0x7ff10401aa50> for environment urn: "beam:env:embedded_python:v1"
test_1 |
test_1 | INFO:apache_beam.runners.portability.fn_api_runner:Running (((((ref_AppliedPTransform_write/Write/WriteImpl/DoOnce/Impulse_19)+(ref_AppliedPTransform_write/Write/WriteImpl/DoOnce/FlatMap(<lambda at core.py:2570>)_20))+(ref_AppliedPTransform_write/Write/WriteImpl/DoOnce/Map(decode)_22))+(ref_AppliedPTransform_write/Write/WriteImpl/InitializeWrite_23))+(ref_PCollection_PCollection_12/Write))+(ref_PCollection_PCollection_13/Write)
test_1 | INFO:apache_beam.runners.portability.fn_api_runner:Running (((ref_AppliedPTransform_read/Read/_SDFBoundedSourceWrapper/Impulse_5)+(read/Read/_SDFBoundedSourceWrapper/ParDo(SDFBoundedSourceDoFn)/PairWithRestriction))+(read/Read/_SDFBoundedSourceWrapper/ParDo(SDFBoundedSourceDoFn)/SplitAndSizeRestriction))+(ref_PCollection_PCollection_1_split/Write)
test_1 | INFO:apache_beam.runners.portability.fn_api_runner:Running ((((ref_PCollection_PCollection_1_split/Read)+(read/Read/_SDFBoundedSourceWrapper/ParDo(SDFBoundedSourceDoFn)/Process))+(ref_AppliedPTransform_split_7))+(ref_AppliedPTransform_pair_with_one_8))+(group/Write)
datanode_1 | 19/12/26 19:53:02 INFO datanode.webhdfs: 172.30.0.4 GET /webhdfs/v1/kinglear.txt?op=OPEN&user.name=root&namenoderpcaddress=namenode:8020&length=157284&offset=0 200
test_1 | INFO:apache_beam.runners.portability.fn_api_runner:Running ((((((group/Read)+(ref_AppliedPTransform_count_13))+(ref_AppliedPTransform_format_14))+(ref_AppliedPTransform_write/Write/WriteImpl/WriteBundles_24))+(ref_AppliedPTransform_write/Write/WriteImpl/Pair_25))+(ref_AppliedPTransform_write/Write/WriteImpl/WindowInto(WindowIntoFn)_26))+(write/Write/WriteImpl/GroupByKey/Write)
test_1 | WARNING:apache_beam.io.hadoopfilesystem:Mime types are not supported. Got non-default mime_type: text/plain
datanode_1 | 19/12/26 19:53:04 INFO datanode.webhdfs: 172.30.0.4 PUT /webhdfs/v1/beam-temp-py-wordcount-integration-53190dd6281911ea944a0242ac1e0004/f8856ca3-d3ab-4728-b138-011c58129b02.py-wordcount-integration?op=CREATE&user.name=root&namenoderpcaddress=namenode:8020&createflag=&createparent=true&overwrite=false&user.name=root 201
namenode_1 | 19/12/26 19:53:05 INFO hdfs.StateChange: BLOCK* allocate blk_1073741826_1002, replicas=172.30.0.3:50010 for /beam-temp-py-wordcount-integration-53190dd6281911ea944a0242ac1e0004/f8856ca3-d3ab-4728-b138-011c58129b02.py-wordcount-integration
datanode_1 | 19/12/26 19:53:05 INFO datanode.DataNode: Receiving BP-2064555177-172.30.0.2-1577389928734:blk_1073741826_1002 src: /172.30.0.3:36864 dest: /172.30.0.3:50010
datanode_1 | 19/12/26 19:53:05 INFO DataNode.clienttrace: src: /172.30.0.3:36864, dest: /172.30.0.3:50010, bytes: 48944, op: HDFS_WRITE, cliID: DFSClient_NONMAPREDUCE_-695235738_69, offset: 0, srvID: 5e673f87-b2b1-423d-96df-657b94e85714, blockid: BP-2064555177-172.30.0.2-1577389928734:blk_1073741826_1002, duration: 3855136
datanode_1 | 19/12/26 19:53:05 INFO datanode.DataNode: PacketResponder: BP-2064555177-172.30.0.2-1577389928734:blk_1073741826_1002, type=LAST_IN_PIPELINE terminating
namenode_1 | 19/12/26 19:53:05 INFO namenode.FSNamesystem: BLOCK* blk_1073741826_1002 is COMMITTED but not COMPLETE(numNodes= 0 < minimum = 1) in file /beam-temp-py-wordcount-integration-53190dd6281911ea944a0242ac1e0004/f8856ca3-d3ab-4728-b138-011c58129b02.py-wordcount-integration
namenode_1 | 19/12/26 19:53:05 INFO namenode.EditLogFileOutputStream: Nothing to flush
namenode_1 | 19/12/26 19:53:05 INFO hdfs.StateChange: DIR* completeFile: /beam-temp-py-wordcount-integration-53190dd6281911ea944a0242ac1e0004/f8856ca3-d3ab-4728-b138-011c58129b02.py-wordcount-integration is closed by DFSClient_NONMAPREDUCE_-695235738_69
test_1 | INFO:apache_beam.runners.portability.fn_api_runner:Running ((write/Write/WriteImpl/GroupByKey/Read)+(ref_AppliedPTransform_write/Write/WriteImpl/Extract_31))+(ref_PCollection_PCollection_20/Write)
test_1 | INFO:apache_beam.runners.portability.fn_api_runner:Running ((ref_PCollection_PCollection_12/Read)+(ref_AppliedPTransform_write/Write/WriteImpl/PreFinalize_32))+(ref_PCollection_PCollection_21/Write)
test_1 | INFO:apache_beam.runners.portability.fn_api_runner:Running (ref_PCollection_PCollection_12/Read)+(ref_AppliedPTransform_write/Write/WriteImpl/FinalizeWrite_33)
test_1 | INFO:apache_beam.io.filebasedsink:Starting finalize_write threads with num_shards: 1 (skipped: 0), batches: 1, num_threads: 1
test_1 | INFO:apache_beam.io.filebasedsink:Renamed 1 shards in 0.10 seconds.
test_1 | INFO:root:number of empty lines: 1663
test_1 | INFO:root:average word length: 4
hdfs_it-jenkins-beam_postcommit_python37-1228_test_1 exited with code 0
Stopping hdfs_it-jenkins-beam_postcommit_python37-1228_datanode_1 ...
Stopping hdfs_it-jenkins-beam_postcommit_python37-1228_namenode_1 ...
Stopping hdfs_it-jenkins-beam_postcommit_python37-1228_datanode_1 ... done
Stopping hdfs_it-jenkins-beam_postcommit_python37-1228_namenode_1 ... done
Aborting on container exit...
real 1m42.402s
user 0m1.386s
sys 0m0.218s
+ finally
+ docker-compose -p hdfs_IT-jenkins-beam_PostCommit_Python37-1228 --no-ansi down
Removing hdfs_it-jenkins-beam_postcommit_python37-1228_test_1 ...
Removing hdfs_it-jenkins-beam_postcommit_python37-1228_datanode_1 ...
Removing hdfs_it-jenkins-beam_postcommit_python37-1228_namenode_1 ...
Removing hdfs_it-jenkins-beam_postcommit_python37-1228_namenode_1 ... done
Removing hdfs_it-jenkins-beam_postcommit_python37-1228_test_1 ... done
Removing hdfs_it-jenkins-beam_postcommit_python37-1228_datanode_1 ... done
Removing network hdfs_it-jenkins-beam_postcommit_python37-1228_test_net
real 0m0.941s
user 0m0.687s
sys 0m0.131s
> 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'
<https://builds.apache.org/job/beam_PostCommit_Python37/ws/src/build/gradleenv/1398941891/lib/python3.7/site-packages/setuptools/dist.py>:476: UserWarning: Normalizing '2.19.0.dev' to '2.19.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/io/gcp/bigquery.py>:1416: 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_read_it_test.py>:153: FutureWarning: _ReadFromBigQuery is experimental.
query=self.query, use_standard_sql=True, project=self.project))
<https://builds.apache.org/job/beam_PostCommit_Python37/ws/src/sdks/python/apache_beam/io/gcp/bigquery.py>:1603: BeamDeprecationWarning: options is deprecated since First stable release. References to <pipeline>.options will not be supported
temp_location = pcoll.pipeline.options.view_as(
<https://builds.apache.org/job/beam_PostCommit_Python37/ws/src/sdks/python/apache_beam/io/gcp/bigquery_read_it_test.py>:257: FutureWarning: _ReadFromBigQuery is experimental.
query=self.query, use_standard_sql=True, project=self.project))
<https://builds.apache.org/job/beam_PostCommit_Python37/ws/src/sdks/python/apache_beam/io/gcp/bigquery.py>:1603: BeamDeprecationWarning: options is deprecated since First stable release. References to <pipeline>.options will not be supported
temp_location = pcoll.pipeline.options.view_as(
<https://builds.apache.org/job/beam_PostCommit_Python37/ws/src/sdks/python/apache_beam/io/gcp/bigquery.py>:1416: 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>:769: 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_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_iobase_source (apache_beam.io.gcp.bigquery_read_it_test.ReadNewTypesTests) ... ok
test_native_source (apache_beam.io.gcp.bigquery_read_it_test.ReadNewTypesTests) ... 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_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_wordcount_it (apache_beam.examples.wordcount_it_test.WordCountIT) ... ok
test_iobase_source (apache_beam.io.gcp.bigquery_read_it_test.ReadTests) ... ok
test_native_source (apache_beam.io.gcp.bigquery_read_it_test.ReadTests) ... ok
----------------------------------------------------------------------
XML: nosetests-postCommitIT-direct-py37.xml
----------------------------------------------------------------------
XML: <https://builds.apache.org/job/beam_PostCommit_Python37/ws/src/sdks/python/nosetests.xml>
----------------------------------------------------------------------
Ran 17 tests in 24.943s
OK (SKIP=1)
FAILURE: Build failed with an exception.
* What went wrong:
Execution failed for task ':sdks:python:test-suites:dataflow:py37:installGcpTest'.
> 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 6m 28s
84 actionable tasks: 63 executed, 21 from cache
Publishing build scan...
https://gradle.com/s/3yawkuwpljxoa
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
Jenkins build is back to normal : beam_PostCommit_Python37 #1232
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/beam_PostCommit_Python37/1232/display/redirect>
---------------------------------------------------------------------
To unsubscribe, e-mail: builds-unsubscribe@beam.apache.org
For additional commands, e-mail: builds-help@beam.apache.org
beam_PostCommit_Python37 - Build # 1231 - Aborted
Posted by Apache Jenkins Server <je...@builds.apache.org>.
The Apache Jenkins build system has built beam_PostCommit_Python37 (build #1231)
Status: Aborted
Check console output at https://builds.apache.org/job/beam_PostCommit_Python37/1231/ to view the results.
beam_PostCommit_Python37 - Build # 1230 - Aborted
Posted by Apache Jenkins Server <je...@builds.apache.org>.
The Apache Jenkins build system has built beam_PostCommit_Python37 (build #1230)
Status: Aborted
Check console output at https://builds.apache.org/job/beam_PostCommit_Python37/1230/ to view the results.
beam_PostCommit_Python37 - Build # 1229 - Aborted
Posted by Apache Jenkins Server <je...@builds.apache.org>.
The Apache Jenkins build system has built beam_PostCommit_Python37 (build #1229)
Status: Aborted
Check console output at https://builds.apache.org/job/beam_PostCommit_Python37/1229/ to view the results.