See
<https://builds.apache.org/job/beam_PostCommit_Python_VR_Spark/1574/display/redirect?page=changes>
Changes:
[markliu] Add Java mobile game on DirectRunner to release script
------------------------------------------
[...truncated 1.67 MB...]
19/11/19 21:44:21 INFO ContextCleaner: Cleaned accumulator 2503
19/11/19 21:44:21 INFO ContextCleaner: Cleaned accumulator 2564
19/11/19 21:44:21 INFO ContextCleaner: Cleaned accumulator 2517
19/11/19 21:44:21 INFO ContextCleaner: Cleaned accumulator 2750
19/11/19 21:44:21 INFO ContextCleaner: Cleaned accumulator 2951
19/11/19 21:44:21 INFO ContextCleaner: Cleaned accumulator 2709
19/11/19 21:44:21 INFO ContextCleaner: Cleaned shuffle 70
19/11/19 21:44:21 INFO ContextCleaner: Cleaned accumulator 2549
19/11/19 21:44:21 INFO ContextCleaner: Cleaned accumulator 2674
19/11/19 21:44:21 INFO ContextCleaner: Cleaned accumulator 2874
19/11/19 21:44:21 INFO ContextCleaner: Cleaned accumulator 2948
19/11/19 21:44:21 INFO ContextCleaner: Cleaned accumulator 2646
19/11/19 21:44:21 INFO ContextCleaner: Cleaned accumulator 2862
19/11/19 21:44:21 INFO ContextCleaner: Cleaned accumulator 2641
19/11/19 21:44:21 INFO ContextCleaner: Cleaned accumulator 2818
19/11/19 21:44:21 INFO ContextCleaner: Cleaned accumulator 2809
19/11/19 21:44:21 INFO ContextCleaner: Cleaned accumulator 2643
19/11/19 21:44:21 INFO ContextCleaner: Cleaned accumulator 2877
19/11/19 21:44:21 INFO ContextCleaner: Cleaned accumulator 2528
19/11/19 21:44:21 INFO ContextCleaner: Cleaned accumulator 2553
19/11/19 21:44:21 INFO ContextCleaner: Cleaned accumulator 2794
19/11/19 21:44:21 INFO ContextCleaner: Cleaned accumulator 2676
19/11/19 21:44:21 INFO ContextCleaner: Cleaned accumulator 2819
19/11/19 21:44:21 INFO ContextCleaner: Cleaned accumulator 2931
19/11/19 21:44:21 INFO ContextCleaner: Cleaned accumulator 2616
19/11/19 21:44:21 INFO ContextCleaner: Cleaned accumulator 2886
19/11/19 21:44:21 INFO ContextCleaner: Cleaned accumulator 2537
19/11/19 21:44:21 INFO ContextCleaner: Cleaned accumulator 2584
19/11/19 21:44:21 INFO ContextCleaner: Cleaned accumulator 2649
19/11/19 21:44:21 INFO ContextCleaner: Cleaned accumulator 2519
19/11/19 21:44:21 INFO ContextCleaner: Cleaned accumulator 2797
19/11/19 21:44:21 INFO ContextCleaner: Cleaned accumulator 2495
19/11/19 21:44:21 INFO ContextCleaner: Cleaned accumulator 2602
19/11/19 21:44:21 INFO ContextCleaner: Cleaned accumulator 2532
19/11/19 21:44:21 INFO ContextCleaner: Cleaned accumulator 2619
19/11/19 21:44:21 INFO ContextCleaner: Cleaned accumulator 2585
19/11/19 21:44:21 INFO ContextCleaner: Cleaned accumulator 2827
19/11/19 21:44:21 INFO BlockManagerInfo: Removed broadcast_123_piece0 on
localhost:46667 in memory (size: 9.1 KB, free: 13.4 GB)
19/11/19 21:44:21 INFO ContextCleaner: Cleaned accumulator 2724
19/11/19 21:44:21 INFO ContextCleaner: Cleaned accumulator 2546
19/11/19 21:44:21 INFO ContextCleaner: Cleaned accumulator 2591
19/11/19 21:44:21 INFO BlockManagerInfo: Removed broadcast_110_piece0 on
localhost:46667 in memory (size: 20.0 KB, free: 13.4 GB)
19/11/19 21:44:21 INFO BlockManagerInfo: Removed broadcast_127_piece0 on
localhost:46667 in memory (size: 9.0 KB, free: 13.4 GB)
19/11/19 21:44:21 INFO ContextCleaner: Cleaned accumulator 2838
19/11/19 21:44:21 INFO ContextCleaner: Cleaned accumulator 2702
19/11/19 21:44:21 INFO ContextCleaner: Cleaned accumulator 2628
19/11/19 21:44:21 INFO ContextCleaner: Cleaned accumulator 2721
19/11/19 21:44:21 INFO ContextCleaner: Cleaned accumulator 2670
19/11/19 21:44:21 INFO ContextCleaner: Cleaned accumulator 2876
19/11/19 21:44:21 INFO ContextCleaner: Cleaned accumulator 2719
19/11/19 21:44:21 INFO ContextCleaner: Cleaned accumulator 2605
19/11/19 21:44:21 INFO ContextCleaner: Cleaned accumulator 2592
19/11/19 21:44:21 INFO ContextCleaner: Cleaned accumulator 2516
19/11/19 21:44:22 INFO GrpcLoggingService: Beam Fn Logging client connected.
19/11/19 21:44:22 INFO main: Logging handler created.
19/11/19 21:44:22 INFO start: Status HTTP server running at localhost:38733
19/11/19 21:44:22 INFO main: semi_persistent_directory: /tmp
19/11/19 21:44:22 WARN _load_main_session: No session file found:
/tmp/staged/pickled_main_session. Functions defined in __main__ (interactive
session) may fail.
19/11/19 21:44:22 WARN get_all_options: Discarding unparseable args:
[u'--job_server_timeout=60',
u'--app_name=test_windowing_1574199858.45_c4096c27-03b9-4992-8e7d-a6825cb62561',
u'--direct_runner_use_stacked_bundle', u'--spark_master=local',
u'--options_id=30', u'--enable_spark_metric_sinks', u'--pipeline_type_check']
19/11/19 21:44:22 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_1574199858.45',
'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:42293'}
19/11/19 21:44:22 INFO __init__: Creating state cache with size 0
19/11/19 21:44:22 INFO __init__: Creating insecure control channel for
localhost:33233.
19/11/19 21:44:22 INFO __init__: Control channel established.
19/11/19 21:44:22 INFO __init__: Initializing SDKHarness with unbounded number
of workers.
19/11/19 21:44:22 INFO FnApiControlClientPoolService: Beam Fn Control client
connected with id 264-1
19/11/19 21:44:22 INFO create_state_handler: Creating insecure state channel
for localhost:45173.
19/11/19 21:44:22 INFO create_state_handler: State channel established.
19/11/19 21:44:22 INFO create_data_channel: Creating client data channel for
localhost:42093
19/11/19 21:44:22 INFO GrpcDataService: Beam Fn Data client connected.
19/11/19 21:44:22 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 21:44:22 INFO run: No more requests from control plane
19/11/19 21:44:22 INFO run: SDK Harness waiting for in-flight requests to
complete
19/11/19 21:44:22 INFO close: Closing all cached grpc data channels.
19/11/19 21:44:22 WARN BeamFnDataGrpcMultiplexer: Hanged up for unknown
endpoint.
19/11/19 21:44:22 INFO close: Closing all cached gRPC state handlers.
19/11/19 21:44:22 INFO run: Done consuming work.
19/11/19 21:44:22 INFO main: Python sdk harness exiting.
19/11/19 21:44:22 INFO GrpcLoggingService: Logging client hanged up.
19/11/19 21:44:22 WARN BeamFnDataGrpcMultiplexer: Hanged up for unknown
endpoint.
19/11/19 21:44:22 INFO Executor: Finished task 0.0 in stage 132.0 (TID 160).
13753 bytes result sent to driver
19/11/19 21:44:22 INFO TaskSetManager: Finished task 0.0 in stage 132.0 (TID
160) in 841 ms on localhost (executor driver) (2/2)
19/11/19 21:44:22 INFO TaskSchedulerImpl: Removed TaskSet 132.0, whose tasks
have all completed, from pool
19/11/19 21:44:22 INFO DAGScheduler: ShuffleMapStage 132 (flatMapToPair at
GroupNonMergingWindowsFunctions.java:115) finished in 1.689 s
19/11/19 21:44:22 INFO DAGScheduler: looking for newly runnable stages
19/11/19 21:44:22 INFO DAGScheduler: running: Set()
19/11/19 21:44:22 INFO DAGScheduler: waiting: Set(ResultStage 133)
19/11/19 21:44:22 INFO DAGScheduler: failed: Set()
19/11/19 21:44:22 INFO DAGScheduler: Submitting ResultStage 133
(EmptyOutputSink_0 MapPartitionsRDD[916] at flatMap at
SparkBatchPortablePipelineTranslator.java:311), which has no missing parents
19/11/19 21:44:22 INFO MemoryStore: Block broadcast_130 stored as values in
memory (estimated size 26.1 KB, free 13.4 GB)
19/11/19 21:44:22 INFO MemoryStore: Block broadcast_130_piece0 stored as bytes
in memory (estimated size 12.3 KB, free 13.4 GB)
19/11/19 21:44:22 INFO BlockManagerInfo: Added broadcast_130_piece0 in memory
on localhost:46667 (size: 12.3 KB, free: 13.4 GB)
19/11/19 21:44:22 INFO SparkContext: Created broadcast 130 from broadcast at
DAGScheduler.scala:1161
19/11/19 21:44:22 INFO DAGScheduler: Submitting 1 missing tasks from
ResultStage 133 (EmptyOutputSink_0 MapPartitionsRDD[916] at flatMap at
SparkBatchPortablePipelineTranslator.java:311) (first 15 tasks are for
partitions Vector(0))
19/11/19 21:44:22 INFO TaskSchedulerImpl: Adding task set 133.0 with 1 tasks
19/11/19 21:44:22 INFO TaskSetManager: Starting task 0.0 in stage 133.0 (TID
161, localhost, executor driver, partition 0, NODE_LOCAL, 7662 bytes)
19/11/19 21:44:22 INFO Executor: Running task 0.0 in stage 133.0 (TID 161)
19/11/19 21:44:22 INFO ShuffleBlockFetcherIterator: Getting 2 non-empty blocks
including 2 local blocks and 0 remote blocks
19/11/19 21:44:22 INFO ShuffleBlockFetcherIterator: Started 0 remote fetches in
0 ms
19/11/19 21:44:22 INFO AbstractArtifactRetrievalService: GetManifest for
/tmp/sparktestLS7Nrc/job_2c02e26e-2572-4604-89f2-d028581e9cd3/MANIFEST
19/11/19 21:44:22 INFO AbstractArtifactRetrievalService: GetManifest for
/tmp/sparktestLS7Nrc/job_2c02e26e-2572-4604-89f2-d028581e9cd3/MANIFEST -> 0
artifacts
19/11/19 21:44:23 INFO GrpcLoggingService: Beam Fn Logging client connected.
19/11/19 21:44:23 INFO main: Logging handler created.
19/11/19 21:44:23 INFO start: Status HTTP server running at localhost:46581
19/11/19 21:44:23 INFO main: semi_persistent_directory: /tmp
19/11/19 21:44:23 WARN _load_main_session: No session file found:
/tmp/staged/pickled_main_session. Functions defined in __main__ (interactive
session) may fail.
19/11/19 21:44:23 WARN get_all_options: Discarding unparseable args:
[u'--job_server_timeout=60',
u'--app_name=test_windowing_1574199858.45_c4096c27-03b9-4992-8e7d-a6825cb62561',
u'--direct_runner_use_stacked_bundle', u'--spark_master=local',
u'--options_id=30', u'--enable_spark_metric_sinks', u'--pipeline_type_check']
19/11/19 21:44:23 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_1574199858.45',
'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:42293'}
19/11/19 21:44:23 INFO __init__: Creating state cache with size 0
19/11/19 21:44:23 INFO __init__: Creating insecure control channel for
localhost:43199.
19/11/19 21:44:23 INFO __init__: Control channel established.
19/11/19 21:44:23 INFO __init__: Initializing SDKHarness with unbounded number
of workers.
19/11/19 21:44:23 INFO FnApiControlClientPoolService: Beam Fn Control client
connected with id 265-1
19/11/19 21:44:23 INFO create_state_handler: Creating insecure state channel
for localhost:46151.
19/11/19 21:44:23 INFO create_state_handler: State channel established.
19/11/19 21:44:23 INFO create_data_channel: Creating client data channel for
localhost:42407
19/11/19 21:44:23 INFO GrpcDataService: Beam Fn Data client connected.
19/11/19 21:44:23 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 21:44:23 INFO run: No more requests from control plane
19/11/19 21:44:23 INFO run: SDK Harness waiting for in-flight requests to
complete
19/11/19 21:44:23 INFO close: Closing all cached grpc data channels.
19/11/19 21:44:23 WARN BeamFnDataGrpcMultiplexer: Hanged up for unknown
endpoint.
19/11/19 21:44:23 INFO close: Closing all cached gRPC state handlers.
19/11/19 21:44:23 INFO run: Done consuming work.
19/11/19 21:44:23 INFO main: Python sdk harness exiting.
19/11/19 21:44:23 INFO GrpcLoggingService: Logging client hanged up.
19/11/19 21:44:23 WARN BeamFnDataGrpcMultiplexer: Hanged up for unknown
endpoint.
19/11/19 21:44:23 INFO Executor: Finished task 0.0 in stage 133.0 (TID 161).
11970 bytes result sent to driver
19/11/19 21:44:23 INFO TaskSetManager: Finished task 0.0 in stage 133.0 (TID
161) in 801 ms on localhost (executor driver) (1/1)
19/11/19 21:44:23 INFO TaskSchedulerImpl: Removed TaskSet 133.0, whose tasks
have all completed, from pool
19/11/19 21:44:23 INFO DAGScheduler: ResultStage 133 (foreach at
BoundedDataset.java:124) finished in 0.806 s
19/11/19 21:44:23 INFO DAGScheduler: Job 47 finished: foreach at
BoundedDataset.java:124, took 4.090454 s
19/11/19 21:44:23 INFO SparkPipelineRunner: Job
test_windowing_1574199858.45_c4096c27-03b9-4992-8e7d-a6825cb62561 finished.
19/11/19 21:44:23 WARN SparkPipelineResult$BatchMode: Collecting monitoring
infos is not implemented yet in Spark portable runner.
19/11/19 21:44:23 INFO AbstractArtifactRetrievalService: Manifest at
/tmp/sparktestLS7Nrc/job_2c02e26e-2572-4604-89f2-d028581e9cd3/MANIFEST has 0
artifact locations
19/11/19 21:44:23 INFO BeamFileSystemArtifactStagingService: Removed dir
/tmp/sparktestLS7Nrc/job_2c02e26e-2572-4604-89f2-d028581e9cd3/
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:
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)
# Thread: <Thread(wait_until_finish_read, started daemon 140177968228096)>
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)
# Thread: <Thread(Thread-118, started daemon 140177959835392)>
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.
======================================================================
# Thread: <_MainThread(MainThread, started 140178755852032)>
==================== Timed out after 60 seconds. ====================
ERROR: test_sdf_with_watermark_tracking (__main__.SparkRunnerTest)
----------------------------------------------------------------------
Traceback (most recent call last):
# Thread: <Thread(wait_until_finish_read, started daemon 140177330665216)>
File "apache_beam/runners/portability/fn_api_runner_test.py", line 499, in
test_sdf_with_watermark_tracking
assert_that(actual, equal_to(lis# Thread: <Thread(Thread-124, started
daemon 140177950394112)>
t(''.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()))
# Thread: <_MainThread(MainThread, started 140178755852032)>
RuntimeError: Pipeline
test_sdf_with_watermark_tracking_1574199849.8_26c7b82f-58bc-4569-8ba8-19efd9bed87b
failed in state FAILED: java.lang.UnsupportedOperationException: The
ActiveBundle does not have a registered bundle checkpoint handler.
----------------------------------------------------------------------
Ran 38 tests in 288.053s
FAILED (errors=3, 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 7m 6s
59 actionable tasks: 46 executed, 13 from cache
Publishing build scan...
https://gradle.com/s/qmtdo6oakmfv2
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]
