[06:59:02.267] New invocation is queued and will start shortly
[06:59:03.328] Starting the invocation (attempt 1)
[06:59:03.360] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:59:03.360] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:59:03.360] Buildbucket request:
{
"bucket": "luci.chromium.webrtc.fyi",
"client_operation_id": "9082739181442013728",
"parameters_json": "{\"builder_name\":\"WebRTC Chromium FYI Mac Builder\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://webrtc.googlesource.com/src/\",\"revision\":\"ac1667d1287c44167665215b09482e7b7391849c\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:WebRTC Chromium FYI Mac Builder",
"scheduler_invocation_id:9082739181442013728",
"scheduler_job_id:chromium/WebRTC Chromium FYI Mac Builder",
"user_agent:luci-scheduler",
"buildset:commit/git/ac1667d1287c44167665215b09482e7b7391849c",
"buildset:commit/gitiles/webrtc.googlesource.com/src/+/ac1667d1287c44167665215b09482e7b7391849c",
"gitiles_ref:refs/heads/master"
]
}
[06:59:04.136] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.webrtc.fyi",
"canary_preference": "AUTO",
"created_by": "project:chromium",
"created_ts": "1554188343520975",
"id": "8917309119463717872",
"parameters_json": "{\"builder_name\": \"WebRTC Chromium FYI Mac Builder\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://webrtc.googlesource.com/src/\", \"revision\": \"ac1667d1287c44167665215b09482e7b7391849c\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1554188344035481",
"tags": [
"build_address:luci.chromium.webrtc.fyi/WebRTC Chromium FYI Mac Builder/2564",
"builder:WebRTC Chromium FYI Mac Builder",
"buildset:commit/git/ac1667d1287c44167665215b09482e7b7391849c",
"buildset:commit/gitiles/webrtc.googlesource.com/src/+/ac1667d1287c44167665215b09482e7b7391849c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9082739181442013728",
"scheduler_job_id:chromium/WebRTC Chromium FYI Mac Builder",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8917309119463717872/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1554188344035570",
"url": "https://ci.chromium.org/b/8917309119463717872",
"utcnow_ts": "1554188344126990"
}
}
[06:59:04.136] Task URL: https://ci.chromium.org/b/8917309119463717872
[06:59:04.136] Scheduling timer "check-buildbucket-build-status" (chromium/WebRTC Chromium FYI Mac Builder:9082739181442013728:2:0) after 1m0s
[06:59:58.853] Received PubSub notification, asking Buildbucket for the build status
[06:59:58.932] Build 8917309119463717872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:04.128] Handling timer "check-buildbucket-build-status" (chromium/WebRTC Chromium FYI Mac Builder:9082739181442013728:2:0)
[07:00:04.128] Timer tick, asking Buildbucket for the build status
[07:00:04.258] Build 8917309119463717872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:04.258] Scheduling timer "check-buildbucket-build-status" (chromium/WebRTC Chromium FYI Mac Builder:9082739181442013728:4:0) after 1m0s
[07:01:04.268] Handling timer "check-buildbucket-build-status" (chromium/WebRTC Chromium FYI Mac Builder:9082739181442013728:4:0)
[07:01:04.268] Timer tick, asking Buildbucket for the build status
[07:01:04.426] Build 8917309119463717872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:04.426] Scheduling timer "check-buildbucket-build-status" (chromium/WebRTC Chromium FYI Mac Builder:9082739181442013728:5:0) after 1m0s
[07:02:04.453] Handling timer "check-buildbucket-build-status" (chromium/WebRTC Chromium FYI Mac Builder:9082739181442013728:5:0)
[07:02:04.453] Timer tick, asking Buildbucket for the build status
[07:02:04.765] Build 8917309119463717872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:04.765] Scheduling timer "check-buildbucket-build-status" (chromium/WebRTC Chromium FYI Mac Builder:9082739181442013728:6:0) after 1m0s
[07:03:04.797] Handling timer "check-buildbucket-build-status" (chromium/WebRTC Chromium FYI Mac Builder:9082739181442013728:6:0)
[07:03:04.797] Timer tick, asking Buildbucket for the build status
[07:03:04.976] Build 8917309119463717872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:04.976] Scheduling timer "check-buildbucket-build-status" (chromium/WebRTC Chromium FYI Mac Builder:9082739181442013728:7:0) after 1m0s
[07:04:04.983] Handling timer "check-buildbucket-build-status" (chromium/WebRTC Chromium FYI Mac Builder:9082739181442013728:7:0)
[07:04:04.983] Timer tick, asking Buildbucket for the build status
[07:04:05.102] Build 8917309119463717872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:05.102] Scheduling timer "check-buildbucket-build-status" (chromium/WebRTC Chromium FYI Mac Builder:9082739181442013728:8:0) after 1m0s
[07:05:05.127] Handling timer "check-buildbucket-build-status" (chromium/WebRTC Chromium FYI Mac Builder:9082739181442013728:8:0)
[07:05:05.127] Timer tick, asking Buildbucket for the build status
[07:05:05.219] Build 8917309119463717872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:05.219] Scheduling timer "check-buildbucket-build-status" (chromium/WebRTC Chromium FYI Mac Builder:9082739181442013728:9:0) after 1m0s
[07:06:05.261] Handling timer "check-buildbucket-build-status" (chromium/WebRTC Chromium FYI Mac Builder:9082739181442013728:9:0)
[07:06:05.262] Timer tick, asking Buildbucket for the build status
[07:06:05.410] Build 8917309119463717872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:05.410] Scheduling timer "check-buildbucket-build-status" (chromium/WebRTC Chromium FYI Mac Builder:9082739181442013728:10:0) after 1m0s
[07:07:05.434] Handling timer "check-buildbucket-build-status" (chromium/WebRTC Chromium FYI Mac Builder:9082739181442013728:10:0)
[07:07:05.434] Timer tick, asking Buildbucket for the build status
[07:07:05.646] Build 8917309119463717872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:05.646] Scheduling timer "check-buildbucket-build-status" (chromium/WebRTC Chromium FYI Mac Builder:9082739181442013728:11:0) after 1m0s
[07:08:05.666] Handling timer "check-buildbucket-build-status" (chromium/WebRTC Chromium FYI Mac Builder:9082739181442013728:11:0)
[07:08:05.666] Timer tick, asking Buildbucket for the build status
[07:08:05.842] Build 8917309119463717872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:05.842] Scheduling timer "check-buildbucket-build-status" (chromium/WebRTC Chromium FYI Mac Builder:9082739181442013728:12:0) after 1m0s
[07:09:05.846] Handling timer "check-buildbucket-build-status" (chromium/WebRTC Chromium FYI Mac Builder:9082739181442013728:12:0)
[07:09:05.846] Timer tick, asking Buildbucket for the build status
[07:09:05.926] Build 8917309119463717872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:05.926] Scheduling timer "check-buildbucket-build-status" (chromium/WebRTC Chromium FYI Mac Builder:9082739181442013728:13:0) after 1m0s
[07:10:05.915] Handling timer "check-buildbucket-build-status" (chromium/WebRTC Chromium FYI Mac Builder:9082739181442013728:13:0)
[07:10:05.915] Timer tick, asking Buildbucket for the build status
[07:10:06.162] Build 8917309119463717872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:06.162] Scheduling timer "check-buildbucket-build-status" (chromium/WebRTC Chromium FYI Mac Builder:9082739181442013728:14:0) after 1m0s
[07:11:06.182] Handling timer "check-buildbucket-build-status" (chromium/WebRTC Chromium FYI Mac Builder:9082739181442013728:14:0)
[07:11:06.182] Timer tick, asking Buildbucket for the build status
[07:11:06.345] Build 8917309119463717872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:06.345] Scheduling timer "check-buildbucket-build-status" (chromium/WebRTC Chromium FYI Mac Builder:9082739181442013728:15:0) after 1m0s
[07:12:06.345] Handling timer "check-buildbucket-build-status" (chromium/WebRTC Chromium FYI Mac Builder:9082739181442013728:15:0)
[07:12:06.345] Timer tick, asking Buildbucket for the build status
[07:12:06.418] Build 8917309119463717872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:06.418] Scheduling timer "check-buildbucket-build-status" (chromium/WebRTC Chromium FYI Mac Builder:9082739181442013728:16:0) after 1m0s
[07:13:06.436] Handling timer "check-buildbucket-build-status" (chromium/WebRTC Chromium FYI Mac Builder:9082739181442013728:16:0)
[07:13:06.436] Timer tick, asking Buildbucket for the build status
[07:13:06.515] Build 8917309119463717872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:06.515] Scheduling timer "check-buildbucket-build-status" (chromium/WebRTC Chromium FYI Mac Builder:9082739181442013728:17:0) after 1m0s
[07:14:06.518] Handling timer "check-buildbucket-build-status" (chromium/WebRTC Chromium FYI Mac Builder:9082739181442013728:17:0)
[07:14:06.518] Timer tick, asking Buildbucket for the build status
[07:14:06.803] Build 8917309119463717872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:06.803] Scheduling timer "check-buildbucket-build-status" (chromium/WebRTC Chromium FYI Mac Builder:9082739181442013728:18:0) after 1m0s
[07:15:06.818] Handling timer "check-buildbucket-build-status" (chromium/WebRTC Chromium FYI Mac Builder:9082739181442013728:18:0)
[07:15:06.818] Timer tick, asking Buildbucket for the build status
[07:15:07.053] Build 8917309119463717872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:07.053] Scheduling timer "check-buildbucket-build-status" (chromium/WebRTC Chromium FYI Mac Builder:9082739181442013728:19:0) after 1m0s
[07:16:07.075] Handling timer "check-buildbucket-build-status" (chromium/WebRTC Chromium FYI Mac Builder:9082739181442013728:19:0)
[07:16:07.075] Timer tick, asking Buildbucket for the build status
[07:16:07.171] Build 8917309119463717872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:07.171] Scheduling timer "check-buildbucket-build-status" (chromium/WebRTC Chromium FYI Mac Builder:9082739181442013728:20:0) after 1m0s
[07:17:07.190] Handling timer "check-buildbucket-build-status" (chromium/WebRTC Chromium FYI Mac Builder:9082739181442013728:20:0)
[07:17:07.190] Timer tick, asking Buildbucket for the build status
[07:17:07.323] Build 8917309119463717872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:07.323] Scheduling timer "check-buildbucket-build-status" (chromium/WebRTC Chromium FYI Mac Builder:9082739181442013728:21:0) after 1m0s
[07:18:07.326] Handling timer "check-buildbucket-build-status" (chromium/WebRTC Chromium FYI Mac Builder:9082739181442013728:21:0)
[07:18:07.326] Timer tick, asking Buildbucket for the build status
[07:18:07.447] Build 8917309119463717872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:07.447] Scheduling timer "check-buildbucket-build-status" (chromium/WebRTC Chromium FYI Mac Builder:9082739181442013728:22:0) after 1m0s
[07:18:16.035] Received PubSub notification, asking Buildbucket for the build status
[07:18:16.189] Build 8917309119463717872: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[07:18:16.189] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.webrtc.fyi",
"canary_preference": "AUTO",
"completed_ts": "1554189495654217",
"created_by": "project:chromium",
"created_ts": "1554188343520975",
"id": "8917309119463717872",
"parameters_json": "{\"builder_name\": \"WebRTC Chromium FYI Mac Builder\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://webrtc.googlesource.com/src/\", \"revision\": \"ac1667d1287c44167665215b09482e7b7391849c\"}}",
"project": "chromium",
"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\": \"vm323-m9\", \"branch\": \"refs/heads/master\", \"buildername\": \"WebRTC Chromium FYI Mac Builder\", \"buildnumber\": 2564, \"got_angle_revision\": \"6cb0cff573768d72d06aef02926d0d92ffb02635\", \"got_clang_revision\": \"357316-1\", \"got_cr_revision\": \"96ea50545831e521f4f651bc3fc1760d97250935\", \"got_cr_revision_cp\": \"refs/heads/master@{#646684}\", \"got_dawn_revision\": \"4f5fc2d325b9263c41bf7bdcac656be775065ceb\", \"got_nacl_revision\": \"de8da4841bc3d1ed020d798d5a7fd6fb3d817fa1\", \"got_revision\": \"ac1667d1287c44167665215b09482e7b7391849c\", \"got_revision_cp\": \"refs/heads/master@{#27407}\", \"got_swarming_client_revision\": \"aa60736aded9fc32a0e21a81f5fc51f6009d01f3\", \"got_v8_revision\": \"51a4744b12dc0fae0d5577634e73133dc87158a9\", \"got_v8_revision_cp\": \"refs/heads/7.5.157@{#1}\", \"got_webrtc_revision\": \"ac1667d1287c44167665215b09482e7b7391849c\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#27407}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.webrtc.fyi\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://webrtc.googlesource.com/src/\", \"revision\": \"ac1667d1287c44167665215b09482e7b7391849c\", \"swarm_hashes_refs/heads/master(at){#27407}_without_patch\": {\"browser_tests\": \"03e3081f5c00e11a681cbd2956a5cf4a8bb3d841\", \"browser_tests_apprtc\": \"4cb506cc3eb7a315d950439ee8a7059a1b89367e\", \"content_browsertests\": \"f33c9c871c634f0123e186823ad54902c46b3ef0\", \"content_unittests\": \"5013de6fdf850ae6d54124a5ff635d7b875a16ad\", \"jingle_unittests\": \"14f55375ed7a34343913d7a7832487a5bf6199fe\", \"remoting_unittests\": \"7414736161eb03a7ef9c988b78ee57f2e7030350\"}}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_4d315a21d49582f6ac1269241de18b93ec0f808a2ae7b2201a57612ac5fb468f_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-E5-2697_v2\"], \"gce\": [\"0\"], \"gpu\": [\"none\"], \"id\": [\"vm323-m9\"], \"mac_model\": [\"VMware7,1\"], \"machine_type\": [\"n1-highcpu-8\"], \"os\": [\"Mac\", \"Mac-10.13\", \"Mac-10.13.6\"], \"pool\": [\"luci.chromium.webrtc.fyi\"], \"python\": [\"2.7.10\"], \"server_version\": [\"4231-d78bb73\"], \"ssd\": [\"1\"], \"zone\": [\"us\", \"us-golo\", \"us-golo-9\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1554188398373597",
"status": "COMPLETED",
"status_changed_ts": "1554189495876776",
"tags": [
"build_address:luci.chromium.webrtc.fyi/WebRTC Chromium FYI Mac Builder/2564",
"builder:WebRTC Chromium FYI Mac Builder",
"buildset:commit/git/ac1667d1287c44167665215b09482e7b7391849c",
"buildset:commit/gitiles/webrtc.googlesource.com/src/+/ac1667d1287c44167665215b09482e7b7391849c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9082739181442013728",
"scheduler_job_id:chromium/WebRTC Chromium FYI Mac Builder",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8917309119463717872/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Mac",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:43f5a9a3ae509810",
"user_agent:luci-scheduler"
],
"updated_ts": "1554189495890584",
"url": "https://ci.chromium.org/b/8917309119463717872",
"utcnow_ts": "1554189496173876"
}
}
[07:18:16.189] Invocation finished in 19m13.938956253s with status SUCCEEDED