[16:36:56.230] New invocation is queued and will start shortly
[16:36:57.427] Starting the invocation (attempt 1)
[16:36:57.470] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:36:57.470] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:36:57.470] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9041843591311370304",
"parameters_json": "{\"builder_name\":\"Mac deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"bbe95b2f02d65dec36a43f4289f1270705119a67\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Mac deterministic",
"scheduler_invocation_id:9041843591311370304",
"scheduler_job_id:chromium/Mac deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/bbe95b2f02d65dec36a43f4289f1270705119a67",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bbe95b2f02d65dec36a43f4289f1270705119a67",
"gitiles_ref:refs/heads/master"
]
}
[16:36:58.645] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1593189417686695",
"id": "8876413529118760736",
"parameters_json": "{\"builder_name\": \"Mac deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"bbe95b2f02d65dec36a43f4289f1270705119a67\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1593189418442660",
"tags": [
"build_address:luci.chromium.ci/Mac deterministic/38912",
"builder:Mac deterministic",
"buildset:commit/git/bbe95b2f02d65dec36a43f4289f1270705119a67",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bbe95b2f02d65dec36a43f4289f1270705119a67",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9041843591311370304",
"scheduler_job_id:chromium/Mac deterministic",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8876413529118760736/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:recipe_name:swarming/deterministic_build",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1593189418442798",
"url": "https://ci.chromium.org/b/8876413529118760736",
"utcnow_ts": "1593189418635502"
}
}
[16:36:58.645] Task URL: https://ci.chromium.org/b/8876413529118760736
[16:36:58.645] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:2:0) after 1m0s
[16:37:02.420] Received PubSub notification, asking Buildbucket for the build status
[16:37:02.596] Build 8876413529118760736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:59.764] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:2:0)
[16:37:59.764] Timer tick, asking Buildbucket for the build status
[16:37:59.893] Build 8876413529118760736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:59.893] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:4:0) after 1m0s
[16:39:00.012] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:4:0)
[16:39:00.012] Timer tick, asking Buildbucket for the build status
[16:39:00.301] Build 8876413529118760736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:00.301] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:5:0) after 1m0s
[16:40:00.352] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:5:0)
[16:40:00.352] Timer tick, asking Buildbucket for the build status
[16:40:00.696] Build 8876413529118760736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:00.696] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:6:0) after 1m0s
[16:41:01.566] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:6:0)
[16:41:01.567] Timer tick, asking Buildbucket for the build status
[16:41:01.725] Build 8876413529118760736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:01.725] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:7:0) after 1m0s
[16:42:01.720] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:7:0)
[16:42:01.720] Timer tick, asking Buildbucket for the build status
[16:42:01.866] Build 8876413529118760736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:01.866] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:8:0) after 1m0s
[16:43:02.094] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:8:0)
[16:43:02.094] Timer tick, asking Buildbucket for the build status
[16:43:02.245] Build 8876413529118760736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:02.245] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:9:0) after 1m0s
[16:44:02.590] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:9:0)
[16:44:02.590] Timer tick, asking Buildbucket for the build status
[16:44:02.758] Build 8876413529118760736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:02.758] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:10:0) after 1m0s
[16:45:02.840] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:10:0)
[16:45:02.840] Timer tick, asking Buildbucket for the build status
[16:45:02.977] Build 8876413529118760736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:02.977] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:11:0) after 1m0s
[16:46:03.038] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:11:0)
[16:46:03.038] Timer tick, asking Buildbucket for the build status
[16:46:05.568] Build 8876413529118760736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:05.568] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:12:0) after 1m0s
[16:47:05.603] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:12:0)
[16:47:05.603] Timer tick, asking Buildbucket for the build status
[16:47:05.730] Build 8876413529118760736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:05.730] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:13:0) after 1m0s
[16:48:06.067] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:13:0)
[16:48:06.067] Timer tick, asking Buildbucket for the build status
[16:48:06.426] Build 8876413529118760736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:06.426] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:14:0) after 1m0s
[16:49:06.608] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:14:0)
[16:49:06.608] Timer tick, asking Buildbucket for the build status
[16:49:06.991] Build 8876413529118760736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:06.991] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:15:0) after 1m0s
[16:50:07.017] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:15:0)
[16:50:07.017] Timer tick, asking Buildbucket for the build status
[16:50:07.584] Build 8876413529118760736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:07.584] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:16:0) after 1m0s
[16:51:07.722] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:16:0)
[16:51:07.722] Timer tick, asking Buildbucket for the build status
[16:51:07.820] Build 8876413529118760736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:07.820] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:17:0) after 1m0s
[16:52:07.955] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:17:0)
[16:52:07.955] Timer tick, asking Buildbucket for the build status
[16:52:08.313] Build 8876413529118760736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:08.314] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:18:0) after 1m0s
[16:53:08.442] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:18:0)
[16:53:08.442] Timer tick, asking Buildbucket for the build status
[16:53:08.634] Build 8876413529118760736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:08.634] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:19:0) after 1m0s
[16:54:08.667] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:19:0)
[16:54:08.667] Timer tick, asking Buildbucket for the build status
[16:54:08.941] Build 8876413529118760736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:08.941] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:20:0) after 1m0s
[16:55:09.012] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:20:0)
[16:55:09.012] Timer tick, asking Buildbucket for the build status
[16:55:09.206] Build 8876413529118760736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:09.206] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:21:0) after 1m0s
[16:56:09.301] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:21:0)
[16:56:09.301] Timer tick, asking Buildbucket for the build status
[16:56:09.461] Build 8876413529118760736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:09.461] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:22:0) after 1m0s
[16:57:10.283] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:22:0)
[16:57:10.286] Timer tick, asking Buildbucket for the build status
[16:57:10.553] Build 8876413529118760736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:10.553] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:23:0) after 1m0s
[16:58:10.684] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:23:0)
[16:58:10.684] Timer tick, asking Buildbucket for the build status
[16:58:11.121] Build 8876413529118760736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:11.121] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:24:0) after 1m0s
[16:59:11.157] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:24:0)
[16:59:11.157] Timer tick, asking Buildbucket for the build status
[16:59:11.363] Build 8876413529118760736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:11.363] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:25:0) after 1m0s
[17:00:11.412] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:25:0)
[17:00:11.412] Timer tick, asking Buildbucket for the build status
[17:00:12.497] Build 8876413529118760736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:12.497] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:26:0) after 1m0s
[17:01:12.534] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:26:0)
[17:01:12.534] Timer tick, asking Buildbucket for the build status
[17:01:12.630] Build 8876413529118760736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:12.630] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:27:0) after 1m0s
[17:02:12.742] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:27:0)
[17:02:12.742] Timer tick, asking Buildbucket for the build status
[17:02:12.867] Build 8876413529118760736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:12.867] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:28:0) after 1m0s
[17:03:12.870] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:28:0)
[17:03:12.870] Timer tick, asking Buildbucket for the build status
[17:03:13.044] Build 8876413529118760736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:13.044] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:29:0) after 1m0s
[17:04:14.152] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:29:0)
[17:04:14.152] Timer tick, asking Buildbucket for the build status
[17:04:14.338] Build 8876413529118760736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:14.338] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:30:0) after 1m0s
[17:05:15.706] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:30:0)
[17:05:15.706] Timer tick, asking Buildbucket for the build status
[17:05:16.001] Build 8876413529118760736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:16.001] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:31:0) after 1m0s
[17:06:16.202] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:31:0)
[17:06:16.202] Timer tick, asking Buildbucket for the build status
[17:06:17.052] Build 8876413529118760736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:17.052] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:32:0) after 1m0s
[17:07:18.647] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:32:0)
[17:07:18.647] Timer tick, asking Buildbucket for the build status
[17:07:18.820] Build 8876413529118760736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:18.820] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:33:0) after 1m0s
[17:08:18.883] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:33:0)
[17:08:18.883] Timer tick, asking Buildbucket for the build status
[17:08:19.028] Build 8876413529118760736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:19.028] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:34:0) after 1m0s
[17:09:19.214] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:34:0)
[17:09:19.214] Timer tick, asking Buildbucket for the build status
[17:09:19.408] Build 8876413529118760736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:19.408] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:35:0) after 1m0s
[17:10:19.462] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:35:0)
[17:10:19.462] Timer tick, asking Buildbucket for the build status
[17:10:19.614] Build 8876413529118760736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:19.614] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:36:0) after 1m0s
[17:11:19.633] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:36:0)
[17:11:19.633] Timer tick, asking Buildbucket for the build status
[17:11:19.745] Build 8876413529118760736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:19.745] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:37:0) after 1m0s
[17:12:19.774] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:37:0)
[17:12:19.774] Timer tick, asking Buildbucket for the build status
[17:12:20.130] Build 8876413529118760736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:20.130] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:38:0) after 1m0s
[17:13:20.827] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:38:0)
[17:13:20.827] Timer tick, asking Buildbucket for the build status
[17:13:20.949] Build 8876413529118760736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:20.949] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:39:0) after 1m0s
[17:14:21.076] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:39:0)
[17:14:21.076] Timer tick, asking Buildbucket for the build status
[17:14:23.766] Build 8876413529118760736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:23.766] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:40:0) after 1m0s
[17:15:24.325] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:40:0)
[17:15:24.325] Timer tick, asking Buildbucket for the build status
[17:15:24.581] Build 8876413529118760736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:24.581] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:41:0) after 1m0s
[17:16:24.621] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:41:0)
[17:16:24.621] Timer tick, asking Buildbucket for the build status
[17:16:24.730] Build 8876413529118760736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:24.730] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:42:0) after 1m0s
[17:17:25.176] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:42:0)
[17:17:25.176] Timer tick, asking Buildbucket for the build status
[17:17:25.309] Build 8876413529118760736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:25.309] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:43:0) after 1m0s
[17:18:25.683] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:43:0)
[17:18:25.683] Timer tick, asking Buildbucket for the build status
[17:18:25.834] Build 8876413529118760736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:25.834] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:44:0) after 1m0s
[17:19:25.903] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:44:0)
[17:19:25.903] Timer tick, asking Buildbucket for the build status
[17:19:27.033] Build 8876413529118760736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:27.033] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:45:0) after 1m0s
[17:20:27.072] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:45:0)
[17:20:27.072] Timer tick, asking Buildbucket for the build status
[17:20:27.470] Build 8876413529118760736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:27.470] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:46:0) after 1m0s
[17:21:27.486] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:46:0)
[17:21:27.486] Timer tick, asking Buildbucket for the build status
[17:21:27.647] Build 8876413529118760736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:27.647] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:47:0) after 1m0s
[17:22:27.707] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:47:0)
[17:22:27.707] Timer tick, asking Buildbucket for the build status
[17:22:27.836] Build 8876413529118760736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:27.836] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:48:0) after 1m0s
[17:23:27.964] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:48:0)
[17:23:27.964] Timer tick, asking Buildbucket for the build status
[17:23:28.740] Build 8876413529118760736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:28.740] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:49:0) after 1m0s
[17:24:28.763] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:49:0)
[17:24:28.763] Timer tick, asking Buildbucket for the build status
[17:24:29.298] Build 8876413529118760736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:29.298] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:50:0) after 1m0s
[17:25:29.327] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:50:0)
[17:25:29.327] Timer tick, asking Buildbucket for the build status
[17:25:29.657] Build 8876413529118760736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:29.657] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9041843591311370304:51:0) after 1m0s
[17:26:24.338] Received PubSub notification, asking Buildbucket for the build status
[17:26:24.405] Build 8876413529118760736: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[17:26:24.405] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1593192381581464",
"created_by": "project:chromium",
"created_ts": "1593189417686695",
"id": "8876413529118760736",
"parameters_json": "{\"builder_name\": \"Mac deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"bbe95b2f02d65dec36a43f4289f1270705119a67\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$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\": \"build204-m9\", \"branch\": \"refs/heads/master\", \"buildername\": \"Mac deterministic\", \"buildnumber\": 38912, \"clang_revision\": \"n356902-4e813bbd-1\", \"got_angle_revision\": \"c9f311e46255f65f5aa72beee8c70dca515f10d3\", \"got_dawn_revision\": \"ff90599cdf600e48d5868f8b3d35698686f5b161\", \"got_nacl_revision\": \"fd20fafa8ec19700e12daa031bf792bae8b6bb21\", \"got_revision\": \"bbe95b2f02d65dec36a43f4289f1270705119a67\", \"got_revision_cp\": \"refs/heads/master@{#783023}\", \"got_swarming_client_revision\": \"4c095d04179dc725a300085ae21fe3b79900d072\", \"got_swiftshader_revision\": \"eb0a25a189b75d950ae4f87c00f8d63f190d82a0\", \"got_v8_revision\": \"a7f8bc434b35c3122a859f094fa541febd74ec7c\", \"got_v8_revision_cp\": \"refs/heads/8.5.210@{#1}\", \"got_webrtc_revision\": \"d43c3788d782a88ff1db37fbac24cb0f9776ab0d\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#31572}\", \"mastername\": \"chromium.fyi\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"bbe95b2f02d65dec36a43f4289f1270705119a67\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"Mac deterministic\"], \"caches\": [\"builder_7b52a3a1245e897041cdcf338a3870df21540919220c864c03da3b37f2e3c589_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"24\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-E5-2697_v2\"], \"gce\": [\"0\"], \"gpu\": [\"1002\", \"1002:679e\", \"1002:679e-4.0.11-3.2.8\"], \"hidpi\": [\"0\"], \"id\": [\"build204-m9\"], \"mac_model\": [\"MacPro6,1\"], \"machine_type\": [\"n1-standard-16\"], \"os\": [\"Mac\", \"Mac-10.13\", \"Mac-10.13.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.10\"], \"server_version\": [\"5218-123b763\"], \"ssd\": [\"1\"], \"zone\": [\"us\", \"us-atl\", \"us-atl-golo\", \"us-atl-golo-m9\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1593189421469285",
"status": "COMPLETED",
"status_changed_ts": "1593192383518981",
"tags": [
"build_address:luci.chromium.ci/Mac deterministic/38912",
"builder:Mac deterministic",
"buildset:commit/git/bbe95b2f02d65dec36a43f4289f1270705119a67",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bbe95b2f02d65dec36a43f4289f1270705119a67",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9041843591311370304",
"scheduler_job_id:chromium/Mac deterministic",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8876413529118760736/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Mac",
"swarming_tag:recipe_name:swarming/deterministic_build",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:4d0a4ecff14be210",
"user_agent:luci-scheduler"
],
"updated_ts": "1593192383639504",
"url": "https://ci.chromium.org/b/8876413529118760736",
"utcnow_ts": "1593192384397438"
}
}
[17:26:24.405] Invocation finished in 49m28.194671929s with status SUCCEEDED