[14:26:56.314] New invocation is queued and will start shortly
[14:26:57.540] Starting the invocation (attempt 1)
[14:26:57.616] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:26:57.616] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:26:57.616] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9050096094064305776",
"parameters_json": "{\"builder_name\":\"Deterministic Linux\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"a054471cd0732e4d032e578710196ff04c93887e\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Deterministic Linux",
"scheduler_invocation_id:9050096094064305776",
"scheduler_job_id:chromium/Deterministic Linux",
"user_agent:luci-scheduler",
"buildset:commit/git/a054471cd0732e4d032e578710196ff04c93887e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a054471cd0732e4d032e578710196ff04c93887e",
"gitiles_ref:refs/heads/master"
]
}
[14:26:58.365] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1585319217698542",
"id": "8884666031941375696",
"parameters_json": "{\"builder_name\": \"Deterministic Linux\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a054471cd0732e4d032e578710196ff04c93887e\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1585319218217125",
"tags": [
"build_address:luci.chromium.ci/Deterministic Linux/27318",
"builder:Deterministic Linux",
"buildset:commit/git/a054471cd0732e4d032e578710196ff04c93887e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a054471cd0732e4d032e578710196ff04c93887e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9050096094064305776",
"scheduler_job_id:chromium/Deterministic Linux",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8884666031941375696/+/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": "1585319218217274",
"url": "https://ci.chromium.org/b/8884666031941375696",
"utcnow_ts": "1585319218348161"
}
}
[14:26:58.365] Task URL: https://ci.chromium.org/b/8884666031941375696
[14:26:58.365] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:2:0) after 1m0s
[14:27:58.392] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:2:0)
[14:27:58.392] Timer tick, asking Buildbucket for the build status
[14:27:58.501] Build 8884666031941375696: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:27:58.501] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:3:0) after 1m0s
[14:28:58.522] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:3:0)
[14:28:58.522] Timer tick, asking Buildbucket for the build status
[14:28:58.724] Build 8884666031941375696: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:28:58.724] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:4:0) after 1m0s
[14:29:58.752] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:4:0)
[14:29:58.752] Timer tick, asking Buildbucket for the build status
[14:29:58.938] Build 8884666031941375696: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:29:58.938] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:5:0) after 1m0s
[14:30:58.963] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:5:0)
[14:30:58.963] Timer tick, asking Buildbucket for the build status
[14:30:59.121] Build 8884666031941375696: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:30:59.121] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:6:0) after 1m0s
[14:31:06.665] Received PubSub notification, asking Buildbucket for the build status
[14:31:06.768] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:59.191] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:6:0)
[14:31:59.191] Timer tick, asking Buildbucket for the build status
[14:31:59.369] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:59.369] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:8:0) after 1m0s
[14:32:59.413] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:8:0)
[14:32:59.413] Timer tick, asking Buildbucket for the build status
[14:32:59.507] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:59.507] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:9:0) after 1m0s
[14:33:59.519] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:9:0)
[14:33:59.519] Timer tick, asking Buildbucket for the build status
[14:33:59.715] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:59.715] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:10:0) after 1m0s
[14:34:59.768] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:10:0)
[14:34:59.768] Timer tick, asking Buildbucket for the build status
[14:34:59.952] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:59.952] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:11:0) after 1m0s
[14:35:59.960] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:11:0)
[14:35:59.960] Timer tick, asking Buildbucket for the build status
[14:36:00.135] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:00.135] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:12:0) after 1m0s
[14:37:00.130] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:12:0)
[14:37:00.130] Timer tick, asking Buildbucket for the build status
[14:37:00.283] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:00.283] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:13:0) after 1m0s
[14:38:00.331] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:13:0)
[14:38:00.331] Timer tick, asking Buildbucket for the build status
[14:38:00.527] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:00.527] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:14:0) after 1m0s
[14:39:00.730] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:14:0)
[14:39:00.730] Timer tick, asking Buildbucket for the build status
[14:39:01.006] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:01.006] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:15:0) after 1m0s
[14:40:01.088] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:15:0)
[14:40:01.088] Timer tick, asking Buildbucket for the build status
[14:40:01.253] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:01.253] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:16:0) after 1m0s
[14:41:01.357] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:16:0)
[14:41:01.357] Timer tick, asking Buildbucket for the build status
[14:41:01.534] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:01.534] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:17:0) after 1m0s
[14:42:01.586] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:17:0)
[14:42:01.586] Timer tick, asking Buildbucket for the build status
[14:42:01.929] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:01.929] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:18:0) after 1m0s
[14:43:01.981] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:18:0)
[14:43:01.981] Timer tick, asking Buildbucket for the build status
[14:43:02.082] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:02.082] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:19:0) after 1m0s
[14:44:02.105] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:19:0)
[14:44:02.105] Timer tick, asking Buildbucket for the build status
[14:44:02.656] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:02.656] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:20:0) after 1m0s
[14:45:02.658] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:20:0)
[14:45:02.658] Timer tick, asking Buildbucket for the build status
[14:45:02.927] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:02.927] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:21:0) after 1m0s
[14:46:02.983] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:21:0)
[14:46:02.983] Timer tick, asking Buildbucket for the build status
[14:46:03.136] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:03.136] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:22:0) after 1m0s
[14:47:03.196] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:22:0)
[14:47:03.196] Timer tick, asking Buildbucket for the build status
[14:47:03.415] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:03.415] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:23:0) after 1m0s
[14:48:03.443] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:23:0)
[14:48:03.443] Timer tick, asking Buildbucket for the build status
[14:48:03.588] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:03.588] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:24:0) after 1m0s
[14:49:03.680] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:24:0)
[14:49:03.680] Timer tick, asking Buildbucket for the build status
[14:49:04.039] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:04.039] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:25:0) after 1m0s
[14:50:04.035] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:25:0)
[14:50:04.035] Timer tick, asking Buildbucket for the build status
[14:50:04.288] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:04.288] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:26:0) after 1m0s
[14:51:04.351] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:26:0)
[14:51:04.351] Timer tick, asking Buildbucket for the build status
[14:51:04.499] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:04.499] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:27:0) after 1m0s
[14:52:04.528] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:27:0)
[14:52:04.528] Timer tick, asking Buildbucket for the build status
[14:52:04.743] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:04.743] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:28:0) after 1m0s
[14:53:04.747] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:28:0)
[14:53:04.747] Timer tick, asking Buildbucket for the build status
[14:53:04.926] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:04.926] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:29:0) after 1m0s
[14:54:05.037] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:29:0)
[14:54:05.037] Timer tick, asking Buildbucket for the build status
[14:54:05.131] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:05.131] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:30:0) after 1m0s
[14:55:05.268] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:30:0)
[14:55:05.268] Timer tick, asking Buildbucket for the build status
[14:55:05.430] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:05.430] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:31:0) after 1m0s
[14:56:05.597] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:31:0)
[14:56:05.597] Timer tick, asking Buildbucket for the build status
[14:56:05.747] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:05.747] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:32:0) after 1m0s
[14:57:05.951] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:32:0)
[14:57:05.951] Timer tick, asking Buildbucket for the build status
[14:57:06.137] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:06.137] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:33:0) after 1m0s
[14:58:06.193] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:33:0)
[14:58:06.193] Timer tick, asking Buildbucket for the build status
[14:58:06.352] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:06.352] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:34:0) after 1m0s
[14:59:06.384] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:34:0)
[14:59:06.384] Timer tick, asking Buildbucket for the build status
[14:59:06.648] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:06.648] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:35:0) after 1m0s
[15:00:06.694] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:35:0)
[15:00:06.694] Timer tick, asking Buildbucket for the build status
[15:00:08.011] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:08.011] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:36:0) after 1m0s
[15:01:08.244] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:36:0)
[15:01:08.244] Timer tick, asking Buildbucket for the build status
[15:01:08.425] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:08.425] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:37:0) after 1m0s
[15:02:08.530] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:37:0)
[15:02:08.530] Timer tick, asking Buildbucket for the build status
[15:02:08.727] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:08.727] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:38:0) after 1m0s
[15:03:08.943] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:38:0)
[15:03:08.943] Timer tick, asking Buildbucket for the build status
[15:03:09.092] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:09.092] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:39:0) after 1m0s
[15:04:09.192] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:39:0)
[15:04:09.192] Timer tick, asking Buildbucket for the build status
[15:04:09.440] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:09.440] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:40:0) after 1m0s
[15:05:09.580] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:40:0)
[15:05:09.580] Timer tick, asking Buildbucket for the build status
[15:05:09.780] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:09.780] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:41:0) after 1m0s
[15:06:09.960] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:41:0)
[15:06:09.960] Timer tick, asking Buildbucket for the build status
[15:06:10.182] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:10.182] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:42:0) after 1m0s
[15:07:10.238] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:42:0)
[15:07:10.238] Timer tick, asking Buildbucket for the build status
[15:07:10.589] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:10.589] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:43:0) after 1m0s
[15:08:10.642] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:43:0)
[15:08:10.643] Timer tick, asking Buildbucket for the build status
[15:08:10.889] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:10.889] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:44:0) after 1m0s
[15:09:11.024] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:44:0)
[15:09:11.024] Timer tick, asking Buildbucket for the build status
[15:09:11.187] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:11.187] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:45:0) after 1m0s
[15:10:11.370] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:45:0)
[15:10:11.370] Timer tick, asking Buildbucket for the build status
[15:10:11.929] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:11.929] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:46:0) after 1m0s
[15:11:12.059] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:46:0)
[15:11:12.059] Timer tick, asking Buildbucket for the build status
[15:11:12.213] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:12.213] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:47:0) after 1m0s
[15:12:12.245] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:47:0)
[15:12:12.245] Timer tick, asking Buildbucket for the build status
[15:12:12.714] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:12.714] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:48:0) after 1m0s
[15:13:12.803] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:48:0)
[15:13:12.803] Timer tick, asking Buildbucket for the build status
[15:13:12.971] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:12.971] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:49:0) after 1m0s
[15:14:13.014] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:49:0)
[15:14:13.014] Timer tick, asking Buildbucket for the build status
[15:14:13.278] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:13.278] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:50:0) after 1m0s
[15:15:13.299] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:50:0)
[15:15:13.299] Timer tick, asking Buildbucket for the build status
[15:15:13.453] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:13.453] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:51:0) after 1m0s
[15:16:13.499] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:51:0)
[15:16:13.499] Timer tick, asking Buildbucket for the build status
[15:16:13.675] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:13.675] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:52:0) after 1m0s
[15:17:13.733] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:52:0)
[15:17:13.733] Timer tick, asking Buildbucket for the build status
[15:17:16.375] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:16.375] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:53:0) after 1m0s
[15:18:16.411] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:53:0)
[15:18:16.411] Timer tick, asking Buildbucket for the build status
[15:18:16.662] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:16.662] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:54:0) after 1m0s
[15:19:16.727] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:54:0)
[15:19:16.727] Timer tick, asking Buildbucket for the build status
[15:19:16.845] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:16.845] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:55:0) after 1m0s
[15:20:16.877] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:55:0)
[15:20:16.877] Timer tick, asking Buildbucket for the build status
[15:20:17.105] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:17.105] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:56:0) after 1m0s
[15:21:17.166] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:56:0)
[15:21:17.166] Timer tick, asking Buildbucket for the build status
[15:21:17.338] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:17.338] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:57:0) after 1m0s
[15:22:17.526] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:57:0)
[15:22:17.526] Timer tick, asking Buildbucket for the build status
[15:22:17.984] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:17.984] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:58:0) after 1m0s
[15:23:18.053] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:58:0)
[15:23:18.053] Timer tick, asking Buildbucket for the build status
[15:23:18.332] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:18.332] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:59:0) after 1m0s
[15:24:18.492] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:59:0)
[15:24:18.492] Timer tick, asking Buildbucket for the build status
[15:24:18.681] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:18.681] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:60:0) after 1m0s
[15:25:18.914] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:60:0)
[15:25:18.914] Timer tick, asking Buildbucket for the build status
[15:25:20.289] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:20.289] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:61:0) after 1m0s
[15:26:20.585] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:61:0)
[15:26:20.586] Timer tick, asking Buildbucket for the build status
[15:26:20.851] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:20.851] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:62:0) after 1m0s
[15:27:20.887] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:62:0)
[15:27:20.887] Timer tick, asking Buildbucket for the build status
[15:27:21.039] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:21.039] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:63:0) after 1m0s
[15:28:21.099] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:63:0)
[15:28:21.099] Timer tick, asking Buildbucket for the build status
[15:28:21.319] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:21.319] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:64:0) after 1m0s
[15:29:21.385] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:64:0)
[15:29:21.385] Timer tick, asking Buildbucket for the build status
[15:29:21.569] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:21.569] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:65:0) after 1m0s
[15:30:21.661] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:65:0)
[15:30:21.661] Timer tick, asking Buildbucket for the build status
[15:30:21.860] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:21.860] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:66:0) after 1m0s
[15:31:21.922] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:66:0)
[15:31:21.922] Timer tick, asking Buildbucket for the build status
[15:31:22.062] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:22.062] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:67:0) after 1m0s
[15:32:22.161] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:67:0)
[15:32:22.161] Timer tick, asking Buildbucket for the build status
[15:32:23.110] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:23.110] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:68:0) after 1m0s
[15:33:23.168] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:68:0)
[15:33:23.168] Timer tick, asking Buildbucket for the build status
[15:33:23.289] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:23.289] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:69:0) after 1m0s
[15:34:23.437] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:69:0)
[15:34:23.437] Timer tick, asking Buildbucket for the build status
[15:34:23.755] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:23.755] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:70:0) after 1m0s
[15:35:23.846] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:70:0)
[15:35:23.846] Timer tick, asking Buildbucket for the build status
[15:35:24.002] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:24.002] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:71:0) after 1m0s
[15:36:24.082] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:71:0)
[15:36:24.082] Timer tick, asking Buildbucket for the build status
[15:36:25.088] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:25.089] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:72:0) after 1m0s
[15:37:25.368] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:72:0)
[15:37:25.368] Timer tick, asking Buildbucket for the build status
[15:37:25.608] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:25.608] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:73:0) after 1m0s
[15:38:25.670] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:73:0)
[15:38:25.670] Timer tick, asking Buildbucket for the build status
[15:38:26.724] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:26.724] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:74:0) after 1m0s
[15:39:26.949] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:74:0)
[15:39:26.949] Timer tick, asking Buildbucket for the build status
[15:39:27.329] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:27.329] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:75:0) after 1m0s
[15:40:27.479] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:75:0)
[15:40:27.479] Timer tick, asking Buildbucket for the build status
[15:40:28.195] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:28.197] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:76:0) after 1m0s
[15:41:28.316] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:76:0)
[15:41:28.316] Timer tick, asking Buildbucket for the build status
[15:41:28.727] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:28.727] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:77:0) after 1m0s
[15:42:28.883] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:77:0)
[15:42:28.883] Timer tick, asking Buildbucket for the build status
[15:42:29.205] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:29.205] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:78:0) after 1m0s
[15:43:34.440] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:78:0)
[15:43:34.440] Timer tick, asking Buildbucket for the build status
[15:43:34.640] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:34.640] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:79:0) after 1m0s
[15:44:40.836] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:79:0)
[15:44:40.836] Timer tick, asking Buildbucket for the build status
[15:44:41.161] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:41.161] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:80:0) after 1m0s
[15:45:41.217] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:80:0)
[15:45:41.217] Timer tick, asking Buildbucket for the build status
[15:45:41.416] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:41.416] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:81:0) after 1m0s
[15:46:41.542] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:81:0)
[15:46:41.542] Timer tick, asking Buildbucket for the build status
[15:46:41.682] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:41.682] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:82:0) after 1m0s
[15:47:41.834] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:82:0)
[15:47:41.834] Timer tick, asking Buildbucket for the build status
[15:47:41.982] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:41.982] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:83:0) after 1m0s
[15:48:42.055] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:83:0)
[15:48:42.055] Timer tick, asking Buildbucket for the build status
[15:48:42.228] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:42.228] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:84:0) after 1m0s
[15:49:42.260] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:84:0)
[15:49:42.260] Timer tick, asking Buildbucket for the build status
[15:49:42.358] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:42.358] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:85:0) after 1m0s
[15:50:42.382] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:85:0)
[15:50:42.382] Timer tick, asking Buildbucket for the build status
[15:50:42.596] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:42.596] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:86:0) after 1m0s
[15:51:42.671] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:86:0)
[15:51:42.671] Timer tick, asking Buildbucket for the build status
[15:51:42.793] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:42.793] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:87:0) after 1m0s
[15:52:42.824] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:87:0)
[15:52:42.824] Timer tick, asking Buildbucket for the build status
[15:52:43.906] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:43.906] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:88:0) after 1m0s
[15:53:43.955] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:88:0)
[15:53:43.955] Timer tick, asking Buildbucket for the build status
[15:53:44.047] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:44.047] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:89:0) after 1m0s
[15:54:44.102] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:89:0)
[15:54:44.102] Timer tick, asking Buildbucket for the build status
[15:54:44.317] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:44.317] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:90:0) after 1m0s
[15:55:44.351] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:90:0)
[15:55:44.351] Timer tick, asking Buildbucket for the build status
[15:55:44.954] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:44.955] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:91:0) after 1m0s
[15:56:45.118] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:91:0)
[15:56:45.118] Timer tick, asking Buildbucket for the build status
[15:56:45.408] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:45.408] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:92:0) after 1m0s
[15:57:45.441] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:92:0)
[15:57:45.441] Timer tick, asking Buildbucket for the build status
[15:57:45.779] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:45.779] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:93:0) after 1m0s
[15:58:45.808] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:93:0)
[15:58:45.808] Timer tick, asking Buildbucket for the build status
[15:58:46.003] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:46.003] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:94:0) after 1m0s
[15:59:46.067] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:94:0)
[15:59:46.067] Timer tick, asking Buildbucket for the build status
[15:59:46.263] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:46.263] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:95:0) after 1m0s
[16:00:46.354] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:95:0)
[16:00:46.354] Timer tick, asking Buildbucket for the build status
[16:00:46.546] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:46.546] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:96:0) after 1m0s
[16:01:46.662] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:96:0)
[16:01:46.662] Timer tick, asking Buildbucket for the build status
[16:01:46.969] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:46.969] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:97:0) after 1m0s
[16:02:47.116] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:97:0)
[16:02:47.116] Timer tick, asking Buildbucket for the build status
[16:02:47.410] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:47.410] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:98:0) after 1m0s
[16:03:47.521] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:98:0)
[16:03:47.521] Timer tick, asking Buildbucket for the build status
[16:03:47.623] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:47.623] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:99:0) after 1m0s
[16:04:47.741] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:99:0)
[16:04:47.741] Timer tick, asking Buildbucket for the build status
[16:04:47.867] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:47.867] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:100:0) after 1m0s
[16:05:47.910] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:100:0)
[16:05:47.910] Timer tick, asking Buildbucket for the build status
[16:05:48.187] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:48.187] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:101:0) after 1m0s
[16:06:48.219] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:101:0)
[16:06:48.219] Timer tick, asking Buildbucket for the build status
[16:06:48.535] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:48.535] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:102:0) after 1m0s
[16:07:48.922] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:102:0)
[16:07:48.922] Timer tick, asking Buildbucket for the build status
[16:07:51.145] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:51.145] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:103:0) after 1m0s
[16:08:51.257] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:103:0)
[16:08:51.257] Timer tick, asking Buildbucket for the build status
[16:08:51.498] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:51.498] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:104:0) after 1m0s
[16:09:51.538] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:104:0)
[16:09:51.538] Timer tick, asking Buildbucket for the build status
[16:09:51.635] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:51.635] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:105:0) after 1m0s
[16:10:51.725] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:105:0)
[16:10:51.725] Timer tick, asking Buildbucket for the build status
[16:10:51.899] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:51.899] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:106:0) after 1m0s
[16:11:51.960] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:106:0)
[16:11:51.960] Timer tick, asking Buildbucket for the build status
[16:11:52.093] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:52.093] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:107:0) after 1m0s
[16:12:52.139] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:107:0)
[16:12:52.139] Timer tick, asking Buildbucket for the build status
[16:12:52.311] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:52.311] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:108:0) after 1m0s
[16:13:52.384] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:108:0)
[16:13:52.384] Timer tick, asking Buildbucket for the build status
[16:13:53.028] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:53.028] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:109:0) after 1m0s
[16:14:53.065] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:109:0)
[16:14:53.065] Timer tick, asking Buildbucket for the build status
[16:14:53.314] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:53.314] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:110:0) after 1m0s
[16:15:53.413] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:110:0)
[16:15:53.413] Timer tick, asking Buildbucket for the build status
[16:15:53.577] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:53.577] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:111:0) after 1m0s
[16:16:53.661] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:111:0)
[16:16:53.662] Timer tick, asking Buildbucket for the build status
[16:16:54.141] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:54.141] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:112:0) after 1m0s
[16:17:54.238] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:112:0)
[16:17:54.238] Timer tick, asking Buildbucket for the build status
[16:17:54.402] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:54.402] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:113:0) after 1m0s
[16:18:54.444] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:113:0)
[16:18:54.444] Timer tick, asking Buildbucket for the build status
[16:18:54.659] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:54.659] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:114:0) after 1m0s
[16:19:54.681] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:114:0)
[16:19:54.681] Timer tick, asking Buildbucket for the build status
[16:19:54.811] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:54.811] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:115:0) after 1m0s
[16:20:54.853] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:115:0)
[16:20:54.853] Timer tick, asking Buildbucket for the build status
[16:20:55.023] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:55.023] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:116:0) after 1m0s
[16:21:55.137] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:116:0)
[16:21:55.137] Timer tick, asking Buildbucket for the build status
[16:21:55.587] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:55.587] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:117:0) after 1m0s
[16:22:55.738] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:117:0)
[16:22:55.738] Timer tick, asking Buildbucket for the build status
[16:22:56.212] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:56.212] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:118:0) after 1m0s
[16:23:56.280] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:118:0)
[16:23:56.280] Timer tick, asking Buildbucket for the build status
[16:23:56.448] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:56.448] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:119:0) after 1m0s
[16:24:56.535] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:119:0)
[16:24:56.535] Timer tick, asking Buildbucket for the build status
[16:24:56.679] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:56.679] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:120:0) after 1m0s
[16:25:56.784] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:120:0)
[16:25:56.784] Timer tick, asking Buildbucket for the build status
[16:25:56.901] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:56.901] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:121:0) after 1m0s
[16:26:57.039] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:121:0)
[16:26:57.039] Timer tick, asking Buildbucket for the build status
[16:26:57.148] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:57.148] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:122:0) after 1m0s
[16:27:57.298] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:122:0)
[16:27:57.298] Timer tick, asking Buildbucket for the build status
[16:27:57.512] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:57.512] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:123:0) after 1m0s
[16:28:57.568] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:123:0)
[16:28:57.568] Timer tick, asking Buildbucket for the build status
[16:28:57.785] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:57.786] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:124:0) after 1m0s
[16:29:57.879] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:124:0)
[16:29:57.880] Timer tick, asking Buildbucket for the build status
[16:29:58.088] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:58.088] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:125:0) after 1m0s
[16:30:58.202] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:125:0)
[16:30:58.202] Timer tick, asking Buildbucket for the build status
[16:30:58.371] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:58.371] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:126:0) after 1m0s
[16:31:58.435] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:126:0)
[16:31:58.435] Timer tick, asking Buildbucket for the build status
[16:31:58.633] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:58.633] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:127:0) after 1m0s
[16:32:59.036] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:127:0)
[16:32:59.036] Timer tick, asking Buildbucket for the build status
[16:33:04.953] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:04.953] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:128:0) after 1m0s
[16:34:05.009] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:128:0)
[16:34:05.009] Timer tick, asking Buildbucket for the build status
[16:34:05.282] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:05.282] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:129:0) after 1m0s
[16:35:05.286] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:129:0)
[16:35:05.286] Timer tick, asking Buildbucket for the build status
[16:35:06.720] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:06.720] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:130:0) after 1m0s
[16:36:06.869] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:130:0)
[16:36:06.869] Timer tick, asking Buildbucket for the build status
[16:36:07.227] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:07.227] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:131:0) after 1m0s
[16:37:07.254] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:131:0)
[16:37:07.254] Timer tick, asking Buildbucket for the build status
[16:37:07.572] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:07.572] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:132:0) after 1m0s
[16:38:07.598] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:132:0)
[16:38:07.598] Timer tick, asking Buildbucket for the build status
[16:38:07.738] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:07.738] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:133:0) after 1m0s
[16:39:07.834] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:133:0)
[16:39:07.834] Timer tick, asking Buildbucket for the build status
[16:39:08.081] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:08.081] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:134:0) after 1m0s
[16:40:08.093] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:134:0)
[16:40:08.093] Timer tick, asking Buildbucket for the build status
[16:40:08.281] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:08.281] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:135:0) after 1m0s
[16:41:08.403] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:135:0)
[16:41:08.403] Timer tick, asking Buildbucket for the build status
[16:41:09.995] Build 8884666031941375696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:09.995] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9050096094064305776:136:0) after 1m0s
[16:41:40.179] Received PubSub notification, asking Buildbucket for the build status
[16:41:40.315] Build 8884666031941375696: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[16:41:40.315] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1585327299186818",
"created_by": "project:chromium",
"created_ts": "1585319217698542",
"id": "8884666031941375696",
"parameters_json": "{\"builder_name\": \"Deterministic Linux\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a054471cd0732e4d032e578710196ff04c93887e\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"jobs\": 500.0, \"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\": \"luci-chromium-ci-xenial-us-central1-b-8-61-kwpi\", \"branch\": \"refs/heads/master\", \"buildername\": \"Deterministic Linux\", \"buildnumber\": 27318, \"got_angle_revision\": \"651fc45d3a2956606e78ac66aa16fc7c58f6867e\", \"got_dawn_revision\": \"4b1be08ec993dd8a858229abdfd33cb2ebb6a292\", \"got_nacl_revision\": \"ffed96d1528df9f114a30b14d4eb06120c8fb022\", \"got_revision\": \"a054471cd0732e4d032e578710196ff04c93887e\", \"got_revision_cp\": \"refs/heads/master@{#754005}\", \"got_swarming_client_revision\": \"cc958279ffd6853e0a1b227a7e957ca334fe56af\", \"got_swiftshader_revision\": \"48f3f6cb2acbbb9a6b6494bbe8fdf3c4ee7627b8\", \"got_v8_revision\": \"5851deb6886177deb57b8894386647458bf481d9\", \"got_v8_revision_cp\": \"refs/heads/8.3.93@{#1}\", \"got_webrtc_revision\": \"d19513f3ffbb939fd56b5377b678bb31d3154e14\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30881}\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a054471cd0732e4d032e578710196ff04c93887e\"}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_61626c7085ee1f9730db9e25e8ceef3d7b395de70268d432aed853b8a16eb295_v2\", \"builder_fdb8dca0f90a2608c4f778e5f29672efafa0e8947deee24935132ecfe3180a21_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-8-61-kwpi\"], \"image\": [\"chrome-xenial-20032200-f0f7a76907a\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"4901-91f024a\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1585319465865754",
"status": "COMPLETED",
"status_changed_ts": "1585327299672137",
"tags": [
"build_address:luci.chromium.ci/Deterministic Linux/27318",
"builder:Deterministic Linux",
"buildset:commit/git/a054471cd0732e4d032e578710196ff04c93887e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a054471cd0732e4d032e578710196ff04c93887e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9050096094064305776",
"scheduler_job_id:chromium/Deterministic Linux",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8884666031941375696/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Linux",
"swarming_tag:recipe_name:swarming/deterministic_build",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:4b3535197738a510",
"user_agent:luci-scheduler"
],
"updated_ts": "1585327299751921",
"url": "https://ci.chromium.org/b/8884666031941375696",
"utcnow_ts": "1585327300303804"
}
}
[16:41:40.315] Invocation finished in 2h14m44.025108103s with status SUCCEEDED