[02:26:54.231] New invocation is queued and will start shortly
[02:26:55.496] Starting the invocation (attempt 1)
[02:26:55.569] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:26:55.569] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:26:55.569] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9039994534489213632",
"parameters_json": "{\"builder_name\":\"Mac deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"3ebaaaf871b8c1717c7e584870a2cda58f086a88\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Mac deterministic",
"scheduler_invocation_id:9039994534489213632",
"scheduler_job_id:chromium/Mac deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/3ebaaaf871b8c1717c7e584870a2cda58f086a88",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3ebaaaf871b8c1717c7e584870a2cda58f086a88",
"gitiles_ref:refs/heads/master"
]
}
[02:26:56.283] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1594952815680925",
"id": "8874564472304028784",
"parameters_json": "{\"builder_name\": \"Mac deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3ebaaaf871b8c1717c7e584870a2cda58f086a88\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1594952816085525",
"tags": [
"build_address:luci.chromium.ci/Mac deterministic/39529",
"builder:Mac deterministic",
"buildset:commit/git/3ebaaaf871b8c1717c7e584870a2cda58f086a88",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3ebaaaf871b8c1717c7e584870a2cda58f086a88",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9039994534489213632",
"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/8874564472304028784/+/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": "1594952816085652",
"url": "https://ci.chromium.org/b/8874564472304028784",
"utcnow_ts": "1594952816267950"
}
}
[02:26:56.283] Task URL: https://ci.chromium.org/b/8874564472304028784
[02:26:56.283] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:2:0) after 1m0s
[02:26:57.654] Received PubSub notification, asking Buildbucket for the build status
[02:26:57.809] Build 8874564472304028784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:56.311] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:2:0)
[02:27:56.311] Timer tick, asking Buildbucket for the build status
[02:27:56.431] Build 8874564472304028784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:56.432] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:4:0) after 1m0s
[02:28:56.521] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:4:0)
[02:28:56.521] Timer tick, asking Buildbucket for the build status
[02:28:56.631] Build 8874564472304028784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:56.631] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:5:0) after 1m0s
[02:29:56.673] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:5:0)
[02:29:56.673] Timer tick, asking Buildbucket for the build status
[02:29:56.804] Build 8874564472304028784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:56.804] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:6:0) after 1m0s
[02:30:56.849] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:6:0)
[02:30:56.849] Timer tick, asking Buildbucket for the build status
[02:30:56.981] Build 8874564472304028784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:56.981] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:7:0) after 1m0s
[02:31:57.008] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:7:0)
[02:31:57.008] Timer tick, asking Buildbucket for the build status
[02:31:57.167] Build 8874564472304028784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:57.167] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:8:0) after 1m0s
[02:32:57.235] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:8:0)
[02:32:57.235] Timer tick, asking Buildbucket for the build status
[02:32:57.837] Build 8874564472304028784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:57.837] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:9:0) after 1m0s
[02:33:57.892] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:9:0)
[02:33:57.892] Timer tick, asking Buildbucket for the build status
[02:33:58.219] Build 8874564472304028784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:58.219] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:10:0) after 1m0s
[02:34:58.357] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:10:0)
[02:34:58.357] Timer tick, asking Buildbucket for the build status
[02:34:58.626] Build 8874564472304028784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:58.626] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:11:0) after 1m0s
[02:35:58.672] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:11:0)
[02:35:58.672] Timer tick, asking Buildbucket for the build status
[02:35:58.833] Build 8874564472304028784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:58.833] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:12:0) after 1m0s
[02:36:58.898] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:12:0)
[02:36:58.898] Timer tick, asking Buildbucket for the build status
[02:36:59.272] Build 8874564472304028784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:59.272] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:13:0) after 1m0s
[02:37:59.345] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:13:0)
[02:37:59.345] Timer tick, asking Buildbucket for the build status
[02:37:59.437] Build 8874564472304028784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:59.437] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:14:0) after 1m0s
[02:38:59.490] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:14:0)
[02:38:59.490] Timer tick, asking Buildbucket for the build status
[02:38:59.649] Build 8874564472304028784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:59.649] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:15:0) after 1m0s
[02:39:59.705] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:15:0)
[02:39:59.705] Timer tick, asking Buildbucket for the build status
[02:39:59.911] Build 8874564472304028784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:59.911] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:16:0) after 1m0s
[02:40:59.970] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:16:0)
[02:40:59.970] Timer tick, asking Buildbucket for the build status
[02:41:00.131] Build 8874564472304028784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:00.131] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:17:0) after 1m0s
[02:42:00.190] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:17:0)
[02:42:00.190] Timer tick, asking Buildbucket for the build status
[02:42:00.515] Build 8874564472304028784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:00.515] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:18:0) after 1m0s
[02:43:00.505] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:18:0)
[02:43:00.505] Timer tick, asking Buildbucket for the build status
[02:43:00.984] Build 8874564472304028784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:00.984] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:19:0) after 1m0s
[02:44:01.011] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:19:0)
[02:44:01.011] Timer tick, asking Buildbucket for the build status
[02:44:01.195] Build 8874564472304028784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:01.195] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:20:0) after 1m0s
[02:45:01.550] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:20:0)
[02:45:01.550] Timer tick, asking Buildbucket for the build status
[02:45:02.014] Build 8874564472304028784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:02.014] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:21:0) after 1m0s
[02:46:02.107] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:21:0)
[02:46:02.107] Timer tick, asking Buildbucket for the build status
[02:46:02.235] Build 8874564472304028784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:02.235] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:22:0) after 1m0s
[02:47:02.350] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:22:0)
[02:47:02.350] Timer tick, asking Buildbucket for the build status
[02:47:02.437] Build 8874564472304028784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:02.437] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:23:0) after 1m0s
[02:48:02.444] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:23:0)
[02:48:02.444] Timer tick, asking Buildbucket for the build status
[02:48:02.643] Build 8874564472304028784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:02.643] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:24:0) after 1m0s
[02:49:02.689] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:24:0)
[02:49:02.689] Timer tick, asking Buildbucket for the build status
[02:49:02.744] Build 8874564472304028784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:02.744] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:25:0) after 1m0s
[02:50:02.925] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:25:0)
[02:50:02.925] Timer tick, asking Buildbucket for the build status
[02:50:03.120] Build 8874564472304028784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:03.120] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:26:0) after 1m0s
[02:51:03.147] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:26:0)
[02:51:03.147] Timer tick, asking Buildbucket for the build status
[02:51:03.388] Build 8874564472304028784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:03.388] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:27:0) after 1m0s
[02:52:03.455] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:27:0)
[02:52:03.455] Timer tick, asking Buildbucket for the build status
[02:52:03.559] Build 8874564472304028784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:03.560] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:28:0) after 1m0s
[02:53:03.633] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:28:0)
[02:53:03.633] Timer tick, asking Buildbucket for the build status
[02:53:03.869] Build 8874564472304028784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:03.870] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:29:0) after 1m0s
[02:54:03.891] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:29:0)
[02:54:03.891] Timer tick, asking Buildbucket for the build status
[02:54:04.194] Build 8874564472304028784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:04.194] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:30:0) after 1m0s
[02:55:04.230] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:30:0)
[02:55:04.230] Timer tick, asking Buildbucket for the build status
[02:55:04.308] Build 8874564472304028784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:04.308] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:31:0) after 1m0s
[02:56:04.340] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:31:0)
[02:56:04.340] Timer tick, asking Buildbucket for the build status
[02:56:04.472] Build 8874564472304028784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:04.472] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:32:0) after 1m0s
[02:57:04.501] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:32:0)
[02:57:04.501] Timer tick, asking Buildbucket for the build status
[02:57:04.642] Build 8874564472304028784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:04.642] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:33:0) after 1m0s
[02:58:04.677] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:33:0)
[02:58:04.677] Timer tick, asking Buildbucket for the build status
[02:58:04.987] Build 8874564472304028784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:04.987] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:34:0) after 1m0s
[02:59:05.075] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:34:0)
[02:59:05.075] Timer tick, asking Buildbucket for the build status
[02:59:05.523] Build 8874564472304028784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:05.523] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:35:0) after 1m0s
[03:00:05.642] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:35:0)
[03:00:05.642] Timer tick, asking Buildbucket for the build status
[03:00:06.266] Build 8874564472304028784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:06.266] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:36:0) after 1m0s
[03:01:06.354] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:36:0)
[03:01:06.354] Timer tick, asking Buildbucket for the build status
[03:01:06.839] Build 8874564472304028784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:06.839] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:37:0) after 1m0s
[03:02:06.941] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:37:0)
[03:02:06.941] Timer tick, asking Buildbucket for the build status
[03:02:07.121] Build 8874564472304028784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:07.121] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:38:0) after 1m0s
[03:03:07.181] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:38:0)
[03:03:07.181] Timer tick, asking Buildbucket for the build status
[03:03:07.378] Build 8874564472304028784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:07.378] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:39:0) after 1m0s
[03:04:07.406] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:39:0)
[03:04:07.406] Timer tick, asking Buildbucket for the build status
[03:04:07.550] Build 8874564472304028784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:07.550] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:40:0) after 1m0s
[03:05:07.596] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:40:0)
[03:05:07.596] Timer tick, asking Buildbucket for the build status
[03:05:07.917] Build 8874564472304028784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:07.917] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:41:0) after 1m0s
[03:06:08.069] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:41:0)
[03:06:08.069] Timer tick, asking Buildbucket for the build status
[03:06:08.336] Build 8874564472304028784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:08.336] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:42:0) after 1m0s
[03:07:08.364] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:42:0)
[03:07:08.364] Timer tick, asking Buildbucket for the build status
[03:07:08.553] Build 8874564472304028784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:08.553] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:43:0) after 1m0s
[03:08:08.610] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:43:0)
[03:08:08.610] Timer tick, asking Buildbucket for the build status
[03:08:10.344] Build 8874564472304028784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:10.344] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:44:0) after 1m0s
[03:09:10.426] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:44:0)
[03:09:10.426] Timer tick, asking Buildbucket for the build status
[03:09:10.665] Build 8874564472304028784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:10.665] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:45:0) after 1m0s
[03:10:10.701] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:45:0)
[03:10:10.701] Timer tick, asking Buildbucket for the build status
[03:10:10.838] Build 8874564472304028784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:10.838] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:46:0) after 1m0s
[03:11:10.920] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:46:0)
[03:11:10.920] Timer tick, asking Buildbucket for the build status
[03:11:11.007] Build 8874564472304028784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:11.007] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:47:0) after 1m0s
[03:12:11.148] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:47:0)
[03:12:11.148] Timer tick, asking Buildbucket for the build status
[03:12:11.291] Build 8874564472304028784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:11.291] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:48:0) after 1m0s
[03:13:11.365] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:48:0)
[03:13:11.365] Timer tick, asking Buildbucket for the build status
[03:13:11.514] Build 8874564472304028784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:11.514] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:49:0) after 1m0s
[03:14:11.818] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:49:0)
[03:14:11.818] Timer tick, asking Buildbucket for the build status
[03:14:11.895] Build 8874564472304028784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:11.895] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:50:0) after 1m0s
[03:15:11.926] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:50:0)
[03:15:11.926] Timer tick, asking Buildbucket for the build status
[03:15:12.193] Build 8874564472304028784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:12.193] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:51:0) after 1m0s
[03:16:12.288] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:51:0)
[03:16:12.288] Timer tick, asking Buildbucket for the build status
[03:16:12.485] Build 8874564472304028784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:12.485] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:52:0) after 1m0s
[03:17:12.632] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:52:0)
[03:17:12.632] Timer tick, asking Buildbucket for the build status
[03:17:12.684] Build 8874564472304028784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:12.684] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:53:0) after 1m0s
[03:18:12.776] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:53:0)
[03:18:12.776] Timer tick, asking Buildbucket for the build status
[03:18:13.199] Build 8874564472304028784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:13.199] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:54:0) after 1m0s
[03:19:13.296] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:54:0)
[03:19:13.296] Timer tick, asking Buildbucket for the build status
[03:19:13.473] Build 8874564472304028784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:13.473] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:55:0) after 1m0s
[03:20:13.500] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:55:0)
[03:20:13.500] Timer tick, asking Buildbucket for the build status
[03:20:13.670] Build 8874564472304028784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:13.670] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9039994534489213632:56:0) after 1m0s
[03:20:49.208] Received PubSub notification, asking Buildbucket for the build status
[03:20:49.335] Build 8874564472304028784: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[03:20:49.335] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1594956048248655",
"created_by": "project:chromium",
"created_ts": "1594952815680925",
"id": "8874564472304028784",
"parameters_json": "{\"builder_name\": \"Mac deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3ebaaaf871b8c1717c7e584870a2cda58f086a88\"}}",
"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\": 39529, \"clang_revision\": \"n359864-04b9a46c-1\", \"got_angle_revision\": \"45f54928a6280936f6a2f60aa7a413fb68292604\", \"got_dawn_revision\": \"c15c6eb9d8c37754c189d72b0f4add60cc499bd8\", \"got_nacl_revision\": \"fd20fafa8ec19700e12daa031bf792bae8b6bb21\", \"got_revision\": \"3ebaaaf871b8c1717c7e584870a2cda58f086a88\", \"got_revision_cp\": \"refs/heads/master@{#789332}\", \"got_swarming_client_revision\": \"4c095d04179dc725a300085ae21fe3b79900d072\", \"got_swiftshader_revision\": \"e8dd233c7a85f3c689caf06c226a7f8405a480d3\", \"got_v8_revision\": \"72a5712edeff2f50384d4f57b2267ab41ed8b6f7\", \"got_v8_revision_cp\": \"refs/heads/8.6.163@{#1}\", \"got_webrtc_revision\": \"820021d24600b8628b8644c43fddf67fa37ccf84\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#31746}\", \"mastername\": \"chromium.fyi\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3ebaaaf871b8c1717c7e584870a2cda58f086a88\"}, \"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\": [\"5409-9d77717\"], \"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": "1594952816985871",
"status": "COMPLETED",
"status_changed_ts": "1594956048659933",
"tags": [
"build_address:luci.chromium.ci/Mac deterministic/39529",
"builder:Mac deterministic",
"buildset:commit/git/3ebaaaf871b8c1717c7e584870a2cda58f086a88",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3ebaaaf871b8c1717c7e584870a2cda58f086a88",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9039994534489213632",
"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/8874564472304028784/+/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:4d736a1fdcaa7910",
"user_agent:luci-scheduler"
],
"updated_ts": "1594956048704304",
"url": "https://ci.chromium.org/b/8874564472304028784",
"utcnow_ts": "1594956049321191"
}
}
[03:20:49.335] Invocation finished in 53m55.123504212s with status SUCCEEDED