See
<https://ci-beam.apache.org/job/beam_PostCommit_Java_Nexmark_Dataflow_V2_Java17/1149/display/redirect>
Changes:
------------------------------------------
[...truncated 249.74 KB...]
0014 query:RESHUFFLE; numEvents:10000000
0015 query:BOUNDED_SIDE_INPUT_JOIN; numEvents:10000000
0016 query:SESSION_SIDE_INPUT_JOIN; numEvents:10000000
Performance:
Conf Runtime(sec) (Baseline) Events(/sec) (Baseline) Results
(Baseline)
0000 -1.0 -1.0 -1
*** Job was unexpectedly updated ***
0001 -1.0 -1.0 -1
*** Job was unexpectedly updated ***
0002 -1.0 -1.0 -1
*** Job was unexpectedly updated ***
0003 *** not run ***
0004 *** not run ***
0005 -1.0 -1.0 -1
*** Job was unexpectedly updated ***
0006 *** not run ***
0007 *** not run ***
0008 -1.0 -1.0 -1
*** Job was unexpectedly updated ***
0009 *** not run ***
0010 -1.0 -1.0 -1
*** Job was unexpectedly updated ***
0011 -1.0 -1.0 -1
*** Job was unexpectedly updated ***
0012 -1.0 -1.0 -1
*** Job was unexpectedly updated ***
0013 -1.0 -1.0 -1
*** Job was unexpectedly updated ***
0014 *** not run ***
0015 *** not run ***
0016 *** not run ***
==========================================================================================
Oct 05, 2022 3:28:59 AM
org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process
INFO: 2022-10-05T03:28:58.514Z: Finished operation
Query14.ReadBounded/Impulse+Query14.ReadBounded/ParDo(OutputSingleSource)/ParMultiDo(OutputSingleSource)+Query14-ReadBounded-ParDo-BoundedSourceAsSDFWrapper--ParMultiDo-BoundedSourceAsSDFWrapper-/PairWithRestriction+Query14-ReadBounded-ParDo-BoundedSourceAsSDFWrapper--ParMultiDo-BoundedSourceAsSDFWrapper-/SplitWithSizing
Oct 05, 2022 3:28:59 AM
org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process
INFO: 2022-10-05T03:28:58.647Z: Executing operation
Query14/Query14/Reshuffle.ViaRandomKey/Reshuffle/GroupByKey/Create
Oct 05, 2022 3:29:01 AM
org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process
WARNING: 2022-10-05T03:28:59.899Z: Autoscaling: Unable to reach resize target
in zone us-central1-b. QUOTA_EXCEEDED: Instance
'main-jenkins-1005032622-6-10042026-6zph-harness-xcmn' creation failed: Quota
'IN_USE_ADDRESSES' exceeded. Limit: 1200.0 in region us-central1.
Oct 05, 2022 3:29:01 AM
org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process
INFO: 2022-10-05T03:29:00.009Z: Finished operation
Query14/Query14/Reshuffle.ViaRandomKey/Reshuffle/GroupByKey/Create
Oct 05, 2022 3:29:01 AM
org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process
INFO: 2022-10-05T03:29:00.208Z: Executing operation
Query14-ReadBounded-ParDo-BoundedSourceAsSDFWrapper--ParMultiDo-BoundedSourceAsSDFWrapper-/ProcessElementAndRestrictionWithSizing+Query14/Query14.Monitor/ParMultiDo(Monitor)+Query14/Query14.Snoop/ParMultiDo(Anonymous)+Query14/Query14/Reshuffle.ViaRandomKey/Pair
with random
key/ParMultiDo(AssignShard)+Query14/Query14/Reshuffle.ViaRandomKey/Reshuffle/Window.Into()/Window.Assign+Query14/Query14/Reshuffle.ViaRandomKey/Reshuffle/ReifyOriginalTimestamps/ParDo(Anonymous)/ParMultiDo(Anonymous)+Query14/Query14/Reshuffle.ViaRandomKey/Reshuffle/GroupByKey/Reify+Query14/Query14/Reshuffle.ViaRandomKey/Reshuffle/GroupByKey/Write
Oct 05, 2022 3:29:20 AM
org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process
INFO: 2022-10-05T03:29:20.214Z: Autoscaling: Raised the number of ****s to 3
based on the rate of progress in the currently running stage(s).
Oct 05, 2022 3:29:20 AM
org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process
INFO: 2022-10-05T03:29:20.251Z: Autoscaling: Resized **** pool to 3, though
goal was 4. This could be a quota issue.
Oct 05, 2022 3:29:52 AM
org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process
WARNING: 2022-10-05T03:29:50.211Z: Autoscaling: Unable to reach resize target
in zone us-central1-b. QUOTA_EXCEEDED: Instance
'main-jenkins-1005032622-6-10042026-6zph-harness-np76' creation failed: Quota
'IN_USE_ADDRESSES' exceeded. Limit: 1200.0 in region us-central1.
Oct 05, 2022 3:30:06 AM
org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process
INFO: 2022-10-05T03:30:05.733Z: Finished operation
Query14-ReadBounded-ParDo-BoundedSourceAsSDFWrapper--ParMultiDo-BoundedSourceAsSDFWrapper-/ProcessElementAndRestrictionWithSizing+Query14/Query14.Monitor/ParMultiDo(Monitor)+Query14/Query14.Snoop/ParMultiDo(Anonymous)+Query14/Query14/Reshuffle.ViaRandomKey/Pair
with random
key/ParMultiDo(AssignShard)+Query14/Query14/Reshuffle.ViaRandomKey/Reshuffle/Window.Into()/Window.Assign+Query14/Query14/Reshuffle.ViaRandomKey/Reshuffle/ReifyOriginalTimestamps/ParDo(Anonymous)/ParMultiDo(Anonymous)+Query14/Query14/Reshuffle.ViaRandomKey/Reshuffle/GroupByKey/Reify+Query14/Query14/Reshuffle.ViaRandomKey/Reshuffle/GroupByKey/Write
Oct 05, 2022 3:30:06 AM
org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process
INFO: 2022-10-05T03:30:05.804Z: Executing operation
Query14/Query14/Reshuffle.ViaRandomKey/Reshuffle/GroupByKey/Close
Oct 05, 2022 3:30:06 AM
org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process
INFO: 2022-10-05T03:30:05.862Z: Finished operation
Query14/Query14/Reshuffle.ViaRandomKey/Reshuffle/GroupByKey/Close
Oct 05, 2022 3:30:06 AM
org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process
INFO: 2022-10-05T03:30:05.925Z: Executing operation
Query14/Query14/Reshuffle.ViaRandomKey/Reshuffle/GroupByKey/Read+Query14/Query14/Reshuffle.ViaRandomKey/Reshuffle/GroupByKey/GroupByWindow+Query14/Query14/Reshuffle.ViaRandomKey/Reshuffle/ExpandIterable/ParMultiDo(Anonymous)+Query14/Query14/Reshuffle.ViaRandomKey/Reshuffle/RestoreOriginalTimestamps/ReifyTimestamps.RemoveWildcard/ParDo(Anonymous)/ParMultiDo(Anonymous)+Query14/Query14/Reshuffle.ViaRandomKey/Reshuffle/RestoreOriginalTimestamps/Reify.ExtractTimestampsFromValues/ParDo(Anonymous)/ParMultiDo(Anonymous)+Query14/Query14/Reshuffle.ViaRandomKey/Values/Values/Map/ParMultiDo(Anonymous)+Query14/Query14.Debug/ParMultiDo(Monitor)+Query14/Query14.Stamp/ParMultiDo(Anonymous)+Query14.Format/ParMultiDo(Anonymous)+Query14.DevNull/ParMultiDo(Anonymous)
Oct 05, 2022 3:30:11 AM
org.apache.beam.runners.dataflow.util.MonitoringUtil$LoggingHandler process
INFO: 2022-10-05T03:30:10.727Z: Autoscaling: Raised the number of ****s to 4
based on the rate of progress in the currently running stage(s).
Oct 05, 2022 3:30:36 AM org.apache.beam.runners.dataflow.DataflowPipelineJob
waitUntilFinish
2022-10-05T03:30:36.362Z Waiting for main pipeline to 'finish'
WARNING: No terminal state was returned within allotted timeout. State value
RUNNING
2022-10-05T03:30:36.394Z RUNNING Query14
2022-10-05T03:30:37.350Z new perf
{"snapshots":null,"runtimeSec":77.966,"numEvents":10000000,"eventsPerSec":128261.036862222,"eventBytesPerSec":1.6463136456917118E7,"numResults":494137,"resultsPerSec":6337.85239719878,"resultBytesPerSec":813616.743195752,"startupDelaySec":-90.866,"processingDelaySec":62.937,"shutdownDelaySec":12.901,"timeDilation":12.825975425185339,"errors":null,"jobId":null}
2022-10-05T03:30:52.405Z RUNNING Query14
2022-10-05T03:30:53.153Z new perf
{"snapshots":null,"runtimeSec":89.59,"numEvents":10000000,"eventsPerSec":111619.60040183055,"eventBytesPerSec":1.432710008929568E7,"numResults":2490664,"resultsPerSec":27800.69204152249,"resultBytesPerSec":3567222.21230048,"startupDelaySec":-90.866,"processingDelaySec":62.937,"shutdownDelaySec":17.267,"timeDilation":11.161848420582654,"errors":null,"jobId":null}
2022-10-05T03:31:08.262Z RUNNING Query14
2022-10-05T03:31:08.988Z new perf
{"snapshots":null,"runtimeSec":108.074,"numEvents":10000000,"eventsPerSec":92529.192960379,"eventBytesPerSec":1.18767224031682E7,"numResults":5273454,"resultsPerSec":48794.844273368246,"resultBytesPerSec":6261980.689157429,"startupDelaySec":-90.866,"processingDelaySec":62.937,"shutdownDelaySec":14.584,"timeDilation":9.25282676684494,"errors":null,"jobId":null}
2022-10-05T03:31:24.031Z RUNNING Query14
2022-10-05T03:31:24.789Z new perf
{"snapshots":null,"runtimeSec":115.193,"numEvents":10000000,"eventsPerSec":86810.83051921558,"eventBytesPerSec":1.1142733473388141E7,"numResults":10000000,"resultsPerSec":86810.83051921558,"resultBytesPerSec":1.1142733473388141E7,"startupDelaySec":-90.866,"processingDelaySec":62.937,"shutdownDelaySec":23.299,"timeDilation":8.680996241091039,"errors":null,"jobId":null}
2022-10-05T03:31:39.832Z RUNNING Query14
2022-10-05T03:31:40.585Z no activity
2022-10-05T03:31:55.628Z RUNNING Query14
2022-10-05T03:31:56.378Z no activity
2022-10-05T03:32:11.421Z RUNNING Query14
2022-10-05T03:32:12.183Z no activity
2022-10-05T03:32:27.231Z RUNNING Query14
2022-10-05T03:32:27.919Z no activity
2022-10-05T03:32:42.964Z RUNNING Query14
2022-10-05T03:32:44.097Z no activity
2022-10-05T03:32:59.132Z RUNNING Query14
2022-10-05T03:32:59.844Z no activity
2022-10-05T03:33:14.883Z RUNNING Query14
2022-10-05T03:33:15.648Z no activity
2022-10-05T03:33:30.691Z RUNNING Query14
2022-10-05T03:33:31.396Z no activity
2022-10-05T03:33:46.456Z DONE Query14
==========================================================================================
Run started 2022-10-05T03:26:17.598Z and ran for PT448.905S
Default configuration:
{"debug":true,"query":null,"sourceType":"DIRECT","generateEventFilePathPrefix":null,"sinkType":"DEVNULL","exportSummaryToBigQuery":false,"pubSubMode":"COMBINED","pubsubMessageSerializationMethod":"CODER","sideInputType":"DIRECT","sideInputRowCount":500,"sideInputNumShards":3,"sideInputUrl":null,"sessionGap":600000,"numEvents":100000,"numEventGenerators":100,"rateShape":"SINE","firstEventRate":10000,"nextEventRate":10000,"rateUnit":"PER_SECOND","ratePeriodSec":600,"preloadSeconds":0,"streamTimeout":240,"isRateLimited":false,"useWallclockEventTime":false,"avgPersonByteSize":200,"avgAuctionByteSize":500,"avgBidByteSize":100,"hotAuctionRatio":2,"hotSellersRatio":4,"hotBiddersRatio":4,"windowSizeSec":10,"windowPeriodSec":5,"watermarkHoldbackSec":0,"numInFlightAuctions":100,"numActivePeople":1000,"coderStrategy":"HAND","cpuDelayMs":0,"diskBusyBytes":0,"auctionSkip":123,"fanout":5,"maxAuctionsWaitingTime":600,"occasionalDelaySec":3,"probDelayedEvent":0.1,"maxLogEvents":100000,"usePubsubPublishTime":false,"outOfOrderGroupSize":1,"numKeyBuckets":20000,"pardoCPUFactor":1.0}
Configurations:
Conf Description
0000 query:PASSTHROUGH; numEvents:10000000
0001 query:CURRENCY_CONVERSION; numEvents:10000000
0002 query:SELECTION; numEvents:10000000
0003 query:LOCAL_ITEM_SUGGESTION; numEvents:10000000
0004 query:AVERAGE_PRICE_FOR_CATEGORY; numEvents:1000000
0005 query:HOT_ITEMS; numEvents:10000000
0006 query:AVERAGE_SELLING_PRICE_BY_SELLER; numEvents:1000000
0007 query:HIGHEST_BID; numEvents:10000000
0008 query:MONITOR_NEW_USERS; numEvents:10000000
0009 query:WINNING_BIDS; numEvents:1000000
0010 query:LOG_TO_SHARDED_FILES; numEvents:10000000
0011 query:USER_SESSIONS; numEvents:10000000
0012 query:PROCESSING_TIME_WINDOWS; numEvents:10000000
0013 query:PORTABILITY_BATCH; numEvents:10000000
0014 query:RESHUFFLE; numEvents:10000000
0015 query:BOUNDED_SIDE_INPUT_JOIN; numEvents:10000000
0016 query:SESSION_SIDE_INPUT_JOIN; numEvents:10000000
Performance:
Conf Runtime(sec) (Baseline) Events(/sec) (Baseline) Results
(Baseline)
0000 -1.0 -1.0 -1
*** Job was unexpectedly updated ***
0001 -1.0 -1.0 -1
*** Job was unexpectedly updated ***
0002 -1.0 -1.0 -1
*** Job was unexpectedly updated ***
0003 *** not run ***
0004 *** not run ***
0005 -1.0 -1.0 -1
*** Job was unexpectedly updated ***
0006 *** not run ***
0007 *** not run ***
0008 -1.0 -1.0 -1
*** Job was unexpectedly updated ***
0009 *** not run ***
0010 -1.0 -1.0 -1
*** Job was unexpectedly updated ***
0011 -1.0 -1.0 -1
*** Job was unexpectedly updated ***
0012 -1.0 -1.0 -1
*** Job was unexpectedly updated ***
0013 -1.0 -1.0 -1
*** Job was unexpectedly updated ***
0014 115.2 86810.8 10000000
0015 *** not run ***
0016 *** not run ***
==========================================================================================
==========================================================================================
Run started 2022-10-05T03:26:17.598Z and ran for PT449.407S
Default configuration:
{"debug":true,"query":null,"sourceType":"DIRECT","generateEventFilePathPrefix":null,"sinkType":"DEVNULL","exportSummaryToBigQuery":false,"pubSubMode":"COMBINED","pubsubMessageSerializationMethod":"CODER","sideInputType":"DIRECT","sideInputRowCount":500,"sideInputNumShards":3,"sideInputUrl":null,"sessionGap":600000,"numEvents":100000,"numEventGenerators":100,"rateShape":"SINE","firstEventRate":10000,"nextEventRate":10000,"rateUnit":"PER_SECOND","ratePeriodSec":600,"preloadSeconds":0,"streamTimeout":240,"isRateLimited":false,"useWallclockEventTime":false,"avgPersonByteSize":200,"avgAuctionByteSize":500,"avgBidByteSize":100,"hotAuctionRatio":2,"hotSellersRatio":4,"hotBiddersRatio":4,"windowSizeSec":10,"windowPeriodSec":5,"watermarkHoldbackSec":0,"numInFlightAuctions":100,"numActivePeople":1000,"coderStrategy":"HAND","cpuDelayMs":0,"diskBusyBytes":0,"auctionSkip":123,"fanout":5,"maxAuctionsWaitingTime":600,"occasionalDelaySec":3,"probDelayedEvent":0.1,"maxLogEvents":100000,"usePubsubPublishTime":false,"outOfOrderGroupSize":1,"numKeyBuckets":20000,"pardoCPUFactor":1.0}
Configurations:
Conf Description
0000 query:PASSTHROUGH; numEvents:10000000
0001 query:CURRENCY_CONVERSION; numEvents:10000000
0002 query:SELECTION; numEvents:10000000
0003 query:LOCAL_ITEM_SUGGESTION; numEvents:10000000
0004 query:AVERAGE_PRICE_FOR_CATEGORY; numEvents:1000000
0005 query:HOT_ITEMS; numEvents:10000000
0006 query:AVERAGE_SELLING_PRICE_BY_SELLER; numEvents:1000000
0007 query:HIGHEST_BID; numEvents:10000000
0008 query:MONITOR_NEW_USERS; numEvents:10000000
0009 query:WINNING_BIDS; numEvents:1000000
0010 query:LOG_TO_SHARDED_FILES; numEvents:10000000
0011 query:USER_SESSIONS; numEvents:10000000
0012 query:PROCESSING_TIME_WINDOWS; numEvents:10000000
0013 query:PORTABILITY_BATCH; numEvents:10000000
0014 query:RESHUFFLE; numEvents:10000000
0015 query:BOUNDED_SIDE_INPUT_JOIN; numEvents:10000000
0016 query:SESSION_SIDE_INPUT_JOIN; numEvents:10000000
Performance:
Conf Runtime(sec) (Baseline) Events(/sec) (Baseline) Results
(Baseline)
0000 -1.0 -1.0 -1
*** Job was unexpectedly updated ***
0001 -1.0 -1.0 -1
*** Job was unexpectedly updated ***
0002 -1.0 -1.0 -1
*** Job was unexpectedly updated ***
0003 *** not run ***
0004 *** not run ***
0005 -1.0 -1.0 -1
*** Job was unexpectedly updated ***
0006 *** not run ***
0007 *** not run ***
0008 -1.0 -1.0 -1
*** Job was unexpectedly updated ***
0009 *** not run ***
0010 -1.0 -1.0 -1
*** Job was unexpectedly updated ***
0011 -1.0 -1.0 -1
*** Job was unexpectedly updated ***
0012 -1.0 -1.0 -1
*** Job was unexpectedly updated ***
0013 -1.0 -1.0 -1
*** Job was unexpectedly updated ***
0014 115.2 86810.8 10000000
0015 *** not run ***
0016 *** not run ***
==========================================================================================
Exception in thread "main" java.lang.RuntimeException: Execution was not
successful
at org.apache.beam.sdk.nexmark.Main.runAll(Main.java:169)
at org.apache.beam.sdk.nexmark.Main.main(Main.java:441)
> Task :sdks:java:testing:nexmark:run FAILED
> Task :runners:google-cloud-dataflow-java:cleanUpDockerJavaImages
Untagged: us.gcr.io/apache-beam-testing/java-postcommit-it/java:20221005032449
Untagged:
us.gcr.io/apache-beam-testing/java-postcommit-it/java@sha256:d4f1d795d4754f3aa4c138fcb815634715a4cadf56966b0905a864df2b30411f
Tag: [us.gcr.io/apache-beam-testing/java-postcommit-it/java:20221005032449]
- referencing digest:
[us.gcr.io/apache-beam-testing/java-postcommit-it/java@sha256:d4f1d795d4754f3aa4c138fcb815634715a4cadf56966b0905a864df2b30411f]
Deleted [[us.gcr.io/apache-beam-testing/java-postcommit-it/java:20221005032449]
(referencing
[us.gcr.io/apache-beam-testing/java-postcommit-it/java@sha256:d4f1d795d4754f3aa4c138fcb815634715a4cadf56966b0905a864df2b30411f])].
Removing untagged image
us.gcr.io/apache-beam-testing/java-postcommit-it/java@sha256:d4f1d795d4754f3aa4c138fcb815634715a4cadf56966b0905a864df2b30411f
Digests:
-
us.gcr.io/apache-beam-testing/java-postcommit-it/java@sha256:d4f1d795d4754f3aa4c138fcb815634715a4cadf56966b0905a864df2b30411f
Deleted
[us.gcr.io/apache-beam-testing/java-postcommit-it/java@sha256:d4f1d795d4754f3aa4c138fcb815634715a4cadf56966b0905a864df2b30411f].
FAILURE: Build failed with an exception.
* What went wrong:
Execution failed for task ':sdks:java:testing:nexmark:run'.
> Process 'command '/usr/lib/jvm/java-8-openjdk-amd64/bin/java'' 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.
* Get more help at https://help.gradle.org
Deprecated Gradle features were used in this build, making it incompatible with
Gradle 8.0.
You can use '--warning-mode all' to show the individual deprecation warnings
and determine if they come from your own scripts or plugins.
See
https://docs.gradle.org/7.5.1/userguide/command_line_interface.html#sec:command_line_warnings
Execution optimizations have been disabled for 1 invalid unit(s) of work during
this build to ensure correctness.
Please consult deprecation warnings for more details.
BUILD FAILED in 9m 24s
137 actionable tasks: 93 executed, 40 from cache, 4 up-to-date
Publishing build scan...
https://gradle.com/s/7cqvnmzisv66a
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]
