[03:02:04.525] New invocation is queued and will start shortly
[03:02:05.699] Starting the invocation (attempt 1)
[03:02:05.743] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:02:05.743] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:02:05.743] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9056027984744802336",
"parameters_json": "{\"builder_name\":\"Deterministic Linux\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"dbf98f17f5b6589373826296cba7321bbb3dfdae\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Deterministic Linux",
"scheduler_invocation_id:9056027984744802336",
"scheduler_job_id:chromium/Deterministic Linux",
"user_agent:luci-scheduler",
"buildset:commit/git/dbf98f17f5b6589373826296cba7321bbb3dfdae",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dbf98f17f5b6589373826296cba7321bbb3dfdae",
"gitiles_ref:refs/heads/master"
]
}
[03:02:06.666] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1579662125926508",
"id": "8890597922603733616",
"parameters_json": "{\"builder_name\": \"Deterministic Linux\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"dbf98f17f5b6589373826296cba7321bbb3dfdae\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1579662126473815",
"tags": [
"build_address:luci.chromium.ci/Deterministic Linux/26545",
"builder:Deterministic Linux",
"buildset:commit/git/dbf98f17f5b6589373826296cba7321bbb3dfdae",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dbf98f17f5b6589373826296cba7321bbb3dfdae",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9056027984744802336",
"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/8890597922603733616/+/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": "1579662126474047",
"url": "https://ci.chromium.org/b/8890597922603733616",
"utcnow_ts": "1579662126659271"
}
}
[03:02:06.666] Task URL: https://ci.chromium.org/b/8890597922603733616
[03:02:06.666] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:2:0) after 1m0s
[03:03:06.799] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:2:0)
[03:03:06.799] Timer tick, asking Buildbucket for the build status
[03:03:07.003] Build 8890597922603733616: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:03:07.003] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:3:0) after 1m0s
[03:04:07.024] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:3:0)
[03:04:07.024] Timer tick, asking Buildbucket for the build status
[03:04:07.181] Build 8890597922603733616: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:04:07.181] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:4:0) after 1m0s
[03:05:07.324] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:4:0)
[03:05:07.324] Timer tick, asking Buildbucket for the build status
[03:05:07.482] Build 8890597922603733616: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:05:07.482] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:5:0) after 1m0s
[03:06:07.498] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:5:0)
[03:06:07.498] Timer tick, asking Buildbucket for the build status
[03:06:07.900] Build 8890597922603733616: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:06:07.900] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:6:0) after 1m0s
[03:07:06.715] Received PubSub notification, asking Buildbucket for the build status
[03:07:06.888] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:07.931] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:6:0)
[03:07:07.931] Timer tick, asking Buildbucket for the build status
[03:07:09.232] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:09.232] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:8:0) after 1m0s
[03:08:09.252] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:8:0)
[03:08:09.252] Timer tick, asking Buildbucket for the build status
[03:08:09.388] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:09.388] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:9:0) after 1m0s
[03:09:09.422] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:9:0)
[03:09:09.422] Timer tick, asking Buildbucket for the build status
[03:09:09.580] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:09.580] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:10:0) after 1m0s
[03:10:09.699] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:10:0)
[03:10:09.699] Timer tick, asking Buildbucket for the build status
[03:10:09.822] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:09.822] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:11:0) after 1m0s
[03:11:10.061] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:11:0)
[03:11:10.061] Timer tick, asking Buildbucket for the build status
[03:11:10.329] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:10.329] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:12:0) after 1m0s
[03:12:10.306] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:12:0)
[03:12:10.306] Timer tick, asking Buildbucket for the build status
[03:12:12.984] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:12.984] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:13:0) after 1m0s
[03:13:13.098] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:13:0)
[03:13:13.098] Timer tick, asking Buildbucket for the build status
[03:13:13.753] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:13.753] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:14:0) after 1m0s
[03:14:13.862] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:14:0)
[03:14:13.862] Timer tick, asking Buildbucket for the build status
[03:14:14.581] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:14.581] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:15:0) after 1m0s
[03:15:14.599] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:15:0)
[03:15:14.599] Timer tick, asking Buildbucket for the build status
[03:15:15.537] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:15.537] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:16:0) after 1m0s
[03:16:15.572] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:16:0)
[03:16:15.572] Timer tick, asking Buildbucket for the build status
[03:16:16.439] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:16.439] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:17:0) after 1m0s
[03:17:16.514] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:17:0)
[03:17:16.514] Timer tick, asking Buildbucket for the build status
[03:17:16.643] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:16.643] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:18:0) after 1m0s
[03:18:16.703] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:18:0)
[03:18:16.703] Timer tick, asking Buildbucket for the build status
[03:18:17.395] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:17.395] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:19:0) after 1m0s
[03:19:17.425] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:19:0)
[03:19:17.425] Timer tick, asking Buildbucket for the build status
[03:19:17.613] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:17.613] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:20:0) after 1m0s
[03:20:17.673] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:20:0)
[03:20:17.673] Timer tick, asking Buildbucket for the build status
[03:20:17.840] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:17.840] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:21:0) after 1m0s
[03:21:17.869] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:21:0)
[03:21:17.869] Timer tick, asking Buildbucket for the build status
[03:21:18.869] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:18.869] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:22:0) after 1m0s
[03:22:18.975] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:22:0)
[03:22:18.975] Timer tick, asking Buildbucket for the build status
[03:22:19.133] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:19.133] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:23:0) after 1m0s
[03:23:19.183] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:23:0)
[03:23:19.183] Timer tick, asking Buildbucket for the build status
[03:23:19.887] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:19.887] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:24:0) after 1m0s
[03:24:19.946] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:24:0)
[03:24:19.946] Timer tick, asking Buildbucket for the build status
[03:24:20.106] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:20.106] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:25:0) after 1m0s
[03:25:20.152] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:25:0)
[03:25:20.152] Timer tick, asking Buildbucket for the build status
[03:25:20.318] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:20.318] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:26:0) after 1m0s
[03:26:20.311] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:26:0)
[03:26:20.311] Timer tick, asking Buildbucket for the build status
[03:26:20.467] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:20.467] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:27:0) after 1m0s
[03:27:20.481] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:27:0)
[03:27:20.481] Timer tick, asking Buildbucket for the build status
[03:27:21.390] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:21.390] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:28:0) after 1m0s
[03:28:21.408] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:28:0)
[03:28:21.408] Timer tick, asking Buildbucket for the build status
[03:28:21.563] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:21.563] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:29:0) after 1m0s
[03:29:21.616] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:29:0)
[03:29:21.616] Timer tick, asking Buildbucket for the build status
[03:29:21.945] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:21.945] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:30:0) after 1m0s
[03:30:21.967] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:30:0)
[03:30:21.967] Timer tick, asking Buildbucket for the build status
[03:30:22.221] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:22.221] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:31:0) after 1m0s
[03:31:22.247] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:31:0)
[03:31:22.247] Timer tick, asking Buildbucket for the build status
[03:31:22.480] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:22.480] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:32:0) after 1m0s
[03:32:22.495] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:32:0)
[03:32:22.495] Timer tick, asking Buildbucket for the build status
[03:32:23.097] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:23.097] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:33:0) after 1m0s
[03:33:23.180] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:33:0)
[03:33:23.180] Timer tick, asking Buildbucket for the build status
[03:33:23.996] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:23.996] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:34:0) after 1m0s
[03:34:24.004] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:34:0)
[03:34:24.004] Timer tick, asking Buildbucket for the build status
[03:34:24.213] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:24.213] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:35:0) after 1m0s
[03:35:24.235] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:35:0)
[03:35:24.235] Timer tick, asking Buildbucket for the build status
[03:35:25.273] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:25.273] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:36:0) after 1m0s
[03:36:25.316] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:36:0)
[03:36:25.316] Timer tick, asking Buildbucket for the build status
[03:36:25.459] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:25.459] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:37:0) after 1m0s
[03:37:25.556] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:37:0)
[03:37:25.556] Timer tick, asking Buildbucket for the build status
[03:37:25.717] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:25.717] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:38:0) after 1m0s
[03:38:25.777] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:38:0)
[03:38:25.777] Timer tick, asking Buildbucket for the build status
[03:38:26.019] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:26.019] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:39:0) after 1m0s
[03:39:26.104] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:39:0)
[03:39:26.104] Timer tick, asking Buildbucket for the build status
[03:39:26.279] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:26.279] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:40:0) after 1m0s
[03:40:26.290] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:40:0)
[03:40:26.290] Timer tick, asking Buildbucket for the build status
[03:40:26.491] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:26.491] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:41:0) after 1m0s
[03:41:26.580] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:41:0)
[03:41:26.580] Timer tick, asking Buildbucket for the build status
[03:41:26.831] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:26.831] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:42:0) after 1m0s
[03:42:26.896] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:42:0)
[03:42:26.896] Timer tick, asking Buildbucket for the build status
[03:42:27.296] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:27.296] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:43:0) after 1m0s
[03:43:27.315] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:43:0)
[03:43:27.315] Timer tick, asking Buildbucket for the build status
[03:43:28.224] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:28.224] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:44:0) after 1m0s
[03:44:28.283] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:44:0)
[03:44:28.283] Timer tick, asking Buildbucket for the build status
[03:44:28.478] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:28.478] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:45:0) after 1m0s
[03:45:28.534] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:45:0)
[03:45:28.534] Timer tick, asking Buildbucket for the build status
[03:45:28.773] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:28.773] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:46:0) after 1m0s
[03:46:28.949] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:46:0)
[03:46:28.949] Timer tick, asking Buildbucket for the build status
[03:46:29.132] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:29.132] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:47:0) after 1m0s
[03:47:29.186] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:47:0)
[03:47:29.186] Timer tick, asking Buildbucket for the build status
[03:47:29.441] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:29.441] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:48:0) after 1m0s
[03:48:29.459] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:48:0)
[03:48:29.459] Timer tick, asking Buildbucket for the build status
[03:48:29.749] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:29.749] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:49:0) after 1m0s
[03:49:29.867] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:49:0)
[03:49:29.867] Timer tick, asking Buildbucket for the build status
[03:49:30.180] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:30.180] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:50:0) after 1m0s
[03:50:30.235] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:50:0)
[03:50:30.235] Timer tick, asking Buildbucket for the build status
[03:50:30.483] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:30.483] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:51:0) after 1m0s
[03:51:30.531] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:51:0)
[03:51:30.531] Timer tick, asking Buildbucket for the build status
[03:51:30.815] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:30.815] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:52:0) after 1m0s
[03:52:30.843] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:52:0)
[03:52:30.843] Timer tick, asking Buildbucket for the build status
[03:52:30.999] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:30.999] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:53:0) after 1m0s
[03:53:31.062] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:53:0)
[03:53:31.062] Timer tick, asking Buildbucket for the build status
[03:53:31.242] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:31.242] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:54:0) after 1m0s
[03:54:31.264] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:54:0)
[03:54:31.264] Timer tick, asking Buildbucket for the build status
[03:54:31.642] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:31.642] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:55:0) after 1m0s
[03:55:31.682] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:55:0)
[03:55:31.682] Timer tick, asking Buildbucket for the build status
[03:55:32.144] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:32.144] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:56:0) after 1m0s
[03:56:32.227] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:56:0)
[03:56:32.227] Timer tick, asking Buildbucket for the build status
[03:56:32.696] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:32.696] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:57:0) after 1m0s
[03:57:32.728] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:57:0)
[03:57:32.728] Timer tick, asking Buildbucket for the build status
[03:57:32.906] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:32.906] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:58:0) after 1m0s
[03:58:32.917] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:58:0)
[03:58:32.917] Timer tick, asking Buildbucket for the build status
[03:58:33.128] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:33.128] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:59:0) after 1m0s
[03:59:33.151] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:59:0)
[03:59:33.151] Timer tick, asking Buildbucket for the build status
[03:59:33.474] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:33.474] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:60:0) after 1m0s
[04:00:33.569] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:60:0)
[04:00:33.569] Timer tick, asking Buildbucket for the build status
[04:00:33.778] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:33.778] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:61:0) after 1m0s
[04:01:33.872] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:61:0)
[04:01:33.872] Timer tick, asking Buildbucket for the build status
[04:01:35.333] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:35.333] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:62:0) after 1m0s
[04:02:35.400] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:62:0)
[04:02:35.400] Timer tick, asking Buildbucket for the build status
[04:02:35.574] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:35.574] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:63:0) after 1m0s
[04:03:35.709] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:63:0)
[04:03:35.709] Timer tick, asking Buildbucket for the build status
[04:03:36.239] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:36.239] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:64:0) after 1m0s
[04:04:36.279] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:64:0)
[04:04:36.279] Timer tick, asking Buildbucket for the build status
[04:04:37.153] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:37.153] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:65:0) after 1m0s
[04:05:37.185] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:65:0)
[04:05:37.185] Timer tick, asking Buildbucket for the build status
[04:05:37.751] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:37.751] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:66:0) after 1m0s
[04:06:37.810] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:66:0)
[04:06:37.811] Timer tick, asking Buildbucket for the build status
[04:06:38.167] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:38.167] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:67:0) after 1m0s
[04:07:38.182] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:67:0)
[04:07:38.182] Timer tick, asking Buildbucket for the build status
[04:07:38.568] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:38.568] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:68:0) after 1m0s
[04:08:38.584] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:68:0)
[04:08:38.584] Timer tick, asking Buildbucket for the build status
[04:08:38.771] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:38.771] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:69:0) after 1m0s
[04:09:38.928] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:69:0)
[04:09:38.928] Timer tick, asking Buildbucket for the build status
[04:09:39.286] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:39.286] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:70:0) after 1m0s
[04:10:39.399] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:70:0)
[04:10:39.399] Timer tick, asking Buildbucket for the build status
[04:10:40.783] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:40.783] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:71:0) after 1m0s
[04:11:40.914] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:71:0)
[04:11:40.914] Timer tick, asking Buildbucket for the build status
[04:11:41.084] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:41.084] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:72:0) after 1m0s
[04:12:41.126] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:72:0)
[04:12:41.126] Timer tick, asking Buildbucket for the build status
[04:12:41.406] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:41.406] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:73:0) after 1m0s
[04:13:41.435] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:73:0)
[04:13:41.435] Timer tick, asking Buildbucket for the build status
[04:13:41.647] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:41.647] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:74:0) after 1m0s
[04:14:41.710] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:74:0)
[04:14:41.710] Timer tick, asking Buildbucket for the build status
[04:14:42.108] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:42.108] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:75:0) after 1m0s
[04:15:42.147] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:75:0)
[04:15:42.147] Timer tick, asking Buildbucket for the build status
[04:15:42.510] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:42.510] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:76:0) after 1m0s
[04:16:42.567] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:76:0)
[04:16:42.567] Timer tick, asking Buildbucket for the build status
[04:16:43.619] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:43.619] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:77:0) after 1m0s
[04:17:43.661] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:77:0)
[04:17:43.661] Timer tick, asking Buildbucket for the build status
[04:17:44.473] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:44.473] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:78:0) after 1m0s
[04:18:44.518] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:78:0)
[04:18:44.518] Timer tick, asking Buildbucket for the build status
[04:18:44.717] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:44.717] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:79:0) after 1m0s
[04:19:44.828] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:79:0)
[04:19:44.828] Timer tick, asking Buildbucket for the build status
[04:19:45.033] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:45.033] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:80:0) after 1m0s
[04:20:45.101] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:80:0)
[04:20:45.101] Timer tick, asking Buildbucket for the build status
[04:20:45.254] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:45.254] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:81:0) after 1m0s
[04:21:45.301] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:81:0)
[04:21:45.301] Timer tick, asking Buildbucket for the build status
[04:21:45.569] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:45.569] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:82:0) after 1m0s
[04:22:45.627] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:82:0)
[04:22:45.627] Timer tick, asking Buildbucket for the build status
[04:22:46.178] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:46.178] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:83:0) after 1m0s
[04:23:46.233] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:83:0)
[04:23:46.233] Timer tick, asking Buildbucket for the build status
[04:23:46.390] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:46.390] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:84:0) after 1m0s
[04:24:46.413] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:84:0)
[04:24:46.413] Timer tick, asking Buildbucket for the build status
[04:24:46.569] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:46.569] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:85:0) after 1m0s
[04:25:46.663] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:85:0)
[04:25:46.663] Timer tick, asking Buildbucket for the build status
[04:25:46.890] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:46.890] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:86:0) after 1m0s
[04:26:47.132] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:86:0)
[04:26:47.132] Timer tick, asking Buildbucket for the build status
[04:26:47.358] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:47.358] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:87:0) after 1m0s
[04:27:47.449] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:87:0)
[04:27:47.449] Timer tick, asking Buildbucket for the build status
[04:27:47.973] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:47.973] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:88:0) after 1m0s
[04:28:48.001] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:88:0)
[04:28:48.001] Timer tick, asking Buildbucket for the build status
[04:28:48.182] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:48.182] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:89:0) after 1m0s
[04:29:48.263] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:89:0)
[04:29:48.263] Timer tick, asking Buildbucket for the build status
[04:29:48.483] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:48.483] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:90:0) after 1m0s
[04:30:48.545] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:90:0)
[04:30:48.545] Timer tick, asking Buildbucket for the build status
[04:30:48.751] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:48.751] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:91:0) after 1m0s
[04:31:48.823] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:91:0)
[04:31:48.823] Timer tick, asking Buildbucket for the build status
[04:31:49.136] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:49.136] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:92:0) after 1m0s
[04:32:49.171] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:92:0)
[04:32:49.171] Timer tick, asking Buildbucket for the build status
[04:32:49.355] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:49.355] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:93:0) after 1m0s
[04:33:49.394] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:93:0)
[04:33:49.394] Timer tick, asking Buildbucket for the build status
[04:33:49.742] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:49.742] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:94:0) after 1m0s
[04:34:49.746] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:94:0)
[04:34:49.746] Timer tick, asking Buildbucket for the build status
[04:34:49.886] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:49.886] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:95:0) after 1m0s
[04:35:49.926] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:95:0)
[04:35:49.926] Timer tick, asking Buildbucket for the build status
[04:35:50.197] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:50.197] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:96:0) after 1m0s
[04:36:50.344] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:96:0)
[04:36:50.344] Timer tick, asking Buildbucket for the build status
[04:36:50.500] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:50.500] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:97:0) after 1m0s
[04:37:50.579] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:97:0)
[04:37:50.579] Timer tick, asking Buildbucket for the build status
[04:37:50.800] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:50.800] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:98:0) after 1m0s
[04:38:50.883] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:98:0)
[04:38:50.883] Timer tick, asking Buildbucket for the build status
[04:38:51.519] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:51.520] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:99:0) after 1m0s
[04:39:51.587] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:99:0)
[04:39:51.587] Timer tick, asking Buildbucket for the build status
[04:39:51.813] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:51.813] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:100:0) after 1m0s
[04:40:51.909] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:100:0)
[04:40:51.909] Timer tick, asking Buildbucket for the build status
[04:40:52.131] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:52.131] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:101:0) after 1m0s
[04:41:52.191] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:101:0)
[04:41:52.191] Timer tick, asking Buildbucket for the build status
[04:41:53.154] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:53.154] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:102:0) after 1m0s
[04:42:53.199] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:102:0)
[04:42:53.199] Timer tick, asking Buildbucket for the build status
[04:42:53.434] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:53.434] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:103:0) after 1m0s
[04:43:53.455] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:103:0)
[04:43:53.455] Timer tick, asking Buildbucket for the build status
[04:43:53.736] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:53.736] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:104:0) after 1m0s
[04:44:53.721] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:104:0)
[04:44:53.721] Timer tick, asking Buildbucket for the build status
[04:44:54.259] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:54.259] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:105:0) after 1m0s
[04:45:54.284] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:105:0)
[04:45:54.284] Timer tick, asking Buildbucket for the build status
[04:45:54.998] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:54.998] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:106:0) after 1m0s
[04:46:55.001] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:106:0)
[04:46:55.001] Timer tick, asking Buildbucket for the build status
[04:46:55.714] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:55.714] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:107:0) after 1m0s
[04:47:55.804] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:107:0)
[04:47:55.804] Timer tick, asking Buildbucket for the build status
[04:47:56.591] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:56.591] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:108:0) after 1m0s
[04:48:56.635] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:108:0)
[04:48:56.635] Timer tick, asking Buildbucket for the build status
[04:48:57.063] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:57.063] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:109:0) after 1m0s
[04:49:57.125] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:109:0)
[04:49:57.125] Timer tick, asking Buildbucket for the build status
[04:49:57.293] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:57.293] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:110:0) after 1m0s
[04:50:57.321] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:110:0)
[04:50:57.321] Timer tick, asking Buildbucket for the build status
[04:50:58.394] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:58.394] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:111:0) after 1m0s
[04:51:58.425] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:111:0)
[04:51:58.425] Timer tick, asking Buildbucket for the build status
[04:51:58.636] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:58.636] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:112:0) after 1m0s
[04:52:58.749] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:112:0)
[04:52:58.749] Timer tick, asking Buildbucket for the build status
[04:52:59.182] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:59.182] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:113:0) after 1m0s
[04:53:59.270] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:113:0)
[04:53:59.270] Timer tick, asking Buildbucket for the build status
[04:53:59.557] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:59.557] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:114:0) after 1m0s
[04:54:59.599] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:114:0)
[04:54:59.599] Timer tick, asking Buildbucket for the build status
[04:55:00.376] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:00.376] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:115:0) after 1m0s
[04:56:00.522] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:115:0)
[04:56:00.522] Timer tick, asking Buildbucket for the build status
[04:56:00.685] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:00.685] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:116:0) after 1m0s
[04:57:00.783] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:116:0)
[04:57:00.783] Timer tick, asking Buildbucket for the build status
[04:57:00.938] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:00.938] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:117:0) after 1m0s
[04:58:01.106] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:117:0)
[04:58:01.106] Timer tick, asking Buildbucket for the build status
[04:58:02.918] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:02.918] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:118:0) after 1m0s
[04:59:02.904] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:118:0)
[04:59:02.904] Timer tick, asking Buildbucket for the build status
[04:59:03.084] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:03.084] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:119:0) after 1m0s
[05:00:03.166] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:119:0)
[05:00:03.166] Timer tick, asking Buildbucket for the build status
[05:00:04.410] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:04.410] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:120:0) after 1m0s
[05:01:04.447] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:120:0)
[05:01:04.447] Timer tick, asking Buildbucket for the build status
[05:01:04.783] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:04.783] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:121:0) after 1m0s
[05:02:04.822] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:121:0)
[05:02:04.822] Timer tick, asking Buildbucket for the build status
[05:02:05.025] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:05.025] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:122:0) after 1m0s
[05:03:05.128] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:122:0)
[05:03:05.128] Timer tick, asking Buildbucket for the build status
[05:03:05.942] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:05.942] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:123:0) after 1m0s
[05:04:05.992] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:123:0)
[05:04:05.992] Timer tick, asking Buildbucket for the build status
[05:04:06.369] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:06.369] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:124:0) after 1m0s
[05:05:06.554] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:124:0)
[05:05:06.554] Timer tick, asking Buildbucket for the build status
[05:05:06.913] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:06.913] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:125:0) after 1m0s
[05:06:07.071] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:125:0)
[05:06:07.071] Timer tick, asking Buildbucket for the build status
[05:06:07.444] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:07.444] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:126:0) after 1m0s
[05:07:07.529] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:126:0)
[05:07:07.529] Timer tick, asking Buildbucket for the build status
[05:07:07.753] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:07.753] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:127:0) after 1m0s
[05:08:07.764] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:127:0)
[05:08:07.764] Timer tick, asking Buildbucket for the build status
[05:08:08.628] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:08.628] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:128:0) after 1m0s
[05:09:08.741] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:128:0)
[05:09:08.741] Timer tick, asking Buildbucket for the build status
[05:09:09.040] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:09.040] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:129:0) after 1m0s
[05:10:09.113] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:129:0)
[05:10:09.113] Timer tick, asking Buildbucket for the build status
[05:10:09.741] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:09.741] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:130:0) after 1m0s
[05:11:09.813] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:130:0)
[05:11:09.813] Timer tick, asking Buildbucket for the build status
[05:11:10.088] Build 8890597922603733616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:10.088] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9056027984744802336:131:0) after 1m0s
[05:11:44.058] Received PubSub notification, asking Buildbucket for the build status
[05:11:44.575] Build 8890597922603733616: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[05:11:44.575] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1579669903321363",
"created_by": "project:chromium",
"created_ts": "1579662125926508",
"id": "8890597922603733616",
"parameters_json": "{\"builder_name\": \"Deterministic Linux\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"dbf98f17f5b6589373826296cba7321bbb3dfdae\"}}",
"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-62-z9wr\", \"branch\": \"refs/heads/master\", \"buildername\": \"Deterministic Linux\", \"buildnumber\": 26545, \"got_angle_revision\": \"7f8b6e3fabbd579065944112095ffb69a869316b\", \"got_dawn_revision\": \"80880ee9985484d39355d2827ce5cb9343a07e91\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"dbf98f17f5b6589373826296cba7321bbb3dfdae\", \"got_revision_cp\": \"refs/heads/master@{#733858}\", \"got_swarming_client_revision\": \"885b3febcc170a60f25795304e60927b77d1e92d\", \"got_swiftshader_revision\": \"b766e5e7fbf42e48be36afc80e2c448e339c599e\", \"got_v8_revision\": \"4cde67a85fd4c29f78551e5aebca1fc8912876e8\", \"got_v8_revision_cp\": \"refs/heads/8.1.244@{#1}\", \"got_webrtc_revision\": \"06df1e1c4655a1048bfeb30e090ab52005f1e841\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30339}\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"dbf98f17f5b6589373826296cba7321bbb3dfdae\"}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_0095fe006eafdabf54002a791fb8c836a288874203ece8347c24b74b8d090ce3_v2\", \"builder_3e75b41c9bcf12a22209c03b338cce1fafa12941d3074ed1e1ed2450e6b71594_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-62-z9wr\"], \"image\": [\"chrome-xenial-20011200-47b90f1b63b\"], \"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\": [\"4784-e3296b5\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1579662425892143",
"status": "COMPLETED",
"status_changed_ts": "1579669903808399",
"tags": [
"build_address:luci.chromium.ci/Deterministic Linux/26545",
"builder:Deterministic Linux",
"buildset:commit/git/dbf98f17f5b6589373826296cba7321bbb3dfdae",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dbf98f17f5b6589373826296cba7321bbb3dfdae",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9056027984744802336",
"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/8890597922603733616/+/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:49e404b7d8934510",
"user_agent:luci-scheduler"
],
"updated_ts": "1579669903811550",
"url": "https://ci.chromium.org/b/8890597922603733616",
"utcnow_ts": "1579669904567980"
}
}
[05:11:44.575] Invocation finished in 2h9m40.074518761s with status SUCCEEDED