See
<https://builds.apache.org/job/beam_PostCommit_Python_VR_Spark/1547/display/redirect?page=changes>
Changes:
[ningk] [BEAM-8379] Cache Eviction
------------------------------------------
[...truncated 1.68 MB...]
19/11/15 21:27:25 INFO ContextCleaner: Cleaned accumulator 2551
19/11/15 21:27:25 INFO ContextCleaner: Cleaned accumulator 2831
19/11/15 21:27:25 INFO ContextCleaner: Cleaned accumulator 2545
19/11/15 21:27:25 INFO ContextCleaner: Cleaned accumulator 2932
19/11/15 21:27:25 INFO ContextCleaner: Cleaned accumulator 2558
19/11/15 21:27:25 INFO ContextCleaner: Cleaned accumulator 2828
19/11/15 21:27:25 INFO ContextCleaner: Cleaned accumulator 2886
19/11/15 21:27:25 INFO ContextCleaner: Cleaned accumulator 2534
19/11/15 21:27:25 INFO ContextCleaner: Cleaned accumulator 2717
19/11/15 21:27:25 INFO ContextCleaner: Cleaned accumulator 2681
19/11/15 21:27:25 INFO ContextCleaner: Cleaned accumulator 2525
19/11/15 21:27:25 INFO ContextCleaner: Cleaned accumulator 2849
19/11/15 21:27:25 INFO ContextCleaner: Cleaned accumulator 2754
19/11/15 21:27:25 INFO ContextCleaner: Cleaned accumulator 2564
19/11/15 21:27:25 INFO ContextCleaner: Cleaned accumulator 2644
19/11/15 21:27:25 INFO ContextCleaner: Cleaned accumulator 2611
19/11/15 21:27:25 INFO ContextCleaner: Cleaned accumulator 2933
19/11/15 21:27:25 INFO ContextCleaner: Cleaned accumulator 2568
19/11/15 21:27:25 INFO ContextCleaner: Cleaned accumulator 2846
19/11/15 21:27:25 INFO ContextCleaner: Cleaned shuffle 64
19/11/15 21:27:25 INFO ContextCleaner: Cleaned accumulator 2922
19/11/15 21:27:25 INFO ContextCleaner: Cleaned accumulator 2677
19/11/15 21:27:25 INFO ContextCleaner: Cleaned accumulator 2796
19/11/15 21:27:25 INFO ContextCleaner: Cleaned accumulator 2730
19/11/15 21:27:25 INFO ContextCleaner: Cleaned shuffle 65
19/11/15 21:27:25 INFO ContextCleaner: Cleaned accumulator 2688
19/11/15 21:27:25 INFO ContextCleaner: Cleaned accumulator 2734
19/11/15 21:27:25 INFO ContextCleaner: Cleaned accumulator 2770
19/11/15 21:27:25 INFO ContextCleaner: Cleaned accumulator 2656
19/11/15 21:27:25 INFO ContextCleaner: Cleaned accumulator 2626
19/11/15 21:27:25 INFO ContextCleaner: Cleaned accumulator 2916
19/11/15 21:27:25 INFO ContextCleaner: Cleaned accumulator 2539
19/11/15 21:27:25 INFO ContextCleaner: Cleaned accumulator 2587
19/11/15 21:27:25 INFO ContextCleaner: Cleaned accumulator 2894
19/11/15 21:27:25 INFO ContextCleaner: Cleaned accumulator 2543
19/11/15 21:27:25 INFO ContextCleaner: Cleaned accumulator 2764
19/11/15 21:27:25 INFO ContextCleaner: Cleaned accumulator 2647
19/11/15 21:27:25 INFO ContextCleaner: Cleaned accumulator 2755
19/11/15 21:27:25 INFO ContextCleaner: Cleaned accumulator 2792
19/11/15 21:27:25 INFO ContextCleaner: Cleaned accumulator 2909
19/11/15 21:27:25 INFO ContextCleaner: Cleaned accumulator 2737
19/11/15 21:27:25 INFO ContextCleaner: Cleaned accumulator 2850
19/11/15 21:27:25 INFO ContextCleaner: Cleaned accumulator 2928
19/11/15 21:27:25 INFO ContextCleaner: Cleaned accumulator 2576
19/11/15 21:27:25 INFO ContextCleaner: Cleaned accumulator 2777
19/11/15 21:27:25 INFO ContextCleaner: Cleaned accumulator 2657
19/11/15 21:27:25 INFO ContextCleaner: Cleaned accumulator 2690
19/11/15 21:27:25 INFO ContextCleaner: Cleaned accumulator 2768
19/11/15 21:27:25 INFO ContextCleaner: Cleaned accumulator 2766
19/11/15 21:27:26 INFO GrpcLoggingService: Beam Fn Logging client connected.
19/11/15 21:27:26 INFO main: Logging handler created.
19/11/15 21:27:26 INFO start: Status HTTP server running at localhost:33041
19/11/15 21:27:26 INFO main: semi_persistent_directory: /tmp
19/11/15 21:27:26 WARN _load_main_session: No session file found:
/tmp/staged/pickled_main_session. Functions defined in __main__ (interactive
session) may fail.
19/11/15 21:27:26 WARN get_all_options: Discarding unparseable args:
[u'--job_server_timeout=60',
u'--app_name=test_windowing_1573853237.56_ee1d2b4b-1dfa-4200-9249-8f8c35c93389',
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/15 21:27:26 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_1573853237.56',
'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:36517'}
19/11/15 21:27:26 INFO __init__: Creating state cache with size 0
19/11/15 21:27:26 INFO __init__: Creating insecure control channel for
localhost:46705.
19/11/15 21:27:26 INFO __init__: Control channel established.
19/11/15 21:27:26 INFO __init__: Initializing SDKHarness with unbounded number
of workers.
19/11/15 21:27:26 INFO FnApiControlClientPoolService: Beam Fn Control client
connected with id 264-1
19/11/15 21:27:26 INFO create_state_handler: Creating insecure state channel
for localhost:33869.
19/11/15 21:27:26 INFO create_state_handler: State channel established.
19/11/15 21:27:26 INFO create_data_channel: Creating client data channel for
localhost:42289
19/11/15 21:27:26 INFO GrpcDataService: Beam Fn Data client connected.
19/11/15 21:27:26 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/15 21:27:26 INFO run: No more requests from control plane
19/11/15 21:27:26 INFO run: SDK Harness waiting for in-flight requests to
complete
19/11/15 21:27:26 WARN BeamFnDataGrpcMultiplexer: Hanged up for unknown
endpoint.
19/11/15 21:27:26 INFO close: Closing all cached grpc data channels.
19/11/15 21:27:26 INFO close: Closing all cached gRPC state handlers.
19/11/15 21:27:26 INFO run: Done consuming work.
19/11/15 21:27:26 INFO main: Python sdk harness exiting.
19/11/15 21:27:26 INFO GrpcLoggingService: Logging client hanged up.
19/11/15 21:27:26 WARN BeamFnDataGrpcMultiplexer: Hanged up for unknown
endpoint.
19/11/15 21:27:27 INFO Executor: Finished task 0.0 in stage 132.0 (TID 160).
13753 bytes result sent to driver
19/11/15 21:27:27 INFO TaskSetManager: Finished task 0.0 in stage 132.0 (TID
160) in 1641 ms on localhost (executor driver) (2/2)
19/11/15 21:27:27 INFO TaskSchedulerImpl: Removed TaskSet 132.0, whose tasks
have all completed, from pool
19/11/15 21:27:27 INFO DAGScheduler: ShuffleMapStage 132 (flatMapToPair at
GroupNonMergingWindowsFunctions.java:115) finished in 4.894 s
19/11/15 21:27:27 INFO DAGScheduler: looking for newly runnable stages
19/11/15 21:27:27 INFO DAGScheduler: running: Set()
19/11/15 21:27:27 INFO DAGScheduler: waiting: Set(ResultStage 133)
19/11/15 21:27:27 INFO DAGScheduler: failed: Set()
19/11/15 21:27:27 INFO DAGScheduler: Submitting ResultStage 133
(EmptyOutputSink_0 MapPartitionsRDD[916] at flatMap at
SparkBatchPortablePipelineTranslator.java:311), which has no missing parents
19/11/15 21:27:27 INFO MemoryStore: Block broadcast_130 stored as values in
memory (estimated size 26.1 KB, free 13.5 GB)
19/11/15 21:27:27 INFO MemoryStore: Block broadcast_130_piece0 stored as bytes
in memory (estimated size 12.4 KB, free 13.5 GB)
19/11/15 21:27:27 INFO BlockManagerInfo: Added broadcast_130_piece0 in memory
on localhost:38775 (size: 12.4 KB, free: 13.5 GB)
19/11/15 21:27:27 INFO SparkContext: Created broadcast 130 from broadcast at
DAGScheduler.scala:1161
19/11/15 21:27:27 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/15 21:27:27 INFO TaskSchedulerImpl: Adding task set 133.0 with 1 tasks
19/11/15 21:27:27 INFO TaskSetManager: Starting task 0.0 in stage 133.0 (TID
161, localhost, executor driver, partition 0, NODE_LOCAL, 7662 bytes)
19/11/15 21:27:27 INFO Executor: Running task 0.0 in stage 133.0 (TID 161)
19/11/15 21:27:27 INFO ShuffleBlockFetcherIterator: Getting 2 non-empty blocks
including 2 local blocks and 0 remote blocks
19/11/15 21:27:27 INFO ShuffleBlockFetcherIterator: Started 0 remote fetches in
0 ms
19/11/15 21:27:27 INFO AbstractArtifactRetrievalService: GetManifest for
/tmp/sparktest1fpj1E/job_38ace3b9-1b83-4d9b-a029-43f964b076a9/MANIFEST
19/11/15 21:27:27 INFO AbstractArtifactRetrievalService: GetManifest for
/tmp/sparktest1fpj1E/job_38ace3b9-1b83-4d9b-a029-43f964b076a9/MANIFEST -> 0
artifacts
19/11/15 21:27:28 INFO GrpcLoggingService: Beam Fn Logging client connected.
19/11/15 21:27:28 INFO main: Logging handler created.
19/11/15 21:27:28 INFO start: Status HTTP server running at localhost:43305
19/11/15 21:27:28 INFO main: semi_persistent_directory: /tmp
19/11/15 21:27:28 WARN _load_main_session: No session file found:
/tmp/staged/pickled_main_session. Functions defined in __main__ (interactive
session) may fail.
19/11/15 21:27:28 WARN get_all_options: Discarding unparseable args:
[u'--job_server_timeout=60',
u'--app_name=test_windowing_1573853237.56_ee1d2b4b-1dfa-4200-9249-8f8c35c93389',
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/15 21:27:28 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_1573853237.56',
'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:36517'}
19/11/15 21:27:28 INFO __init__: Creating state cache with size 0
19/11/15 21:27:28 INFO __init__: Creating insecure control channel for
localhost:39763.
19/11/15 21:27:28 INFO __init__: Control channel established.
19/11/15 21:27:28 INFO __init__: Initializing SDKHarness with unbounded number
of workers.
19/11/15 21:27:28 INFO FnApiControlClientPoolService: Beam Fn Control client
connected with id 265-1
19/11/15 21:27:28 INFO create_state_handler: Creating insecure state channel
for localhost:33207.
19/11/15 21:27:28 INFO create_state_handler: State channel established.
19/11/15 21:27:28 INFO create_data_channel: Creating client data channel for
localhost:36429
19/11/15 21:27:28 INFO GrpcDataService: Beam Fn Data client connected.
19/11/15 21:27:28 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/15 21:27:28 INFO run: No more requests from control plane
19/11/15 21:27:28 INFO run: SDK Harness waiting for in-flight requests to
complete
19/11/15 21:27:28 INFO close: Closing all cached grpc data channels.
19/11/15 21:27:28 INFO close: Closing all cached gRPC state handlers.
19/11/15 21:27:28 WARN BeamFnDataGrpcMultiplexer: Hanged up for unknown
endpoint.
19/11/15 21:27:28 INFO run: Done consuming work.
19/11/15 21:27:28 INFO main: Python sdk harness exiting.
19/11/15 21:27:28 INFO GrpcLoggingService: Logging client hanged up.
19/11/15 21:27:28 WARN BeamFnDataGrpcMultiplexer: Hanged up for unknown
endpoint.
19/11/15 21:27:28 INFO Executor: Finished task 0.0 in stage 133.0 (TID 161).
11970 bytes result sent to driver
19/11/15 21:27:28 INFO TaskSetManager: Finished task 0.0 in stage 133.0 (TID
161) in 909 ms on localhost (executor driver) (1/1)
19/11/15 21:27:28 INFO TaskSchedulerImpl: Removed TaskSet 133.0, whose tasks
have all completed, from pool
19/11/15 21:27:28 INFO DAGScheduler: ResultStage 133 (foreach at
BoundedDataset.java:124) finished in 0.917 s
19/11/15 21:27:28 INFO DAGScheduler: Job 47 finished: foreach at
BoundedDataset.java:124, took 9.199294 s
19/11/15 21:27:28 INFO SparkPipelineRunner: Job
test_windowing_1573853237.56_ee1d2b4b-1dfa-4200-9249-8f8c35c93389 finished.
19/11/15 21:27:28 WARN SparkPipelineResult$BatchMode: Collecting monitoring
infos is not implemented yet in Spark portable runner.
19/11/15 21:27:28 INFO AbstractArtifactRetrievalService: Manifest at
/tmp/sparktest1fpj1E/job_38ace3b9-1b83-4d9b-a029-43f964b076a9/MANIFEST has 0
artifact locations
19/11/15 21:27:29 INFO BeamFileSystemArtifactStagingService: Removed dir
/tmp/sparktest1fpj1E/job_38ace3b9-1b83-4d9b-a029-43f964b076a9/
INFO:root: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 229, 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 419, 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 73, 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 326, in
test_pardo_timers
assert_that(actual, equal_to(expected))
File "apache_beam/pipeline.py", line 436, in __exit__
==================== Timed out after 60 seconds. ====================
self.run().wait_until_finish()
File "apache_beam/runners/portability/portable_runner.py", line 419, 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()
# Thread: <Thread(wait_until_finish_read, started daemon 140173112760064)>
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(Thread-119, started daemon 140173121152768)>
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: <_MainThread(MainThread, started 140173908776704)>
==================== Timed out after 60 seconds. ====================
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)
# Thread: <Thread(wait_until_finish_read, started daemon 140172624652032)>
File "apache_beam/runners/portability/portable_runner_test.py", line 73, in
handler
raise BaseException(msg)
BaseException: Timed out after 60 seconds.
======================================================================
ERROR: test_sdf_with_watermark_tracking (__main__.SparkRunnerTest)
----------------------------------------------------------------------
Traceback (most recent call last):
# Thread: <Thread(Thread-125, started daemon 140172633044736)>
# Thread: <Thread(Thread-119, started daemon 140173121152768)>
File "apache_beam/runners/portability/fn_api_runner_test.py", line 497, in
test_sdf_with_watermark_tracking
assert_that(actual, equal_to(lis# Thread: <_MainThread(MainThread, started
140173908776704)>
t(''.join(data))))
File "apache_beam/pipeline.py", line 436, in __exit__
self.run().wait_until_finish()
# Thread: <Thread(wait_until_finish_read, started daemon 140173112760064)>
File "apache_beam/runners/portability/portable_runner.py", line 429, in
wait_until_finish
self._job_id, self._state, self._last_error_message()))
RuntimeError: Pipeline
test_sdf_with_watermark_tracking_1573853221.01_bc98c626-8347-4ac0-921e-ef12a27830e3
failed in state FAILED: java.lang.UnsupportedOperationException: The
ActiveBundle does not have a registered bundle checkpoint handler.
----------------------------------------------------------------------
Ran 38 tests in 334.871s
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 9m 6s
59 actionable tasks: 46 executed, 13 from cache
Publishing build scan...
https://gradle.com/s/zbw3g6zznxad2
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]
