[00:42:54.457] New invocation is queued and will start shortly
[00:42:55.696] Starting the invocation (attempt 1)
[00:42:55.740] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:42:55.740] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:42:55.741] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9034202871552357840",
"parameters_json": "{\"builder_name\":\"Mac deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"ef18a93c3a3aa195f3fbc0357b02dd1a85e41edd\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Mac deterministic",
"scheduler_invocation_id:9034202871552357840",
"scheduler_job_id:chromium/Mac deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/ef18a93c3a3aa195f3fbc0357b02dd1a85e41edd",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ef18a93c3a3aa195f3fbc0357b02dd1a85e41edd",
"gitiles_ref:refs/heads/master"
]
}
[00:42:57.727] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1600476175917545",
"id": "8868772809319425760",
"parameters_json": "{\"builder_name\": \"Mac deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"ef18a93c3a3aa195f3fbc0357b02dd1a85e41edd\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1600476177328767",
"tags": [
"build_address:luci.chromium.ci/Mac deterministic/40950",
"builder:Mac deterministic",
"buildset:commit/git/ef18a93c3a3aa195f3fbc0357b02dd1a85e41edd",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ef18a93c3a3aa195f3fbc0357b02dd1a85e41edd",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9034202871552357840",
"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/8868772809319425760/+/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": "1600476177328883",
"url": "https://ci.chromium.org/b/8868772809319425760",
"utcnow_ts": "1600476177706392"
}
}
[00:42:57.727] Task URL: https://ci.chromium.org/b/8868772809319425760
[00:42:57.727] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:2:0) after 1m0s
[00:43:57.850] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:2:0)
[00:43:57.850] Timer tick, asking Buildbucket for the build status
[00:43:58.002] Build 8868772809319425760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:43:58.002] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:3:0) after 1m0s
[00:44:58.029] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:3:0)
[00:44:58.029] Timer tick, asking Buildbucket for the build status
[00:44:58.129] Build 8868772809319425760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:44:58.129] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:4:0) after 1m0s
[00:45:33.352] Received PubSub notification, asking Buildbucket for the build status
[00:45:33.749] Build 8868772809319425760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:58.373] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:4:0)
[00:45:58.373] Timer tick, asking Buildbucket for the build status
[00:45:58.523] Build 8868772809319425760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:58.523] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:6:0) after 1m0s
[00:46:58.604] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:6:0)
[00:46:58.604] Timer tick, asking Buildbucket for the build status
[00:46:59.124] Build 8868772809319425760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:59.124] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:7:0) after 1m0s
[00:47:59.203] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:7:0)
[00:47:59.203] Timer tick, asking Buildbucket for the build status
[00:47:59.320] Build 8868772809319425760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:59.320] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:8:0) after 1m0s
[00:48:59.476] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:8:0)
[00:48:59.476] Timer tick, asking Buildbucket for the build status
[00:48:59.608] Build 8868772809319425760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:59.608] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:9:0) after 1m0s
[00:49:59.772] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:9:0)
[00:49:59.772] Timer tick, asking Buildbucket for the build status
[00:49:59.931] Build 8868772809319425760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:59.931] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:10:0) after 1m0s
[00:50:59.982] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:10:0)
[00:50:59.982] Timer tick, asking Buildbucket for the build status
[00:51:00.118] Build 8868772809319425760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:00.118] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:11:0) after 1m0s
[00:52:00.240] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:11:0)
[00:52:00.240] Timer tick, asking Buildbucket for the build status
[00:52:00.522] Build 8868772809319425760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:00.522] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:12:0) after 1m0s
[00:53:00.652] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:12:0)
[00:53:00.652] Timer tick, asking Buildbucket for the build status
[00:53:00.811] Build 8868772809319425760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:00.811] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:13:0) after 1m0s
[00:54:00.872] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:13:0)
[00:54:00.872] Timer tick, asking Buildbucket for the build status
[00:54:02.073] Build 8868772809319425760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:02.073] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:14:0) after 1m0s
[00:55:02.098] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:14:0)
[00:55:02.098] Timer tick, asking Buildbucket for the build status
[00:55:02.169] Build 8868772809319425760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:02.169] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:15:0) after 1m0s
[00:56:02.196] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:15:0)
[00:56:02.196] Timer tick, asking Buildbucket for the build status
[00:56:02.385] Build 8868772809319425760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:02.385] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:16:0) after 1m0s
[00:57:03.426] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:16:0)
[00:57:03.426] Timer tick, asking Buildbucket for the build status
[00:57:04.023] Build 8868772809319425760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:04.024] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:17:0) after 1m0s
[00:58:04.342] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:17:0)
[00:58:04.342] Timer tick, asking Buildbucket for the build status
[00:58:04.692] Build 8868772809319425760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:04.692] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:18:0) after 1m0s
[00:59:04.861] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:18:0)
[00:59:04.861] Timer tick, asking Buildbucket for the build status
[00:59:05.131] Build 8868772809319425760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:05.131] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:19:0) after 1m0s
[01:00:05.277] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:19:0)
[01:00:05.280] Timer tick, asking Buildbucket for the build status
[01:00:05.654] Build 8868772809319425760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:05.654] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:20:0) after 1m0s
[01:01:05.833] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:20:0)
[01:01:05.833] Timer tick, asking Buildbucket for the build status
[01:01:05.913] Build 8868772809319425760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:05.913] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:21:0) after 1m0s
[01:02:06.116] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:21:0)
[01:02:06.116] Timer tick, asking Buildbucket for the build status
[01:02:06.306] Build 8868772809319425760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:06.306] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:22:0) after 1m0s
[01:03:06.567] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:22:0)
[01:03:06.567] Timer tick, asking Buildbucket for the build status
[01:03:06.833] Build 8868772809319425760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:06.833] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:23:0) after 1m0s
[01:04:06.899] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:23:0)
[01:04:06.899] Timer tick, asking Buildbucket for the build status
[01:04:07.113] Build 8868772809319425760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:07.113] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:24:0) after 1m0s
[01:05:07.227] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:24:0)
[01:05:07.227] Timer tick, asking Buildbucket for the build status
[01:05:07.302] Build 8868772809319425760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:07.302] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:25:0) after 1m0s
[01:06:07.520] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:25:0)
[01:06:07.520] Timer tick, asking Buildbucket for the build status
[01:06:07.626] Build 8868772809319425760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:07.626] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:26:0) after 1m0s
[01:07:07.714] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:26:0)
[01:07:07.714] Timer tick, asking Buildbucket for the build status
[01:07:07.977] Build 8868772809319425760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:07.977] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:27:0) after 1m0s
[01:08:08.047] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:27:0)
[01:08:08.047] Timer tick, asking Buildbucket for the build status
[01:08:08.773] Build 8868772809319425760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:08.773] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:28:0) after 1m0s
[01:09:08.884] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:28:0)
[01:09:08.884] Timer tick, asking Buildbucket for the build status
[01:09:08.950] Build 8868772809319425760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:08.950] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:29:0) after 1m0s
[01:10:09.246] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:29:0)
[01:10:09.246] Timer tick, asking Buildbucket for the build status
[01:10:09.339] Build 8868772809319425760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:09.339] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:30:0) after 1m0s
[01:11:09.773] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:30:0)
[01:11:09.773] Timer tick, asking Buildbucket for the build status
[01:11:09.984] Build 8868772809319425760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:09.984] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:31:0) after 1m0s
[01:12:10.134] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:31:0)
[01:12:10.134] Timer tick, asking Buildbucket for the build status
[01:12:10.403] Build 8868772809319425760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:10.403] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:32:0) after 1m0s
[01:13:10.424] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:32:0)
[01:13:10.424] Timer tick, asking Buildbucket for the build status
[01:13:10.729] Build 8868772809319425760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:10.729] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:33:0) after 1m0s
[01:14:10.852] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:33:0)
[01:14:10.852] Timer tick, asking Buildbucket for the build status
[01:14:10.926] Build 8868772809319425760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:10.926] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:34:0) after 1m0s
[01:15:11.240] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:34:0)
[01:15:11.241] Timer tick, asking Buildbucket for the build status
[01:15:11.412] Build 8868772809319425760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:11.412] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:35:0) after 1m0s
[01:16:11.470] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:35:0)
[01:16:11.470] Timer tick, asking Buildbucket for the build status
[01:16:11.678] Build 8868772809319425760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:11.678] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:36:0) after 1m0s
[01:17:11.911] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:36:0)
[01:17:11.911] Timer tick, asking Buildbucket for the build status
[01:17:11.979] Build 8868772809319425760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:11.979] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:37:0) after 1m0s
[01:18:12.031] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:37:0)
[01:18:12.031] Timer tick, asking Buildbucket for the build status
[01:18:12.350] Build 8868772809319425760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:12.350] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:38:0) after 1m0s
[01:19:12.445] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:38:0)
[01:19:12.445] Timer tick, asking Buildbucket for the build status
[01:19:12.549] Build 8868772809319425760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:12.549] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:39:0) after 1m0s
[01:20:12.789] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:39:0)
[01:20:12.789] Timer tick, asking Buildbucket for the build status
[01:20:12.929] Build 8868772809319425760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:12.929] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:40:0) after 1m0s
[01:21:13.197] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:40:0)
[01:21:13.197] Timer tick, asking Buildbucket for the build status
[01:21:13.260] Build 8868772809319425760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:13.260] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:41:0) after 1m0s
[01:22:13.381] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:41:0)
[01:22:13.381] Timer tick, asking Buildbucket for the build status
[01:22:13.451] Build 8868772809319425760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:13.451] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:42:0) after 1m0s
[01:23:13.631] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:42:0)
[01:23:13.631] Timer tick, asking Buildbucket for the build status
[01:23:14.134] Build 8868772809319425760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:14.134] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:43:0) after 1m0s
[01:24:14.144] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:43:0)
[01:24:14.144] Timer tick, asking Buildbucket for the build status
[01:24:14.602] Build 8868772809319425760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:14.602] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:44:0) after 1m0s
[01:25:14.644] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:44:0)
[01:25:14.644] Timer tick, asking Buildbucket for the build status
[01:25:14.897] Build 8868772809319425760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:14.897] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:45:0) after 1m0s
[01:26:14.957] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:45:0)
[01:26:14.957] Timer tick, asking Buildbucket for the build status
[01:26:15.135] Build 8868772809319425760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:15.135] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:46:0) after 1m0s
[01:27:15.203] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:46:0)
[01:27:15.203] Timer tick, asking Buildbucket for the build status
[01:27:15.707] Build 8868772809319425760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:15.707] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:47:0) after 1m0s
[01:28:15.759] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:47:0)
[01:28:15.760] Timer tick, asking Buildbucket for the build status
[01:28:15.904] Build 8868772809319425760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:15.904] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:48:0) after 1m0s
[01:29:15.988] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:48:0)
[01:29:15.988] Timer tick, asking Buildbucket for the build status
[01:29:16.695] Build 8868772809319425760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:16.695] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:49:0) after 1m0s
[01:30:16.812] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:49:0)
[01:30:16.812] Timer tick, asking Buildbucket for the build status
[01:30:17.272] Build 8868772809319425760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:17.273] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:50:0) after 1m0s
[01:31:17.336] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:50:0)
[01:31:17.336] Timer tick, asking Buildbucket for the build status
[01:31:17.487] Build 8868772809319425760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:17.487] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:51:0) after 1m0s
[01:32:17.580] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:51:0)
[01:32:17.580] Timer tick, asking Buildbucket for the build status
[01:32:17.697] Build 8868772809319425760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:17.697] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:52:0) after 1m0s
[01:33:17.732] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:52:0)
[01:33:17.732] Timer tick, asking Buildbucket for the build status
[01:33:17.857] Build 8868772809319425760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:17.857] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:53:0) after 1m0s
[01:34:17.947] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:53:0)
[01:34:17.947] Timer tick, asking Buildbucket for the build status
[01:34:18.003] Build 8868772809319425760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:18.003] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:54:0) after 1m0s
[01:35:18.028] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:54:0)
[01:35:18.028] Timer tick, asking Buildbucket for the build status
[01:35:18.083] Build 8868772809319425760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:18.083] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:55:0) after 1m0s
[01:36:18.114] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:55:0)
[01:36:18.114] Timer tick, asking Buildbucket for the build status
[01:36:18.201] Build 8868772809319425760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:18.201] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:56:0) after 1m0s
[01:37:18.484] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:56:0)
[01:37:18.484] Timer tick, asking Buildbucket for the build status
[01:37:18.568] Build 8868772809319425760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:18.568] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:57:0) after 1m0s
[01:38:18.555] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:57:0)
[01:38:18.555] Timer tick, asking Buildbucket for the build status
[01:38:18.675] Build 8868772809319425760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:18.675] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:58:0) after 1m0s
[01:39:18.811] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:58:0)
[01:39:18.811] Timer tick, asking Buildbucket for the build status
[01:39:19.075] Build 8868772809319425760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:19.075] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:59:0) after 1m0s
[01:40:19.294] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:59:0)
[01:40:19.294] Timer tick, asking Buildbucket for the build status
[01:40:19.434] Build 8868772809319425760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:19.435] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:60:0) after 1m0s
[01:41:19.529] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:60:0)
[01:41:19.529] Timer tick, asking Buildbucket for the build status
[01:41:19.594] Build 8868772809319425760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:19.594] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:61:0) after 1m0s
[01:42:19.702] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:61:0)
[01:42:19.702] Timer tick, asking Buildbucket for the build status
[01:42:19.773] Build 8868772809319425760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:19.773] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:62:0) after 1m0s
[01:43:19.895] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:62:0)
[01:43:19.895] Timer tick, asking Buildbucket for the build status
[01:43:20.223] Build 8868772809319425760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:20.223] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:63:0) after 1m0s
[01:44:20.324] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:63:0)
[01:44:20.324] Timer tick, asking Buildbucket for the build status
[01:44:20.391] Build 8868772809319425760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:20.391] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:64:0) after 1m0s
[01:45:20.553] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:64:0)
[01:45:20.553] Timer tick, asking Buildbucket for the build status
[01:45:20.629] Build 8868772809319425760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:20.629] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:65:0) after 1m0s
[01:46:20.696] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:65:0)
[01:46:20.696] Timer tick, asking Buildbucket for the build status
[01:46:20.917] Build 8868772809319425760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:20.917] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:66:0) after 1m0s
[01:47:20.933] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:66:0)
[01:47:20.933] Timer tick, asking Buildbucket for the build status
[01:47:20.999] Build 8868772809319425760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:20.999] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:67:0) after 1m0s
[01:48:21.329] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:67:0)
[01:48:21.329] Timer tick, asking Buildbucket for the build status
[01:48:21.495] Build 8868772809319425760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:21.495] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:68:0) after 1m0s
[01:49:21.589] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:68:0)
[01:49:21.589] Timer tick, asking Buildbucket for the build status
[01:49:21.734] Build 8868772809319425760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:21.734] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:69:0) after 1m0s
[01:50:21.899] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:69:0)
[01:50:21.899] Timer tick, asking Buildbucket for the build status
[01:50:22.004] Build 8868772809319425760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:22.004] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:70:0) after 1m0s
[01:51:22.191] Handling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:70:0)
[01:51:22.191] Timer tick, asking Buildbucket for the build status
[01:51:22.258] Build 8868772809319425760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:22.258] Scheduling timer "check-buildbucket-build-status" (chromium/Mac deterministic:9034202871552357840:71:0) after 1m0s
[01:51:36.374] Received PubSub notification, asking Buildbucket for the build status
[01:51:36.486] Build 8868772809319425760: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[01:51:36.486] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1600480294923119",
"created_by": "project:chromium",
"created_ts": "1600476175917545",
"id": "8868772809319425760",
"parameters_json": "{\"builder_name\": \"Mac deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"ef18a93c3a3aa195f3fbc0357b02dd1a85e41edd\"}}",
"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\": \"/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\", \"builder_group\": \"chromium.fyi\", \"buildername\": \"Mac deterministic\", \"buildnumber\": 40950, \"clang_revision\": \"llvmorg-12-init-5627-gf086e85e-2\", \"got_angle_revision\": \"fbab23e18687edcb110cde7e8a3282ee0ccf7033\", \"got_dawn_revision\": \"1da25e51036411a3b328b4574f3bc89b4c482d21\", \"got_nacl_revision\": \"69a0d6e8affc94187af10e0673592d1b238c6eb0\", \"got_revision\": \"ef18a93c3a3aa195f3fbc0357b02dd1a85e41edd\", \"got_revision_cp\": \"refs/heads/master@{#808612}\", \"got_swarming_client_revision\": \"44c13d73156581ea09b9389001e58c23a4b8d70a\", \"got_swiftshader_revision\": \"fe878dedd5addbedab4033c0cb6b19bbbe04f8e4\", \"got_v8_revision\": \"31d58940cd313cddfe6ff2a25206845e2fcddfda\", \"got_v8_revision_cp\": \"refs/heads/8.7.141@{#1}\", \"got_webrtc_revision\": \"788480dcff70560348106b50bc4d57bac92ee16a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32140}\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"ef18a93c3a3aa195f3fbc0357b02dd1a85e41edd\"}, \"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\", \"Mac-10.13.6-17G66\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.10\"], \"server_version\": [\"5531-8ab19d9\"], \"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": "1600476331891680",
"status": "COMPLETED",
"status_changed_ts": "1600480295502148",
"tags": [
"build_address:luci.chromium.ci/Mac deterministic/40950",
"builder:Mac deterministic",
"buildset:commit/git/ef18a93c3a3aa195f3fbc0357b02dd1a85e41edd",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ef18a93c3a3aa195f3fbc0357b02dd1a85e41edd",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9034202871552357840",
"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/8868772809319425760/+/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:4ebca1f3a5e90c10",
"user_agent:luci-scheduler"
],
"updated_ts": "1600480295593408",
"url": "https://ci.chromium.org/b/8868772809319425760",
"utcnow_ts": "1600480296464203"
}
}
[01:51:36.486] Invocation finished in 1h8m42.081893712s with status SUCCEEDED