[09:54:35.288] New invocation is queued and will start shortly
[09:54:36.452] Starting the invocation (attempt 1)
[09:54:36.489] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:54:36.489] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:54:36.489] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9019672648155924016",
"parameters_json": "{\"builder_name\":\"Mac deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"63714a9ffe267a0212f4120a188f45408555a993\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Mac deterministic",
"scheduler_invocation_id:9019672648155924016",
"scheduler_job_id:chromium/Mac deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/63714a9ffe267a0212f4120a188f45408555a993",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/63714a9ffe267a0212f4120a188f45408555a993",
"gitiles_ref:refs/heads/master"
]
}
[09:54:37.273] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1614333276640693",
"id": "8854242586071707600",
"parameters_json": "{\"builder_name\": \"Mac deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"63714a9ffe267a0212f4120a188f45408555a993\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614333277029797",
"tags": [
"build_address:luci.chromium.ci/Mac deterministic/44600",
"builder:Mac deterministic",
"buildset:commit/git/63714a9ffe267a0212f4120a188f45408555a993",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/63714a9ffe267a0212f4120a188f45408555a993",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019672648155924016",
"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/8854242586071707600/+/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": "1614333277029911",
"url": "https://ci.chromium.org/b/8854242586071707600",
"utcnow_ts": "1614333277253378"
}
}
[09:54:37.273] Task URL: https://ci.chromium.org/b/8854242586071707600
[09:54:37.273] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:2:0) after 1m0s
[09:54:38.971] Received PubSub notification, asking Buildbucket for the build status
[09:54:39.031] Build 8854242586071707600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:37.320] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:2:0)
[09:55:37.320] Timer tick, asking Buildbucket for the build status
[09:55:37.565] Build 8854242586071707600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:37.565] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:4:0) after 1m0s
[09:56:37.607] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:4:0)
[09:56:37.607] Timer tick, asking Buildbucket for the build status
[09:56:37.697] Build 8854242586071707600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:37.697] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:5:0) after 1m0s
[09:57:37.746] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:5:0)
[09:57:37.746] Timer tick, asking Buildbucket for the build status
[09:57:37.831] Build 8854242586071707600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:37.831] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:6:0) after 1m0s
[09:58:37.849] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:6:0)
[09:58:37.849] Timer tick, asking Buildbucket for the build status
[09:58:37.920] Build 8854242586071707600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:37.920] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:7:0) after 1m0s
[09:59:37.938] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:7:0)
[09:59:37.938] Timer tick, asking Buildbucket for the build status
[09:59:38.012] Build 8854242586071707600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:38.012] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:8:0) after 1m0s
[10:00:38.002] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:8:0)
[10:00:38.002] Timer tick, asking Buildbucket for the build status
[10:00:38.083] Build 8854242586071707600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:38.083] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:9:0) after 1m0s
[10:01:38.105] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:9:0)
[10:01:38.105] Timer tick, asking Buildbucket for the build status
[10:01:38.881] Build 8854242586071707600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:38.881] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:10:0) after 1m0s
[10:02:38.923] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:10:0)
[10:02:38.923] Timer tick, asking Buildbucket for the build status
[10:02:39.251] Build 8854242586071707600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:39.251] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:11:0) after 1m0s
[10:03:39.294] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:11:0)
[10:03:39.294] Timer tick, asking Buildbucket for the build status
[10:03:39.381] Build 8854242586071707600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:39.381] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:12:0) after 1m0s
[10:04:39.414] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:12:0)
[10:04:39.414] Timer tick, asking Buildbucket for the build status
[10:04:39.527] Build 8854242586071707600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:39.527] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:13:0) after 1m0s
[10:05:39.541] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:13:0)
[10:05:39.541] Timer tick, asking Buildbucket for the build status
[10:05:39.610] Build 8854242586071707600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:39.610] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:14:0) after 1m0s
[10:06:39.626] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:14:0)
[10:06:39.626] Timer tick, asking Buildbucket for the build status
[10:06:39.703] Build 8854242586071707600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:39.703] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:15:0) after 1m0s
[10:07:39.703] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:15:0)
[10:07:39.703] Timer tick, asking Buildbucket for the build status
[10:07:39.772] Build 8854242586071707600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:39.772] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:16:0) after 1m0s
[10:08:39.773] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:16:0)
[10:08:39.773] Timer tick, asking Buildbucket for the build status
[10:08:39.897] Build 8854242586071707600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:39.897] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:17:0) after 1m0s
[10:09:39.914] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:17:0)
[10:09:39.914] Timer tick, asking Buildbucket for the build status
[10:09:39.997] Build 8854242586071707600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:39.997] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:18:0) after 1m0s
[10:10:39.997] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:18:0)
[10:10:39.997] Timer tick, asking Buildbucket for the build status
[10:10:40.079] Build 8854242586071707600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:40.079] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:19:0) after 1m0s
[10:11:40.132] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:19:0)
[10:11:40.132] Timer tick, asking Buildbucket for the build status
[10:11:40.199] Build 8854242586071707600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:40.199] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:20:0) after 1m0s
[10:12:40.223] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:20:0)
[10:12:40.223] Timer tick, asking Buildbucket for the build status
[10:12:40.532] Build 8854242586071707600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:40.532] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:21:0) after 1m0s
[10:13:40.547] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:21:0)
[10:13:40.547] Timer tick, asking Buildbucket for the build status
[10:13:40.618] Build 8854242586071707600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:40.618] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:22:0) after 1m0s
[10:14:40.639] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:22:0)
[10:14:40.639] Timer tick, asking Buildbucket for the build status
[10:14:40.737] Build 8854242586071707600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:40.737] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:23:0) after 1m0s
[10:15:40.758] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:23:0)
[10:15:40.758] Timer tick, asking Buildbucket for the build status
[10:15:40.875] Build 8854242586071707600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:40.875] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:24:0) after 1m0s
[10:16:40.926] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:24:0)
[10:16:40.926] Timer tick, asking Buildbucket for the build status
[10:16:41.199] Build 8854242586071707600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:41.199] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:25:0) after 1m0s
[10:17:41.233] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:25:0)
[10:17:41.233] Timer tick, asking Buildbucket for the build status
[10:17:41.669] Build 8854242586071707600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:41.669] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:26:0) after 1m0s
[10:18:41.730] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:26:0)
[10:18:41.730] Timer tick, asking Buildbucket for the build status
[10:18:41.814] Build 8854242586071707600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:41.814] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:27:0) after 1m0s
[10:19:41.871] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:27:0)
[10:19:41.871] Timer tick, asking Buildbucket for the build status
[10:19:42.053] Build 8854242586071707600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:42.053] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:28:0) after 1m0s
[10:20:42.068] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:28:0)
[10:20:42.068] Timer tick, asking Buildbucket for the build status
[10:20:42.146] Build 8854242586071707600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:42.146] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:29:0) after 1m0s
[10:21:42.142] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:29:0)
[10:21:42.142] Timer tick, asking Buildbucket for the build status
[10:21:42.218] Build 8854242586071707600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:42.218] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:30:0) after 1m0s
[10:22:42.327] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:30:0)
[10:22:42.327] Timer tick, asking Buildbucket for the build status
[10:22:42.388] Build 8854242586071707600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:42.388] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:31:0) after 1m0s
[10:23:42.535] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:31:0)
[10:23:42.535] Timer tick, asking Buildbucket for the build status
[10:23:43.405] Build 8854242586071707600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:43.405] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:32:0) after 1m0s
[10:24:43.429] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:32:0)
[10:24:43.429] Timer tick, asking Buildbucket for the build status
[10:24:43.679] Build 8854242586071707600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:43.679] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:33:0) after 1m0s
[10:25:43.784] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:33:0)
[10:25:43.785] Timer tick, asking Buildbucket for the build status
[10:25:43.856] Build 8854242586071707600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:43.856] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:34:0) after 1m0s
[10:26:43.872] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:34:0)
[10:26:43.872] Timer tick, asking Buildbucket for the build status
[10:26:43.954] Build 8854242586071707600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:43.954] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:35:0) after 1m0s
[10:27:43.972] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:35:0)
[10:27:43.972] Timer tick, asking Buildbucket for the build status
[10:27:44.042] Build 8854242586071707600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:44.042] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:36:0) after 1m0s
[10:28:44.086] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:36:0)
[10:28:44.086] Timer tick, asking Buildbucket for the build status
[10:28:44.170] Build 8854242586071707600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:44.170] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:37:0) after 1m0s
[10:29:44.227] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:37:0)
[10:29:44.227] Timer tick, asking Buildbucket for the build status
[10:29:44.477] Build 8854242586071707600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:44.477] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:38:0) after 1m0s
[10:30:44.587] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:38:0)
[10:30:44.587] Timer tick, asking Buildbucket for the build status
[10:30:44.649] Build 8854242586071707600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:44.649] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:39:0) after 1m0s
[10:31:44.709] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:39:0)
[10:31:44.709] Timer tick, asking Buildbucket for the build status
[10:31:44.783] Build 8854242586071707600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:44.783] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:40:0) after 1m0s
[10:32:44.971] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:40:0)
[10:32:44.971] Timer tick, asking Buildbucket for the build status
[10:32:45.038] Build 8854242586071707600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:45.038] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:41:0) after 1m0s
[10:33:45.040] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:41:0)
[10:33:45.040] Timer tick, asking Buildbucket for the build status
[10:33:45.510] Build 8854242586071707600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:45.510] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:42:0) after 1m0s
[10:34:45.714] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:42:0)
[10:34:45.714] Timer tick, asking Buildbucket for the build status
[10:34:46.127] Build 8854242586071707600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:46.127] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:43:0) after 1m0s
[10:35:46.236] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:43:0)
[10:35:46.236] Timer tick, asking Buildbucket for the build status
[10:35:46.313] Build 8854242586071707600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:46.313] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:44:0) after 1m0s
[10:36:46.403] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:44:0)
[10:36:46.403] Timer tick, asking Buildbucket for the build status
[10:36:46.497] Build 8854242586071707600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:46.497] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:45:0) after 1m0s
[10:37:46.503] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:45:0)
[10:37:46.503] Timer tick, asking Buildbucket for the build status
[10:37:46.711] Build 8854242586071707600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:46.711] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:46:0) after 1m0s
[10:38:46.828] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:46:0)
[10:38:46.828] Timer tick, asking Buildbucket for the build status
[10:38:46.971] Build 8854242586071707600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:46.971] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:47:0) after 1m0s
[10:39:47.008] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:47:0)
[10:39:47.008] Timer tick, asking Buildbucket for the build status
[10:39:47.084] Build 8854242586071707600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:47.084] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:48:0) after 1m0s
[10:40:47.170] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:48:0)
[10:40:47.170] Timer tick, asking Buildbucket for the build status
[10:40:47.260] Build 8854242586071707600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:47.260] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:49:0) after 1m0s
[10:41:47.284] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:49:0)
[10:41:47.284] Timer tick, asking Buildbucket for the build status
[10:41:47.376] Build 8854242586071707600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:47.376] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:50:0) after 1m0s
[10:42:47.437] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:50:0)
[10:42:47.437] Timer tick, asking Buildbucket for the build status
[10:42:47.531] Build 8854242586071707600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:47.531] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:51:0) after 1m0s
[10:43:47.646] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:51:0)
[10:43:47.646] Timer tick, asking Buildbucket for the build status
[10:43:47.724] Build 8854242586071707600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:47.724] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:52:0) after 1m0s
[10:44:47.854] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:52:0)
[10:44:47.854] Timer tick, asking Buildbucket for the build status
[10:44:47.970] Build 8854242586071707600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:47.970] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:53:0) after 1m0s
[10:45:48.500] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:53:0)
[10:45:48.500] Timer tick, asking Buildbucket for the build status
[10:45:48.571] Build 8854242586071707600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:48.571] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:54:0) after 1m0s
[10:46:48.706] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:54:0)
[10:46:48.706] Timer tick, asking Buildbucket for the build status
[10:46:48.778] Build 8854242586071707600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:48.778] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:55:0) after 1m0s
[10:47:48.877] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:55:0)
[10:47:48.877] Timer tick, asking Buildbucket for the build status
[10:47:48.938] Build 8854242586071707600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:48.938] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:56:0) after 1m0s
[10:48:49.124] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:56:0)
[10:48:49.124] Timer tick, asking Buildbucket for the build status
[10:48:49.312] Build 8854242586071707600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:49.312] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:57:0) after 1m0s
[10:49:50.023] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:57:0)
[10:49:50.023] Timer tick, asking Buildbucket for the build status
[10:49:50.415] Build 8854242586071707600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:50.415] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:58:0) after 1m0s
[10:50:50.441] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:58:0)
[10:50:50.441] Timer tick, asking Buildbucket for the build status
[10:50:50.510] Build 8854242586071707600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:50.510] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:59:0) after 1m0s
[10:51:50.527] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:59:0)
[10:51:50.527] Timer tick, asking Buildbucket for the build status
[10:51:50.810] Build 8854242586071707600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:50.810] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:60:0) after 1m0s
[10:52:50.827] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:60:0)
[10:52:50.827] Timer tick, asking Buildbucket for the build status
[10:52:50.895] Build 8854242586071707600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:50.895] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:61:0) after 1m0s
[10:53:50.931] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:61:0)
[10:53:50.931] Timer tick, asking Buildbucket for the build status
[10:53:50.997] Build 8854242586071707600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:50.997] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:62:0) after 1m0s
[10:54:51.005] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:62:0)
[10:54:51.005] Timer tick, asking Buildbucket for the build status
[10:54:51.111] Build 8854242586071707600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:51.111] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:63:0) after 1m0s
[10:55:51.164] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:63:0)
[10:55:51.164] Timer tick, asking Buildbucket for the build status
[10:55:51.278] Build 8854242586071707600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:51.278] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:64:0) after 1m0s
[10:56:51.330] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:64:0)
[10:56:51.330] Timer tick, asking Buildbucket for the build status
[10:56:51.685] Build 8854242586071707600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:51.685] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:65:0) after 1m0s
[10:57:51.784] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:65:0)
[10:57:51.784] Timer tick, asking Buildbucket for the build status
[10:57:51.848] Build 8854242586071707600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:51.848] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:66:0) after 1m0s
[10:58:51.926] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:66:0)
[10:58:51.926] Timer tick, asking Buildbucket for the build status
[10:58:52.003] Build 8854242586071707600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:52.003] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:67:0) after 1m0s
[10:59:52.030] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:67:0)
[10:59:52.030] Timer tick, asking Buildbucket for the build status
[10:59:52.181] Build 8854242586071707600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:52.181] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:68:0) after 1m0s
[11:00:52.185] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:68:0)
[11:00:52.185] Timer tick, asking Buildbucket for the build status
[11:00:52.275] Build 8854242586071707600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:52.275] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:69:0) after 1m0s
[11:01:52.293] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:69:0)
[11:01:52.293] Timer tick, asking Buildbucket for the build status
[11:01:52.430] Build 8854242586071707600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:52.430] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9019672648155924016:70:0) after 1m0s
[11:02:52.314] Received PubSub notification, asking Buildbucket for the build status
[11:02:52.394] Build 8854242586071707600: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[11:02:52.394] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1614337371723320",
"created_by": "project:chromium",
"created_ts": "1614333276640693",
"id": "8854242586071707600",
"parameters_json": "{\"builder_name\": \"Mac deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"63714a9ffe267a0212f4120a188f45408555a993\"}}",
"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\": 44600, \"clang_revision\": \"llvmorg-13-init-1559-g01b87444-3\", \"got_angle_revision\": \"d24398b3e36175d08e74261a1a0a2c8a27a00dd6\", \"got_dawn_revision\": \"87649ff09dee357f0412834527e4174b4989358f\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"63714a9ffe267a0212f4120a188f45408555a993\", \"got_revision_cp\": \"refs/heads/master@{#858069}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"349abccec8f7ebd1259de11965d58790a76b0ba4\", \"got_v8_revision\": \"bd0108b4c88e0d6f2350cb79b5f363fbd02f3eb7\", \"got_v8_revision_cp\": \"refs/heads/9.0.257@{#1}\", \"got_webrtc_revision\": \"bb52bdf09516ca548c4aff50526eda561f239bc0\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33341}\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"63714a9ffe267a0212f4120a188f45408555a993\"}, \"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\", \"Mac-10.15\", \"Mac-10.15.7\", \"Mac-10.15.7-19H2\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.16\"], \"server_version\": [\"5712-339372b\"], \"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": "1614333278385524",
"status": "COMPLETED",
"status_changed_ts": "1614337371975094",
"tags": [
"build_address:luci.chromium.ci/Mac deterministic/44600",
"builder:Mac deterministic",
"buildset:commit/git/63714a9ffe267a0212f4120a188f45408555a993",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/63714a9ffe267a0212f4120a188f45408555a993",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019672648155924016",
"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/8854242586071707600/+/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:51f6947cc3c9ec10",
"user_agent:luci-scheduler"
],
"updated_ts": "1614337371994543",
"url": "https://ci.chromium.org/b/8854242586071707600",
"utcnow_ts": "1614337372387539"
}
}
[11:02:52.394] Invocation finished in 1h8m17.124393588s with status SUCCEEDED