[16:04:18.888] New invocation is queued and will start shortly
[16:04:20.567] Starting the invocation (attempt 1)
[16:04:20.603] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:04:20.603] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:04:20.603] Buildbucket request:
{
"bucket": "luci.webrtc.ci",
"client_operation_id": "9078175027256257888",
"parameters_json": "{\"builder_name\":\"Mac64 Debug\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://webrtc.googlesource.com/src\",\"revision\":\"646fda0212b17f55b73e0f52f455fc48a019558a\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Mac64 Debug",
"scheduler_invocation_id:9078175027256257888",
"scheduler_job_id:webrtc/Mac64 Debug",
"user_agent:luci-scheduler",
"buildset:commit/git/646fda0212b17f55b73e0f52f455fc48a019558a",
"buildset:commit/gitiles/webrtc.googlesource.com/src/+/646fda0212b17f55b73e0f52f455fc48a019558a",
"gitiles_ref:refs/heads/master"
]
}
[16:04:22.913] Buildbucket response:
{
"build": {
"bucket": "luci.webrtc.ci",
"canary_preference": "AUTO",
"created_by": "project:webrtc",
"created_ts": "1558541060852170",
"id": "8912744964534441104",
"parameters_json": "{\"builder_name\": \"Mac64 Debug\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://webrtc.googlesource.com/src\", \"revision\": \"646fda0212b17f55b73e0f52f455fc48a019558a\"}}",
"project": "webrtc",
"result_details_json": "{\"properties\": {}}",
"service_account": "webrtc-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1558541062637125",
"tags": [
"build_address:luci.webrtc.ci/Mac64 Debug/19431",
"builder:Mac64 Debug",
"buildset:commit/git/646fda0212b17f55b73e0f52f455fc48a019558a",
"buildset:commit/gitiles/webrtc.googlesource.com/src/+/646fda0212b17f55b73e0f52f455fc48a019558a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9078175027256257888",
"scheduler_job_id:webrtc/Mac64 Debug",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/webrtc/buildbucket/cr-buildbucket.appspot.com/8912744964534441104/+/annotations",
"swarming_tag:luci_project:webrtc",
"swarming_tag:recipe_name:webrtc/standalone",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1558541062637252",
"url": "https://ci.chromium.org/b/8912744964534441104",
"utcnow_ts": "1558541062894078"
}
}
[16:04:22.913] Task URL: https://ci.chromium.org/b/8912744964534441104
[16:04:22.913] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac64 Debug:9078175027256257888:2:0) after 1m0s
[16:05:22.929] Handling timer "check-buildbucket-build-status" (webrtc/Mac64 Debug:9078175027256257888:2:0)
[16:05:22.929] Timer tick, asking Buildbucket for the build status
[16:05:23.391] Build 8912744964534441104: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:05:23.391] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac64 Debug:9078175027256257888:3:0) after 1m0s
[16:06:23.404] Handling timer "check-buildbucket-build-status" (webrtc/Mac64 Debug:9078175027256257888:3:0)
[16:06:23.404] Timer tick, asking Buildbucket for the build status
[16:06:23.744] Build 8912744964534441104: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:06:23.744] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac64 Debug:9078175027256257888:4:0) after 1m0s
[16:07:23.762] Handling timer "check-buildbucket-build-status" (webrtc/Mac64 Debug:9078175027256257888:4:0)
[16:07:23.762] Timer tick, asking Buildbucket for the build status
[16:07:23.920] Build 8912744964534441104: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:07:23.920] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac64 Debug:9078175027256257888:5:0) after 1m0s
[16:08:25.267] Handling timer "check-buildbucket-build-status" (webrtc/Mac64 Debug:9078175027256257888:5:0)
[16:08:25.267] Timer tick, asking Buildbucket for the build status
[16:08:26.450] Build 8912744964534441104: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:08:26.450] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac64 Debug:9078175027256257888:6:0) after 1m0s
[16:09:27.159] Handling timer "check-buildbucket-build-status" (webrtc/Mac64 Debug:9078175027256257888:6:0)
[16:09:27.159] Timer tick, asking Buildbucket for the build status
[16:09:28.835] Build 8912744964534441104: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:09:28.835] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac64 Debug:9078175027256257888:7:0) after 1m0s
[16:10:29.049] Handling timer "check-buildbucket-build-status" (webrtc/Mac64 Debug:9078175027256257888:7:0)
[16:10:29.049] Timer tick, asking Buildbucket for the build status
[16:10:29.887] Build 8912744964534441104: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:10:29.887] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac64 Debug:9078175027256257888:8:0) after 1m0s
[16:11:30.355] Handling timer "check-buildbucket-build-status" (webrtc/Mac64 Debug:9078175027256257888:8:0)
[16:11:30.356] Timer tick, asking Buildbucket for the build status
[16:11:30.884] Build 8912744964534441104: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:11:30.884] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac64 Debug:9078175027256257888:9:0) after 1m0s
[16:12:30.908] Handling timer "check-buildbucket-build-status" (webrtc/Mac64 Debug:9078175027256257888:9:0)
[16:12:30.908] Timer tick, asking Buildbucket for the build status
[16:12:30.977] Build 8912744964534441104: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:12:30.977] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac64 Debug:9078175027256257888:10:0) after 1m0s
[16:13:31.062] Handling timer "check-buildbucket-build-status" (webrtc/Mac64 Debug:9078175027256257888:10:0)
[16:13:31.062] Timer tick, asking Buildbucket for the build status
[16:13:31.447] Build 8912744964534441104: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:13:31.447] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac64 Debug:9078175027256257888:11:0) after 1m0s
[16:14:31.747] Handling timer "check-buildbucket-build-status" (webrtc/Mac64 Debug:9078175027256257888:11:0)
[16:14:31.747] Timer tick, asking Buildbucket for the build status
[16:14:32.471] Build 8912744964534441104: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:14:32.471] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac64 Debug:9078175027256257888:12:0) after 1m0s
[16:15:32.490] Handling timer "check-buildbucket-build-status" (webrtc/Mac64 Debug:9078175027256257888:12:0)
[16:15:32.490] Timer tick, asking Buildbucket for the build status
[16:15:32.988] Build 8912744964534441104: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:15:32.988] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac64 Debug:9078175027256257888:13:0) after 1m0s
[16:16:32.985] Handling timer "check-buildbucket-build-status" (webrtc/Mac64 Debug:9078175027256257888:13:0)
[16:16:32.985] Timer tick, asking Buildbucket for the build status
[16:16:33.115] Build 8912744964534441104: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:16:33.115] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac64 Debug:9078175027256257888:14:0) after 1m0s
[16:17:33.140] Handling timer "check-buildbucket-build-status" (webrtc/Mac64 Debug:9078175027256257888:14:0)
[16:17:33.140] Timer tick, asking Buildbucket for the build status
[16:17:33.562] Build 8912744964534441104: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:17:33.562] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac64 Debug:9078175027256257888:15:0) after 1m0s
[16:18:33.657] Handling timer "check-buildbucket-build-status" (webrtc/Mac64 Debug:9078175027256257888:15:0)
[16:18:33.657] Timer tick, asking Buildbucket for the build status
[16:18:40.876] Build 8912744964534441104: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:18:40.876] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac64 Debug:9078175027256257888:16:0) after 1m0s
[16:19:31.909] Received PubSub notification, asking Buildbucket for the build status
[16:19:31.960] Build 8912744964534441104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:40.992] Handling timer "check-buildbucket-build-status" (webrtc/Mac64 Debug:9078175027256257888:16:0)
[16:19:40.992] Timer tick, asking Buildbucket for the build status
[16:19:41.543] Build 8912744964534441104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:41.543] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac64 Debug:9078175027256257888:18:0) after 1m0s
[16:20:41.567] Handling timer "check-buildbucket-build-status" (webrtc/Mac64 Debug:9078175027256257888:18:0)
[16:20:41.567] Timer tick, asking Buildbucket for the build status
[16:20:41.755] Build 8912744964534441104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:41.755] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac64 Debug:9078175027256257888:19:0) after 1m0s
[16:21:41.774] Handling timer "check-buildbucket-build-status" (webrtc/Mac64 Debug:9078175027256257888:19:0)
[16:21:41.774] Timer tick, asking Buildbucket for the build status
[16:21:42.754] Build 8912744964534441104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:42.754] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac64 Debug:9078175027256257888:20:0) after 1m0s
[16:22:42.747] Handling timer "check-buildbucket-build-status" (webrtc/Mac64 Debug:9078175027256257888:20:0)
[16:22:42.747] Timer tick, asking Buildbucket for the build status
[16:22:43.020] Build 8912744964534441104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:43.020] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac64 Debug:9078175027256257888:21:0) after 1m0s
[16:23:43.059] Handling timer "check-buildbucket-build-status" (webrtc/Mac64 Debug:9078175027256257888:21:0)
[16:23:43.059] Timer tick, asking Buildbucket for the build status
[16:23:43.322] Build 8912744964534441104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:43.322] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac64 Debug:9078175027256257888:22:0) after 1m0s
[16:24:43.348] Handling timer "check-buildbucket-build-status" (webrtc/Mac64 Debug:9078175027256257888:22:0)
[16:24:43.348] Timer tick, asking Buildbucket for the build status
[16:24:43.501] Build 8912744964534441104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:43.501] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac64 Debug:9078175027256257888:23:0) after 1m0s
[16:25:43.530] Handling timer "check-buildbucket-build-status" (webrtc/Mac64 Debug:9078175027256257888:23:0)
[16:25:43.530] Timer tick, asking Buildbucket for the build status
[16:25:43.751] Build 8912744964534441104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:43.751] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac64 Debug:9078175027256257888:24:0) after 1m0s
[16:26:43.756] Handling timer "check-buildbucket-build-status" (webrtc/Mac64 Debug:9078175027256257888:24:0)
[16:26:43.756] Timer tick, asking Buildbucket for the build status
[16:26:44.746] Build 8912744964534441104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:44.746] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac64 Debug:9078175027256257888:25:0) after 1m0s
[16:27:44.771] Handling timer "check-buildbucket-build-status" (webrtc/Mac64 Debug:9078175027256257888:25:0)
[16:27:44.771] Timer tick, asking Buildbucket for the build status
[16:27:46.379] Build 8912744964534441104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:46.379] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac64 Debug:9078175027256257888:26:0) after 1m0s
[16:28:46.424] Handling timer "check-buildbucket-build-status" (webrtc/Mac64 Debug:9078175027256257888:26:0)
[16:28:46.424] Timer tick, asking Buildbucket for the build status
[16:28:46.672] Build 8912744964534441104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:46.672] Scheduling timer "check-buildbucket-build-status" (webrtc/Mac64 Debug:9078175027256257888:27:0) after 1m0s
[16:29:27.205] Received PubSub notification, asking Buildbucket for the build status
[16:29:28.201] Build 8912744964534441104: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[16:29:28.201] Buildbucket build:
{
"build": {
"bucket": "luci.webrtc.ci",
"canary_preference": "AUTO",
"completed_ts": "1558542566316598",
"created_by": "project:webrtc",
"created_ts": "1558541060852170",
"id": "8912744964534441104",
"parameters_json": "{\"builder_name\": \"Mac64 Debug\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://webrtc.googlesource.com/src\", \"revision\": \"646fda0212b17f55b73e0f52f455fc48a019558a\"}}",
"project": "webrtc",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"vm322-m9\", \"branch\": \"refs/heads/master\", \"buildername\": \"Mac64 Debug\", \"buildnumber\": 19431, \"got_clang_revision\": \"361212-1\", \"got_revision\": \"646fda0212b17f55b73e0f52f455fc48a019558a\", \"got_revision_cp\": \"refs/heads/master@{#28028}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"client.webrtc\", \"path_config\": \"generic\", \"recipe\": \"webrtc/standalone\", \"repository\": \"https://webrtc.googlesource.com/src\", \"revision\": \"646fda0212b17f55b73e0f52f455fc48a019558a\", \"swarm_hashes\": {\"audio_decoder_unittests\": \"f1463d6c2dbb1d1567a11ebe5b6df0c85fd7d004\", \"common_audio_unittests\": \"0605128ccdec32bacb1cedbec14c7fc1c268e972\", \"common_video_unittests\": \"4f833e0748777e1d09e5f21004ac9e9b4e3991af\", \"low_bandwidth_audio_test\": \"7b84f40824a819fa334120b734ab0345281cb055\", \"modules_tests\": \"afd7546e7ab5c972e10bd66a88432848e3712ab5\", \"modules_unittests\": \"d63656b070dac66b28701d7fea493d53f65a6694\", \"peerconnection_unittests\": \"797aec0cf8d35c61d22998e433fca2b5e93d428d\", \"rtc_media_unittests\": \"ecc2c4f539a109f2e9a880fa3777f9808aa33e80\", \"rtc_pc_unittests\": \"67130d4f2bad0450c84f40fa37db4654456f49ff\", \"rtc_stats_unittests\": \"3370a0bbf66b11d32c8a7be37c35b7299f5607ab\", \"rtc_unittests\": \"6118c135477e47a208bc8cbc323eee7570cf937e\", \"slow_tests\": \"027a1dceb9fad692dfa140eccb0355456e66460b\", \"system_wrappers_unittests\": \"bf6dee2bde4b347dea99f8b307e37b03694e7275\", \"test_support_unittests\": \"8d51ab22aa7f5e7a77bb1bbccb33ead091c9f65d\", \"tools_unittests\": \"def89568d91230ff87248d806d4a39c2afaff896\", \"video_engine_tests\": \"aed84e686b01933f61914f46416b95224c820008\", \"webrtc_nonparallel_tests\": \"5c94ccfe6637d81705a26ae4051a6aa29616cca5\"}}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_059806cb76369fad874f65dcf15d9fac8b85f8aaa2bcc21181e3047888c9f722_v2\", \"builder_061ad2c84e63fc0fec4b05c132093ef9b6ab75b59928be3b662c8e2e0a6344fd_v2\", \"builder_08dcb1b73883f7e211b0b3bd789c28dc0ecd4dbbececafe484581157878e0b74_v2\", \"builder_159efc1d4e07f83357b1af150b22b5735146a4a38107e0dc728b750b9a5ddd95_v2\", \"builder_2187023a95b72a4c85b14150e8627786d4d0f124144eef86bf927816a2830941_v2\", \"builder_3d12c2f33c8b539e7425f6bc13584a1590c81aeb2114d2a2ac69b6615cd68c43_v2\", \"builder_43f7e9f5ae24b6ee3ca22209fec6b34c1a00e60d041ce7cae040782e455433aa_v2\", \"builder_5be86841ba1a2fffbf39b2203b40a6ecce8d6cb8ff7379bf32e5204ecbef59b2_v2\", \"builder_63601768ebaa6550931251d01f66fd42a511d047ab36f27e7a12ce2bed95fdde_v2\", \"builder_af73c5fcdc7c8d0d83ff5fdfb91effdc54af69c7f411ef9f3eba46b34978fd88_v2\", \"builder_d425412af15739f7858c3ec48c0cf46fd8a190c41fc9a14b966839c948b4f9be_v2\", \"git\", \"goma_v2\", \"osx_sdk\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-E5-2697_v2\"], \"gce\": [\"0\"], \"gpu\": [\"none\"], \"id\": [\"vm322-m9\"], \"mac_model\": [\"VMware7,1\"], \"machine_type\": [\"n1-highcpu-8\"], \"os\": [\"Mac\", \"Mac-10.13\", \"Mac-10.13.6\"], \"pool\": [\"luci.webrtc.ci\"], \"python\": [\"2.7.10\"], \"server_version\": [\"4303-bae210e\"], \"ssd\": [\"1\"], \"zone\": [\"us\", \"us-golo\", \"us-golo-9\"]}}}",
"service_account": "webrtc-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1558541970429997",
"status": "COMPLETED",
"status_changed_ts": "1558542566773286",
"tags": [
"build_address:luci.webrtc.ci/Mac64 Debug/19431",
"builder:Mac64 Debug",
"buildset:commit/git/646fda0212b17f55b73e0f52f455fc48a019558a",
"buildset:commit/gitiles/webrtc.googlesource.com/src/+/646fda0212b17f55b73e0f52f455fc48a019558a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9078175027256257888",
"scheduler_job_id:webrtc/Mac64 Debug",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/webrtc/buildbucket/cr-buildbucket.appspot.com/8912744964534441104/+/annotations",
"swarming_tag:luci_project:webrtc",
"swarming_tag:os:Mac",
"swarming_tag:recipe_name:webrtc/standalone",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:44f91add3b42cb10",
"user_agent:luci-scheduler"
],
"updated_ts": "1558542566775741",
"url": "https://ci.chromium.org/b/8912744964534441104",
"utcnow_ts": "1558542568165763"
}
}
[16:29:28.201] Invocation finished in 25m9.327756174s with status SUCCEEDED