See
<https://builds.apache.org/job/beam_PostCommit_Python_VR_Spark/1571/display/redirect?page=changes>
Changes:
[suztomo] [BEAM-8654] Fix resolutionStrategy's interference with dependency
check
[suztomo] [BEAM-8737] Incorporate TRIAGE NEEDED status
[suztomo] Handle None object returned from jira_manager.run
[lukecwik] [BEAM-8729] Gracefully skip irrelevant http/https lines from
[lukecwik] [BEAM-8335] Add timestamp and duration to/from protos to Python SDK
------------------------------------------
[...truncated 1.65 MB...]
19/11/19 17:32:23 INFO run: SDK Harness waiting for in-flight requests to
complete
19/11/19 17:32:23 INFO close: Closing all cached grpc data channels.
19/11/19 17:32:23 INFO close: Closing all cached gRPC state handlers.
19/11/19 17:32:23 WARN BeamFnDataGrpcMultiplexer: Hanged up for unknown
endpoint.
19/11/19 17:32:23 INFO run: Done consuming work.
19/11/19 17:32:23 INFO main: Python sdk harness exiting.
19/11/19 17:32:23 INFO GrpcLoggingService: Logging client hanged up.
19/11/19 17:32:24 WARN BeamFnDataGrpcMultiplexer: Hanged up for unknown
endpoint.
19/11/19 17:32:24 INFO Executor: Finished task 0.0 in stage 126.0 (TID 153).
15272 bytes result sent to driver
19/11/19 17:32:24 INFO TaskSetManager: Starting task 1.0 in stage 126.0 (TID
154, localhost, executor driver, partition 1, PROCESS_LOCAL, 7977 bytes)
19/11/19 17:32:24 INFO Executor: Running task 1.0 in stage 126.0 (TID 154)
19/11/19 17:32:24 INFO TaskSetManager: Finished task 0.0 in stage 126.0 (TID
153) in 935 ms on localhost (executor driver) (1/2)
19/11/19 17:32:24 INFO AbstractArtifactRetrievalService: GetManifest for
/tmp/sparktestsYPCfK/job_317c6739-21f8-413d-9cad-a0f90b1d3a93/MANIFEST
19/11/19 17:32:24 INFO AbstractArtifactRetrievalService: GetManifest for
/tmp/sparktestsYPCfK/job_317c6739-21f8-413d-9cad-a0f90b1d3a93/MANIFEST -> 0
artifacts
19/11/19 17:32:24 INFO GrpcLoggingService: Beam Fn Logging client connected.
19/11/19 17:32:24 INFO main: Logging handler created.
19/11/19 17:32:24 INFO start: Status HTTP server running at localhost:41975
19/11/19 17:32:24 INFO main: semi_persistent_directory: /tmp
19/11/19 17:32:24 WARN _load_main_session: No session file found:
/tmp/staged/pickled_main_session. Functions defined in __main__ (interactive
session) may fail.
19/11/19 17:32:24 WARN get_all_options: Discarding unparseable args:
[u'--job_server_timeout=60',
u'--app_name=test_windowing_1574184740.42_21e78f11-33e8-40f0-aca0-1a905f760674',
u'--direct_runner_use_stacked_bundle', u'--spark_master=local',
u'--options_id=29', u'--enable_spark_metric_sinks', u'--pipeline_type_check']
19/11/19 17:32:24 INFO main: Python sdk harness started with pipeline_options:
{'runner': u'None', 'experiments': [u'beam_fn_api'],
'environment_cache_millis': u'0', 'environment_type': u'PROCESS',
'sdk_location': u'container', 'job_name': u'test_windowing_1574184740.42',
'environment_config': u'{"command":
"<https://builds.apache.org/job/beam_PostCommit_Python_VR_Spark/ws/src/sdks/python/test-suites/portable/py2/build/sdk_worker.sh"}',>
'sdk_worker_parallelism': u'1', 'job_endpoint': u'localhost:55319'}
19/11/19 17:32:24 INFO __init__: Creating state cache with size 0
19/11/19 17:32:24 INFO __init__: Creating insecure control channel for
localhost:42057.
19/11/19 17:32:24 INFO __init__: Control channel established.
19/11/19 17:32:24 INFO FnApiControlClientPoolService: Beam Fn Control client
connected with id 257-1
19/11/19 17:32:24 INFO __init__: Initializing SDKHarness with unbounded number
of workers.
19/11/19 17:32:24 INFO create_state_handler: Creating insecure state channel
for localhost:40177.
19/11/19 17:32:24 INFO create_state_handler: State channel established.
19/11/19 17:32:24 INFO create_data_channel: Creating client data channel for
localhost:34615
19/11/19 17:32:24 INFO GrpcDataService: Beam Fn Data client connected.
19/11/19 17:32:24 INFO DefaultJobBundleFactory: Closing environment urn:
"beam:env:process:v1"
payload:
"\032\202\001<https://builds.apache.org/job/beam_PostCommit_Python_VR_Spark/ws/src/sdks/python/test-suites/portable/py2/build/sdk_worker.sh";>
19/11/19 17:32:24 INFO run: No more requests from control plane
19/11/19 17:32:24 INFO run: SDK Harness waiting for in-flight requests to
complete
19/11/19 17:32:24 INFO close: Closing all cached grpc data channels.
19/11/19 17:32:24 WARN BeamFnDataGrpcMultiplexer: Hanged up for unknown
endpoint.
19/11/19 17:32:24 INFO close: Closing all cached gRPC state handlers.
19/11/19 17:32:24 INFO run: Done consuming work.
19/11/19 17:32:24 INFO main: Python sdk harness exiting.
19/11/19 17:32:24 INFO GrpcLoggingService: Logging client hanged up.
19/11/19 17:32:24 WARN BeamFnDataGrpcMultiplexer: Hanged up for unknown
endpoint.
19/11/19 17:32:24 INFO Executor: Finished task 1.0 in stage 126.0 (TID 154).
13710 bytes result sent to driver
19/11/19 17:32:24 INFO TaskSetManager: Finished task 1.0 in stage 126.0 (TID
154) in 831 ms on localhost (executor driver) (2/2)
19/11/19 17:32:24 INFO TaskSchedulerImpl: Removed TaskSet 126.0, whose tasks
have all completed, from pool
19/11/19 17:32:24 INFO DAGScheduler: ShuffleMapStage 126 (flatMapToPair at
GroupNonMergingWindowsFunctions.java:115) finished in 1.772 s
19/11/19 17:32:24 INFO DAGScheduler: looking for newly runnable stages
19/11/19 17:32:24 INFO DAGScheduler: running: Set()
19/11/19 17:32:24 INFO DAGScheduler: waiting: Set(ResultStage 127)
19/11/19 17:32:24 INFO DAGScheduler: failed: Set()
19/11/19 17:32:24 INFO DAGScheduler: Submitting ResultStage 127
(EmptyOutputSink_0 MapPartitionsRDD[881] at flatMap at
SparkBatchPortablePipelineTranslator.java:311), which has no missing parents
19/11/19 17:32:24 INFO MemoryStore: Block broadcast_124 stored as values in
memory (estimated size 26.1 KB, free 13.5 GB)
19/11/19 17:32:24 INFO MemoryStore: Block broadcast_124_piece0 stored as bytes
in memory (estimated size 12.4 KB, free 13.5 GB)
19/11/19 17:32:24 INFO BlockManagerInfo: Added broadcast_124_piece0 in memory
on localhost:38405 (size: 12.4 KB, free: 13.5 GB)
19/11/19 17:32:24 INFO SparkContext: Created broadcast 124 from broadcast at
DAGScheduler.scala:1161
19/11/19 17:32:24 INFO DAGScheduler: Submitting 1 missing tasks from
ResultStage 127 (EmptyOutputSink_0 MapPartitionsRDD[881] at flatMap at
SparkBatchPortablePipelineTranslator.java:311) (first 15 tasks are for
partitions Vector(0))
19/11/19 17:32:24 INFO TaskSchedulerImpl: Adding task set 127.0 with 1 tasks
19/11/19 17:32:24 INFO TaskSetManager: Starting task 0.0 in stage 127.0 (TID
155, localhost, executor driver, partition 0, NODE_LOCAL, 7662 bytes)
19/11/19 17:32:24 INFO Executor: Running task 0.0 in stage 127.0 (TID 155)
19/11/19 17:32:24 INFO ShuffleBlockFetcherIterator: Getting 2 non-empty blocks
including 2 local blocks and 0 remote blocks
19/11/19 17:32:24 INFO ShuffleBlockFetcherIterator: Started 0 remote fetches in
0 ms
19/11/19 17:32:24 INFO AbstractArtifactRetrievalService: GetManifest for
/tmp/sparktestsYPCfK/job_317c6739-21f8-413d-9cad-a0f90b1d3a93/MANIFEST
19/11/19 17:32:24 INFO AbstractArtifactRetrievalService: GetManifest for
/tmp/sparktestsYPCfK/job_317c6739-21f8-413d-9cad-a0f90b1d3a93/MANIFEST -> 0
artifacts
19/11/19 17:32:25 INFO GrpcLoggingService: Beam Fn Logging client connected.
19/11/19 17:32:25 INFO main: Logging handler created.
19/11/19 17:32:25 INFO start: Status HTTP server running at localhost:36013
19/11/19 17:32:25 INFO main: semi_persistent_directory: /tmp
19/11/19 17:32:25 WARN _load_main_session: No session file found:
/tmp/staged/pickled_main_session. Functions defined in __main__ (interactive
session) may fail.
19/11/19 17:32:25 WARN get_all_options: Discarding unparseable args:
[u'--job_server_timeout=60',
u'--app_name=test_windowing_1574184740.42_21e78f11-33e8-40f0-aca0-1a905f760674',
u'--direct_runner_use_stacked_bundle', u'--spark_master=local',
u'--options_id=29', u'--enable_spark_metric_sinks', u'--pipeline_type_check']
19/11/19 17:32:25 INFO main: Python sdk harness started with pipeline_options:
{'runner': u'None', 'experiments': [u'beam_fn_api'],
'environment_cache_millis': u'0', 'environment_type': u'PROCESS',
'sdk_location': u'container', 'job_name': u'test_windowing_1574184740.42',
'environment_config': u'{"command":
"<https://builds.apache.org/job/beam_PostCommit_Python_VR_Spark/ws/src/sdks/python/test-suites/portable/py2/build/sdk_worker.sh"}',>
'sdk_worker_parallelism': u'1', 'job_endpoint': u'localhost:55319'}
19/11/19 17:32:25 INFO __init__: Creating state cache with size 0
19/11/19 17:32:25 INFO __init__: Creating insecure control channel for
localhost:41763.
19/11/19 17:32:25 INFO __init__: Control channel established.
19/11/19 17:32:25 INFO __init__: Initializing SDKHarness with unbounded number
of workers.
19/11/19 17:32:25 INFO FnApiControlClientPoolService: Beam Fn Control client
connected with id 258-1
19/11/19 17:32:25 INFO create_state_handler: Creating insecure state channel
for localhost:46423.
19/11/19 17:32:25 INFO create_state_handler: State channel established.
19/11/19 17:32:25 INFO create_data_channel: Creating client data channel for
localhost:33235
19/11/19 17:32:25 INFO GrpcDataService: Beam Fn Data client connected.
19/11/19 17:32:25 INFO DefaultJobBundleFactory: Closing environment urn:
"beam:env:process:v1"
payload:
"\032\202\001<https://builds.apache.org/job/beam_PostCommit_Python_VR_Spark/ws/src/sdks/python/test-suites/portable/py2/build/sdk_worker.sh";>
19/11/19 17:32:25 INFO run: No more requests from control plane
19/11/19 17:32:25 INFO run: SDK Harness waiting for in-flight requests to
complete
19/11/19 17:32:25 WARN BeamFnDataGrpcMultiplexer: Hanged up for unknown
endpoint.
19/11/19 17:32:25 INFO close: Closing all cached grpc data channels.
19/11/19 17:32:25 INFO close: Closing all cached gRPC state handlers.
19/11/19 17:32:25 INFO run: Done consuming work.
19/11/19 17:32:25 INFO main: Python sdk harness exiting.
19/11/19 17:32:25 INFO GrpcLoggingService: Logging client hanged up.
19/11/19 17:32:25 WARN BeamFnDataGrpcMultiplexer: Hanged up for unknown
endpoint.
19/11/19 17:32:25 INFO Executor: Finished task 0.0 in stage 127.0 (TID 155).
11970 bytes result sent to driver
19/11/19 17:32:25 INFO TaskSetManager: Finished task 0.0 in stage 127.0 (TID
155) in 865 ms on localhost (executor driver) (1/1)
19/11/19 17:32:25 INFO TaskSchedulerImpl: Removed TaskSet 127.0, whose tasks
have all completed, from pool
19/11/19 17:32:25 INFO DAGScheduler: ResultStage 127 (foreach at
BoundedDataset.java:124) finished in 0.871 s
19/11/19 17:32:25 INFO DAGScheduler: Job 45 finished: foreach at
BoundedDataset.java:124, took 4.313323 s
19/11/19 17:32:25 INFO SparkPipelineRunner: Job
test_windowing_1574184740.42_21e78f11-33e8-40f0-aca0-1a905f760674 finished.
19/11/19 17:32:25 WARN SparkPipelineResult$BatchMode: Collecting monitoring
infos is not implemented yet in Spark portable runner.
19/11/19 17:32:25 INFO AbstractArtifactRetrievalService: Manifest at
/tmp/sparktestsYPCfK/job_317c6739-21f8-413d-9cad-a0f90b1d3a93/MANIFEST has 0
artifact locations
19/11/19 17:32:25 INFO BeamFileSystemArtifactStagingService: Removed dir
/tmp/sparktestsYPCfK/job_317c6739-21f8-413d-9cad-a0f90b1d3a93/
INFO:apache_beam.runners.portability.portable_runner:Job state changed to DONE
.
======================================================================
ERROR: test_pardo_state_with_custom_key_coder (__main__.SparkRunnerTest)
Tests that state requests work correctly when the key coder is an
----------------------------------------------------------------------
Traceback (most recent call last):
File "apache_beam/runners/portability/portable_runner_test.py", line 231, in
test_pardo_state_with_custom_key_coder
equal_to(expected))
File "apache_beam/pipeline.py", line 436, in __exit__
self.run().wait_until_finish()
File "apache_beam/runners/portability/portable_runner.py", line 428, in
wait_until_finish
for state_response in self._state_stream:
File
"<https://builds.apache.org/job/beam_PostCommit_Python_VR_Spark/ws/src/build/gradleenv/1866363813/local/lib/python2.7/site-packages/grpc/_channel.py";,>
line 395, in next
return self._next()
File
"<https://builds.apache.org/job/beam_PostCommit_Python_VR_Spark/ws/src/build/gradleenv/1866363813/local/lib/python2.7/site-packages/grpc/_channel.py";,>
line 552, in _next
_common.wait(self._state.condition.wait, _response_ready)
File
"<https://builds.apache.org/job/beam_PostCommit_Python_VR_Spark/ws/src/build/gradleenv/1866363813/local/lib/python2.7/site-packages/grpc/_common.py";,>
line 140, in wait
_wait_once(wait_fn, MAXIMUM_WAIT_TIMEOUT, spin_cb)
File
"<https://builds.apache.org/job/beam_PostCommit_Python_VR_Spark/ws/src/build/gradleenv/1866363813/local/lib/python2.7/site-packages/grpc/_common.py";,>
line 105, in _wait_once
wait_fn(timeout=timeout)
File "/usr/lib/python2.7/threading.py", line 359, in wait
_sleep(delay)
File "apache_beam/runners/portability/portable_runner_test.py", line 75, in
handler
raise BaseException(msg)
BaseException: Timed out after 60 seconds.
======================================================================
ERROR: test_pardo_timers (__main__.SparkRunnerTest)
----------------------------------------------------------------------
Traceback (most recent call last):
File "apache_beam/runners/portability/fn_api_runner_test.py", line 328, in
test_pardo_timers
assert_that(actual, equal_to(expected))
File "apache_beam/pipeline.py", line 436, in __exit__
self.run().wait_until_finish()
==================== Timed out after 60 seconds. ====================
File "apache_beam/runners/portability/portable_runner.py", line 428, in
wait_until_finish
for state_response in self._state_stream:
# Thread: <Thread(wait_until_finish_read, started daemon 139996563339008)>
File
"<https://builds.apache.org/job/beam_PostCommit_Python_VR_Spark/ws/src/build/gradleenv/1866363813/local/lib/python2.7/site-packages/grpc/_channel.py";,>
line 395, in next
return self._next()
# Thread: <Thread(Thread-120, started daemon 139996571731712)>
File
"<https://builds.apache.org/job/beam_PostCommit_Python_VR_Spark/ws/src/build/gradleenv/1866363813/local/lib/python2.7/site-packages/grpc/_channel.py";,>
line 552, in _next
# Thread: <_MainThread(MainThread, started 139997350962944)>
==================== Timed out after 60 seconds. ====================
_common.wait(self._state.condition.wait, _response_ready)
# Thread: <Thread(wait_until_finish_read, started daemon 139996061230848)>
File
"<https://builds.apache.org/job/beam_PostCommit_Python_VR_Spark/ws/src/build/gradleenv/1866363813/local/lib/python2.7/site-packages/grpc/_common.py";,>
line 140, in wait
# Thread: <Thread(Thread-125, started daemon 139996069623552)>
_wait_once(wait_fn, MAXIMUM_WAIT_TIMEOUT, spin_cb)
# Thread: <Thread(Thread-120, started daemon 139996571731712)>
File
"<https://builds.apache.org/job/beam_PostCommit_Python_VR_Spark/ws/src/build/gradleenv/1866363813/local/lib/python2.7/site-packages/grpc/_common.py";,>
line 105, in _wait_once
wait_fn(timeout=timeout)
# Thread: <_MainThread(MainThread, started 139997350962944)>
File "/usr/lib/python2.7/threading.py", line 359, in wait
_sleep(delay)
File "apache_beam/runners/portability/portable_runner_test.py", line 75, in
handler
# Thread: <Thread(wait_until_finish_read, started daemon 139996563339008)>
==================== Timed out after 60 seconds. ====================
# Thread: <Thread(wait_until_finish_read, started daemon 139996052838144)>
raise BaseException(msg)
# Thread: <Thread(Thread-131, started daemon 139996044445440)>
BaseException: Timed out after 60 seconds.
# Thread: <Thread(Thread-125, started daemon 139996069623552)>
======================================================================
# Thread: <_MainThread(MainThread, started 139997350962944)>
ERROR: test_pardo_unfusable_side_inputs (__main__.SparkRunnerTest)
# Thread: <Thread(wait_until_finish_read, started daemon 139996061230848)>
----------------------------------------------------------------------
Traceback (most recent call last):
File "apache_beam/runners/portability/fn_api_runner_test.py", line 244, in
test_pardo_unfusable_side_inputs
equal_to([('a', 'a'), ('a', 'b'), ('b', 'a'), ('b', 'b')]))
File "apache_beam/pipeline.py", line 436, in __exit__
self.run().wait_until_finish()
File "apache_beam/runners/portability/portable_runner.py", line 428, in
wait_until_finish
for state_response in self._state_stream:
File
"<https://builds.apache.org/job/beam_PostCommit_Python_VR_Spark/ws/src/build/gradleenv/1866363813/local/lib/python2.7/site-packages/grpc/_channel.py";,>
line 395, in next
return self._next()
File
"<https://builds.apache.org/job/beam_PostCommit_Python_VR_Spark/ws/src/build/gradleenv/1866363813/local/lib/python2.7/site-packages/grpc/_channel.py";,>
line 552, in _next
_common.wait(self._state.condition.wait, _response_ready)
File
"<https://builds.apache.org/job/beam_PostCommit_Python_VR_Spark/ws/src/build/gradleenv/1866363813/local/lib/python2.7/site-packages/grpc/_common.py";,>
line 140, in wait
_wait_once(wait_fn, MAXIMUM_WAIT_TIMEOUT, spin_cb)
File
"<https://builds.apache.org/job/beam_PostCommit_Python_VR_Spark/ws/src/build/gradleenv/1866363813/local/lib/python2.7/site-packages/grpc/_common.py";,>
line 105, in _wait_once
wait_fn(timeout=timeout)
File "/usr/lib/python2.7/threading.py", line 359, in wait
_sleep(delay)
File "apache_beam/runners/portability/portable_runner_test.py", line 75, in
handler
raise BaseException(msg)
BaseException: Timed out after 60 seconds.
======================================================================
ERROR: test_sdf_with_watermark_tracking (__main__.SparkRunnerTest)
----------------------------------------------------------------------
Traceback (most recent call last):
File "apache_beam/runners/portability/fn_api_runner_test.py", line 499, in
test_sdf_with_watermark_tracking
assert_that(actual, equal_to(list(''.join(data))))
File "apache_beam/pipeline.py", line 436, in __exit__
self.run().wait_until_finish()
File "apache_beam/runners/portability/portable_runner.py", line 438, in
wait_until_finish
self._job_id, self._state, self._last_error_message()))
RuntimeError: Pipeline
test_sdf_with_watermark_tracking_1574184731.42_53bfdfcf-b365-48e9-8fe3-6c7dd8e79eeb
failed in state FAILED: java.lang.UnsupportedOperationException: The
ActiveBundle does not have a registered bundle checkpoint handler.
----------------------------------------------------------------------
Ran 38 tests in 347.867s
FAILED (errors=4, skipped=9)
> Task :sdks:python:test-suites:portable:py2:sparkValidatesRunner FAILED
FAILURE: Build failed with an exception.
* Where:
Build file
'<https://builds.apache.org/job/beam_PostCommit_Python_VR_Spark/ws/src/sdks/python/test-suites/portable/py2/build.gradle'>
line: 198
* What went wrong:
Execution failed for task
':sdks:python:test-suites:portable:py2:sparkValidatesRunner'.
> 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 9m 6s
59 actionable tasks: 58 executed, 1 from cache
Publishing build scan...
https://gradle.com/s/mdgmwo3unr7fk
Build step 'Invoke Gradle script' changed build result to FAILURE
Build step 'Invoke Gradle script' marked build as failure
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
