[13:45:47.646] New invocation is queued and will start shortly
[13:45:48.867] Starting the invocation (attempt 1)
[13:45:48.955] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:45:48.956] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:45:48.956] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9028264813775236832",
"parameters_json": "{\"builder_name\":\"Mac deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"a9fc978e5c06b1f1692a7c08ce241c9d01f95a83\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Mac deterministic",
"scheduler_invocation_id:9028264813775236832",
"scheduler_job_id:chromium/Mac deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/a9fc978e5c06b1f1692a7c08ce241c9d01f95a83",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a9fc978e5c06b1f1692a7c08ce241c9d01f95a83",
"gitiles_ref:refs/heads/master"
]
}
[13:45:49.865] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1606139149039268",
"id": "8862834751615771248",
"parameters_json": "{\"builder_name\": \"Mac deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a9fc978e5c06b1f1692a7c08ce241c9d01f95a83\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1606139149751413",
"tags": [
"build_address:luci.chromium.ci/Mac deterministic/42402",
"builder:Mac deterministic",
"buildset:commit/git/a9fc978e5c06b1f1692a7c08ce241c9d01f95a83",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a9fc978e5c06b1f1692a7c08ce241c9d01f95a83",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028264813775236832",
"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/8862834751615771248/+/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": "1606139149751522",
"url": "https://ci.chromium.org/b/8862834751615771248",
"utcnow_ts": "1606139149857522"
}
}
[13:45:49.865] Task URL: https://ci.chromium.org/b/8862834751615771248
[13:45:49.865] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:2:0) after 1m0s
[13:45:52.335] Received PubSub notification, asking Buildbucket for the build status
[13:45:52.409] Build 8862834751615771248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:49.934] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:2:0)
[13:46:49.934] Timer tick, asking Buildbucket for the build status
[13:46:50.037] Build 8862834751615771248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:50.038] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:4:0) after 1m0s
[13:47:50.093] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:4:0)
[13:47:50.093] Timer tick, asking Buildbucket for the build status
[13:47:50.186] Build 8862834751615771248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:50.187] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:5:0) after 1m0s
[13:48:50.243] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:5:0)
[13:48:50.243] Timer tick, asking Buildbucket for the build status
[13:48:50.443] Build 8862834751615771248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:50.443] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:6:0) after 1m0s
[13:49:50.471] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:6:0)
[13:49:50.471] Timer tick, asking Buildbucket for the build status
[13:49:50.719] Build 8862834751615771248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:50.719] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:7:0) after 1m0s
[13:50:50.773] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:7:0)
[13:50:50.773] Timer tick, asking Buildbucket for the build status
[13:50:51.153] Build 8862834751615771248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:51.153] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:8:0) after 1m0s
[13:51:51.228] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:8:0)
[13:51:51.228] Timer tick, asking Buildbucket for the build status
[13:51:51.749] Build 8862834751615771248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:51.749] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:9:0) after 1m0s
[13:52:51.781] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:9:0)
[13:52:51.781] Timer tick, asking Buildbucket for the build status
[13:52:51.851] Build 8862834751615771248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:51.851] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:10:0) after 1m0s
[13:53:51.861] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:10:0)
[13:53:51.861] Timer tick, asking Buildbucket for the build status
[13:53:52.025] Build 8862834751615771248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:52.025] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:11:0) after 1m0s
[13:54:52.128] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:11:0)
[13:54:52.128] Timer tick, asking Buildbucket for the build status
[13:54:52.211] Build 8862834751615771248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:52.211] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:12:0) after 1m0s
[13:55:52.233] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:12:0)
[13:55:52.233] Timer tick, asking Buildbucket for the build status
[13:55:52.341] Build 8862834751615771248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:52.341] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:13:0) after 1m0s
[13:56:52.387] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:13:0)
[13:56:52.387] Timer tick, asking Buildbucket for the build status
[13:56:52.482] Build 8862834751615771248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:52.482] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:14:0) after 1m0s
[13:57:52.529] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:14:0)
[13:57:52.529] Timer tick, asking Buildbucket for the build status
[13:57:52.630] Build 8862834751615771248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:52.630] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:15:0) after 1m0s
[13:58:52.684] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:15:0)
[13:58:52.684] Timer tick, asking Buildbucket for the build status
[13:58:52.775] Build 8862834751615771248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:52.775] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:16:0) after 1m0s
[13:59:52.829] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:16:0)
[13:59:52.829] Timer tick, asking Buildbucket for the build status
[13:59:52.906] Build 8862834751615771248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:52.906] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:17:0) after 1m0s
[14:00:52.931] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:17:0)
[14:00:52.931] Timer tick, asking Buildbucket for the build status
[14:00:53.039] Build 8862834751615771248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:53.039] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:18:0) after 1m0s
[14:01:53.104] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:18:0)
[14:01:53.104] Timer tick, asking Buildbucket for the build status
[14:01:53.330] Build 8862834751615771248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:53.330] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:19:0) after 1m0s
[14:02:53.366] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:19:0)
[14:02:53.366] Timer tick, asking Buildbucket for the build status
[14:02:53.539] Build 8862834751615771248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:53.539] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:20:0) after 1m0s
[14:03:53.555] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:20:0)
[14:03:53.555] Timer tick, asking Buildbucket for the build status
[14:03:53.634] Build 8862834751615771248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:53.634] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:21:0) after 1m0s
[14:04:53.645] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:21:0)
[14:04:53.645] Timer tick, asking Buildbucket for the build status
[14:04:53.694] Build 8862834751615771248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:53.694] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:22:0) after 1m0s
[14:05:53.766] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:22:0)
[14:05:53.766] Timer tick, asking Buildbucket for the build status
[14:05:53.885] Build 8862834751615771248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:53.885] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:23:0) after 1m0s
[14:06:53.926] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:23:0)
[14:06:53.926] Timer tick, asking Buildbucket for the build status
[14:06:54.057] Build 8862834751615771248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:54.057] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:24:0) after 1m0s
[14:07:54.083] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:24:0)
[14:07:54.083] Timer tick, asking Buildbucket for the build status
[14:07:54.188] Build 8862834751615771248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:54.189] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:25:0) after 1m0s
[14:08:54.216] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:25:0)
[14:08:54.216] Timer tick, asking Buildbucket for the build status
[14:08:54.282] Build 8862834751615771248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:54.282] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:26:0) after 1m0s
[14:09:54.354] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:26:0)
[14:09:54.354] Timer tick, asking Buildbucket for the build status
[14:09:54.435] Build 8862834751615771248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:54.435] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:27:0) after 1m0s
[14:10:54.456] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:27:0)
[14:10:54.456] Timer tick, asking Buildbucket for the build status
[14:10:54.564] Build 8862834751615771248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:54.564] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:28:0) after 1m0s
[14:11:54.608] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:28:0)
[14:11:54.608] Timer tick, asking Buildbucket for the build status
[14:11:55.785] Build 8862834751615771248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:55.785] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:29:0) after 1m0s
[14:12:55.779] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:29:0)
[14:12:55.779] Timer tick, asking Buildbucket for the build status
[14:12:55.847] Build 8862834751615771248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:55.847] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:30:0) after 1m0s
[14:13:55.871] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:30:0)
[14:13:55.871] Timer tick, asking Buildbucket for the build status
[14:13:56.046] Build 8862834751615771248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:56.046] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:31:0) after 1m0s
[14:14:56.073] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:31:0)
[14:14:56.073] Timer tick, asking Buildbucket for the build status
[14:14:56.356] Build 8862834751615771248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:56.358] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:32:0) after 1m0s
[14:15:56.398] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:32:0)
[14:15:56.398] Timer tick, asking Buildbucket for the build status
[14:15:56.559] Build 8862834751615771248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:56.559] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:33:0) after 1m0s
[14:16:56.590] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:33:0)
[14:16:56.590] Timer tick, asking Buildbucket for the build status
[14:16:57.162] Build 8862834751615771248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:57.162] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:34:0) after 1m0s
[14:17:57.226] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:34:0)
[14:17:57.226] Timer tick, asking Buildbucket for the build status
[14:17:57.328] Build 8862834751615771248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:57.328] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:35:0) after 1m0s
[14:18:57.383] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:35:0)
[14:18:57.383] Timer tick, asking Buildbucket for the build status
[14:18:57.455] Build 8862834751615771248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:57.455] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:36:0) after 1m0s
[14:19:57.480] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:36:0)
[14:19:57.480] Timer tick, asking Buildbucket for the build status
[14:19:57.645] Build 8862834751615771248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:57.645] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:37:0) after 1m0s
[14:20:57.672] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:37:0)
[14:20:57.672] Timer tick, asking Buildbucket for the build status
[14:20:57.979] Build 8862834751615771248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:57.980] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:38:0) after 1m0s
[14:21:58.005] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:38:0)
[14:21:58.005] Timer tick, asking Buildbucket for the build status
[14:21:58.093] Build 8862834751615771248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:58.093] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:39:0) after 1m0s
[14:22:58.124] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:39:0)
[14:22:58.124] Timer tick, asking Buildbucket for the build status
[14:22:58.673] Build 8862834751615771248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:58.673] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:40:0) after 1m0s
[14:23:58.722] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:40:0)
[14:23:58.722] Timer tick, asking Buildbucket for the build status
[14:23:58.789] Build 8862834751615771248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:58.789] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:41:0) after 1m0s
[14:24:58.784] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:41:0)
[14:24:58.784] Timer tick, asking Buildbucket for the build status
[14:24:58.857] Build 8862834751615771248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:58.857] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:42:0) after 1m0s
[14:25:58.920] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:42:0)
[14:25:58.920] Timer tick, asking Buildbucket for the build status
[14:25:59.065] Build 8862834751615771248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:59.066] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:43:0) after 1m0s
[14:26:59.111] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:43:0)
[14:26:59.111] Timer tick, asking Buildbucket for the build status
[14:26:59.196] Build 8862834751615771248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:59.196] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:44:0) after 1m0s
[14:27:59.257] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:44:0)
[14:27:59.257] Timer tick, asking Buildbucket for the build status
[14:27:59.320] Build 8862834751615771248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:59.320] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:45:0) after 1m0s
[14:28:59.438] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:45:0)
[14:28:59.438] Timer tick, asking Buildbucket for the build status
[14:28:59.723] Build 8862834751615771248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:59.723] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:46:0) after 1m0s
[14:29:59.849] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:46:0)
[14:29:59.849] Timer tick, asking Buildbucket for the build status
[14:29:59.939] Build 8862834751615771248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:59.939] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:47:0) after 1m0s
[14:30:59.989] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:47:0)
[14:30:59.989] Timer tick, asking Buildbucket for the build status
[14:31:00.235] Build 8862834751615771248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:00.235] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:48:0) after 1m0s
[14:32:01.087] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:48:0)
[14:32:01.087] Timer tick, asking Buildbucket for the build status
[14:32:01.175] Build 8862834751615771248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:01.175] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:49:0) after 1m0s
[14:33:01.349] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:49:0)
[14:33:01.349] Timer tick, asking Buildbucket for the build status
[14:33:01.433] Build 8862834751615771248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:01.433] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:50:0) after 1m0s
[14:34:01.803] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:50:0)
[14:34:01.803] Timer tick, asking Buildbucket for the build status
[14:34:01.879] Build 8862834751615771248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:01.879] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:51:0) after 1m0s
[14:35:02.013] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:51:0)
[14:35:02.013] Timer tick, asking Buildbucket for the build status
[14:35:02.148] Build 8862834751615771248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:02.148] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:52:0) after 1m0s
[14:36:02.247] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:52:0)
[14:36:02.247] Timer tick, asking Buildbucket for the build status
[14:36:02.366] Build 8862834751615771248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:02.366] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:53:0) after 1m0s
[14:37:02.427] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:53:0)
[14:37:02.427] Timer tick, asking Buildbucket for the build status
[14:37:02.512] Build 8862834751615771248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:02.512] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:54:0) after 1m0s
[14:38:02.722] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:54:0)
[14:38:02.722] Timer tick, asking Buildbucket for the build status
[14:38:02.927] Build 8862834751615771248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:02.927] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:55:0) after 1m0s
[14:39:02.999] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:55:0)
[14:39:02.999] Timer tick, asking Buildbucket for the build status
[14:39:03.099] Build 8862834751615771248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:03.099] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:56:0) after 1m0s
[14:40:03.185] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:56:0)
[14:40:03.185] Timer tick, asking Buildbucket for the build status
[14:40:03.505] Build 8862834751615771248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:03.505] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:57:0) after 1m0s
[14:41:03.621] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:57:0)
[14:41:03.621] Timer tick, asking Buildbucket for the build status
[14:41:04.066] Build 8862834751615771248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:04.066] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:58:0) after 1m0s
[14:42:04.105] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:58:0)
[14:42:04.105] Timer tick, asking Buildbucket for the build status
[14:42:04.306] Build 8862834751615771248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:04.306] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:59:0) after 1m0s
[14:43:04.368] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:59:0)
[14:43:04.368] Timer tick, asking Buildbucket for the build status
[14:43:04.622] Build 8862834751615771248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:04.622] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9028264813775236832:60:0) after 1m0s
[14:43:45.295] Received PubSub notification, asking Buildbucket for the build status
[14:43:45.355] Build 8862834751615771248: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[14:43:45.355] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1606142624438302",
"created_by": "project:chromium",
"created_ts": "1606139149039268",
"id": "8862834751615771248",
"parameters_json": "{\"builder_name\": \"Mac deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a9fc978e5c06b1f1692a7c08ce241c9d01f95a83\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/opt/s/w/ir/cache\", \"temp_dir\": \"/opt/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"build204-m9\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.fyi\", \"buildername\": \"Mac deterministic\", \"buildnumber\": 42402, \"clang_revision\": \"llvmorg-12-init-12083-g94e4ec64-1\", \"got_angle_revision\": \"9304577415d5b2cb7e34d357e7e20ab09f523bb0\", \"got_dawn_revision\": \"546a7c98c89718f2dadf1ae07088fa7cf2ccb53f\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"a9fc978e5c06b1f1692a7c08ce241c9d01f95a83\", \"got_revision_cp\": \"refs/heads/master@{#830162}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"6d612051c083238db89541be4fbb2d624a9baef4\", \"got_v8_revision\": \"847989b56465f5aadba45fd4f9c7a33b8d3692cc\", \"got_v8_revision_cp\": \"refs/heads/8.9.33@{#1}\", \"got_webrtc_revision\": \"79348b55df90f3f7d00f12fbc0c679cff147ba81\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32664}\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a9fc978e5c06b1f1692a7c08ce241c9d01f95a83\"}, \"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.15\", \"Mac-10.15.7\", \"Mac-10.15.7-19H2\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.16\"], \"server_version\": [\"5637-4d8b832\"], \"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": "1606139151549187",
"status": "COMPLETED",
"status_changed_ts": "1606142624882602",
"tags": [
"build_address:luci.chromium.ci/Mac deterministic/42402",
"builder:Mac deterministic",
"buildset:commit/git/a9fc978e5c06b1f1692a7c08ce241c9d01f95a83",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a9fc978e5c06b1f1692a7c08ce241c9d01f95a83",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028264813775236832",
"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/8862834751615771248/+/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:500e2c06b7538d10",
"user_agent:luci-scheduler"
],
"updated_ts": "1606142624899827",
"url": "https://ci.chromium.org/b/8862834751615771248",
"utcnow_ts": "1606142625347576"
}
}
[14:43:45.355] Invocation finished in 57m57.758886433s with status SUCCEEDED