[18:09:04.194] New invocation is queued and will start shortly
[18:09:05.313] Starting the invocation (attempt 1)
[18:09:05.351] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:09:05.351] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:09:05.351] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9076083448162296800",
"parameters_json": "{\"builder_name\":\"Deterministic Linux\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"061e64bf593859f9c126b230de35c481b3774e75\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Deterministic Linux",
"scheduler_invocation_id:9076083448162296800",
"scheduler_job_id:chromium/Deterministic Linux",
"user_agent:luci-scheduler",
"buildset:commit/git/061e64bf593859f9c126b230de35c481b3774e75",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/061e64bf593859f9c126b230de35c481b3774e75",
"gitiles_ref:refs/heads/master"
]
}
[18:09:06.013] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1560535745485409",
"id": "8910653386100112896",
"parameters_json": "{\"builder_name\": \"Deterministic Linux\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"061e64bf593859f9c126b230de35c481b3774e75\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1560535745872086",
"tags": [
"build_address:luci.chromium.ci/Deterministic Linux/23728",
"builder:Deterministic Linux",
"buildset:commit/git/061e64bf593859f9c126b230de35c481b3774e75",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/061e64bf593859f9c126b230de35c481b3774e75",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9076083448162296800",
"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/8910653386100112896/+/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": "1560535745872177",
"url": "https://ci.chromium.org/b/8910653386100112896",
"utcnow_ts": "1560535746002273"
}
}
[18:09:06.013] Task URL: https://ci.chromium.org/b/8910653386100112896
[18:09:06.013] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:2:0) after 1m0s
[18:09:46.044] Received PubSub notification, asking Buildbucket for the build status
[18:09:46.865] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:06.283] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:2:0)
[18:10:06.283] Timer tick, asking Buildbucket for the build status
[18:10:06.454] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:06.454] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:4:0) after 1m0s
[18:11:06.435] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:4:0)
[18:11:06.435] Timer tick, asking Buildbucket for the build status
[18:11:06.950] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:06.950] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:5:0) after 1m0s
[18:12:06.936] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:5:0)
[18:12:06.936] Timer tick, asking Buildbucket for the build status
[18:12:07.731] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:07.731] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:6:0) after 1m0s
[18:13:07.736] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:6:0)
[18:13:07.736] Timer tick, asking Buildbucket for the build status
[18:13:08.212] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:08.212] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:7:0) after 1m0s
[18:14:08.295] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:7:0)
[18:14:08.295] Timer tick, asking Buildbucket for the build status
[18:14:09.028] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:09.028] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:8:0) after 1m0s
[18:15:09.047] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:8:0)
[18:15:09.047] Timer tick, asking Buildbucket for the build status
[18:15:09.277] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:09.277] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:9:0) after 1m0s
[18:16:09.295] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:9:0)
[18:16:09.295] Timer tick, asking Buildbucket for the build status
[18:16:09.457] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:09.457] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:10:0) after 1m0s
[18:17:09.504] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:10:0)
[18:17:09.504] Timer tick, asking Buildbucket for the build status
[18:17:10.609] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:10.609] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:11:0) after 1m0s
[18:18:10.626] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:11:0)
[18:18:10.626] Timer tick, asking Buildbucket for the build status
[18:18:11.152] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:11.152] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:12:0) after 1m0s
[18:19:11.210] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:12:0)
[18:19:11.210] Timer tick, asking Buildbucket for the build status
[18:19:11.412] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:11.412] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:13:0) after 1m0s
[18:20:11.433] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:13:0)
[18:20:11.433] Timer tick, asking Buildbucket for the build status
[18:20:11.529] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:11.529] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:14:0) after 1m0s
[18:21:11.645] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:14:0)
[18:21:11.645] Timer tick, asking Buildbucket for the build status
[18:21:11.817] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:11.817] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:15:0) after 1m0s
[18:22:11.878] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:15:0)
[18:22:11.878] Timer tick, asking Buildbucket for the build status
[18:22:11.960] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:11.961] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:16:0) after 1m0s
[18:23:12.035] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:16:0)
[18:23:12.035] Timer tick, asking Buildbucket for the build status
[18:23:12.653] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:12.653] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:17:0) after 1m0s
[18:24:12.740] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:17:0)
[18:24:12.740] Timer tick, asking Buildbucket for the build status
[18:24:13.246] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:13.246] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:18:0) after 1m0s
[18:25:13.414] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:18:0)
[18:25:13.414] Timer tick, asking Buildbucket for the build status
[18:25:16.959] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:16.959] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:19:0) after 1m0s
[18:26:16.984] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:19:0)
[18:26:16.984] Timer tick, asking Buildbucket for the build status
[18:26:17.828] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:17.828] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:20:0) after 1m0s
[18:27:17.864] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:20:0)
[18:27:17.864] Timer tick, asking Buildbucket for the build status
[18:27:17.958] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:17.958] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:21:0) after 1m0s
[18:28:18.077] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:21:0)
[18:28:18.077] Timer tick, asking Buildbucket for the build status
[18:28:18.178] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:18.178] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:22:0) after 1m0s
[18:29:18.207] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:22:0)
[18:29:18.207] Timer tick, asking Buildbucket for the build status
[18:29:18.342] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:18.342] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:23:0) after 1m0s
[18:30:18.362] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:23:0)
[18:30:18.362] Timer tick, asking Buildbucket for the build status
[18:30:18.504] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:18.504] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:24:0) after 1m0s
[18:31:18.524] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:24:0)
[18:31:18.524] Timer tick, asking Buildbucket for the build status
[18:31:19.049] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:19.049] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:25:0) after 1m0s
[18:32:19.112] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:25:0)
[18:32:19.112] Timer tick, asking Buildbucket for the build status
[18:32:21.122] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:21.122] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:26:0) after 1m0s
[18:33:21.176] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:26:0)
[18:33:21.176] Timer tick, asking Buildbucket for the build status
[18:33:21.425] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:21.425] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:27:0) after 1m0s
[18:34:21.447] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:27:0)
[18:34:21.447] Timer tick, asking Buildbucket for the build status
[18:34:22.502] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:22.502] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:28:0) after 1m0s
[18:35:22.525] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:28:0)
[18:35:22.525] Timer tick, asking Buildbucket for the build status
[18:35:22.616] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:22.616] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:29:0) after 1m0s
[18:36:22.670] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:29:0)
[18:36:22.670] Timer tick, asking Buildbucket for the build status
[18:36:22.975] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:22.975] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:30:0) after 1m0s
[18:37:23.123] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:30:0)
[18:37:23.123] Timer tick, asking Buildbucket for the build status
[18:37:23.606] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:23.606] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:31:0) after 1m0s
[18:38:23.690] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:31:0)
[18:38:23.690] Timer tick, asking Buildbucket for the build status
[18:38:23.795] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:23.795] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:32:0) after 1m0s
[18:39:23.842] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:32:0)
[18:39:23.842] Timer tick, asking Buildbucket for the build status
[18:39:24.521] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:24.521] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:33:0) after 1m0s
[18:40:24.536] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:33:0)
[18:40:24.536] Timer tick, asking Buildbucket for the build status
[18:40:24.706] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:24.706] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:34:0) after 1m0s
[18:41:24.726] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:34:0)
[18:41:24.726] Timer tick, asking Buildbucket for the build status
[18:41:25.063] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:25.063] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:35:0) after 1m0s
[18:42:25.106] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:35:0)
[18:42:25.106] Timer tick, asking Buildbucket for the build status
[18:42:25.340] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:25.340] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:36:0) after 1m0s
[18:43:25.356] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:36:0)
[18:43:25.356] Timer tick, asking Buildbucket for the build status
[18:43:25.496] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:25.496] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:37:0) after 1m0s
[18:44:25.596] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:37:0)
[18:44:25.596] Timer tick, asking Buildbucket for the build status
[18:44:25.706] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:25.706] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:38:0) after 1m0s
[18:45:25.857] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:38:0)
[18:45:25.857] Timer tick, asking Buildbucket for the build status
[18:45:25.990] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:25.990] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:39:0) after 1m0s
[18:46:26.050] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:39:0)
[18:46:26.050] Timer tick, asking Buildbucket for the build status
[18:46:26.713] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:26.713] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:40:0) after 1m0s
[18:47:26.732] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:40:0)
[18:47:26.732] Timer tick, asking Buildbucket for the build status
[18:47:26.960] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:26.960] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:41:0) after 1m0s
[18:48:26.982] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:41:0)
[18:48:26.982] Timer tick, asking Buildbucket for the build status
[18:48:27.159] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:27.159] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:42:0) after 1m0s
[18:49:27.299] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:42:0)
[18:49:27.299] Timer tick, asking Buildbucket for the build status
[18:49:27.717] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:27.717] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:43:0) after 1m0s
[18:50:27.746] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:43:0)
[18:50:27.746] Timer tick, asking Buildbucket for the build status
[18:50:27.948] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:27.948] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:44:0) after 1m0s
[18:51:28.026] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:44:0)
[18:51:28.026] Timer tick, asking Buildbucket for the build status
[18:51:28.989] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:28.989] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:45:0) after 1m0s
[18:52:28.999] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:45:0)
[18:52:28.999] Timer tick, asking Buildbucket for the build status
[18:52:29.182] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:29.182] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:46:0) after 1m0s
[18:53:29.239] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:46:0)
[18:53:29.239] Timer tick, asking Buildbucket for the build status
[18:53:29.459] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:29.459] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:47:0) after 1m0s
[18:54:29.458] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:47:0)
[18:54:29.458] Timer tick, asking Buildbucket for the build status
[18:54:29.676] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:29.676] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:48:0) after 1m0s
[18:55:29.696] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:48:0)
[18:55:29.696] Timer tick, asking Buildbucket for the build status
[18:55:29.923] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:29.923] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:49:0) after 1m0s
[18:56:30.018] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:49:0)
[18:56:30.018] Timer tick, asking Buildbucket for the build status
[18:56:31.175] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:31.175] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:50:0) after 1m0s
[18:57:31.231] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:50:0)
[18:57:31.231] Timer tick, asking Buildbucket for the build status
[18:57:31.374] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:31.374] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:51:0) after 1m0s
[18:58:31.533] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:51:0)
[18:58:31.533] Timer tick, asking Buildbucket for the build status
[18:58:31.662] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:31.662] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:52:0) after 1m0s
[18:59:31.655] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:52:0)
[18:59:31.655] Timer tick, asking Buildbucket for the build status
[18:59:32.325] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:32.325] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:53:0) after 1m0s
[19:00:32.384] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:53:0)
[19:00:32.384] Timer tick, asking Buildbucket for the build status
[19:00:33.479] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:33.479] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:54:0) after 1m0s
[19:01:33.518] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:54:0)
[19:01:33.518] Timer tick, asking Buildbucket for the build status
[19:01:33.644] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:33.644] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:55:0) after 1m0s
[19:02:33.695] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:55:0)
[19:02:33.695] Timer tick, asking Buildbucket for the build status
[19:02:33.800] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:33.800] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:56:0) after 1m0s
[19:03:33.839] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:56:0)
[19:03:33.839] Timer tick, asking Buildbucket for the build status
[19:03:35.173] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:35.173] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:57:0) after 1m0s
[19:04:35.207] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:57:0)
[19:04:35.207] Timer tick, asking Buildbucket for the build status
[19:04:35.383] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:35.383] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:58:0) after 1m0s
[19:05:35.418] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:58:0)
[19:05:35.418] Timer tick, asking Buildbucket for the build status
[19:05:35.939] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:35.939] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:59:0) after 1m0s
[19:06:35.998] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:59:0)
[19:06:35.998] Timer tick, asking Buildbucket for the build status
[19:06:36.243] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:36.243] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:60:0) after 1m0s
[19:07:36.345] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:60:0)
[19:07:36.345] Timer tick, asking Buildbucket for the build status
[19:07:37.150] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:37.150] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:61:0) after 1m0s
[19:08:37.241] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:61:0)
[19:08:37.241] Timer tick, asking Buildbucket for the build status
[19:08:38.575] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:38.575] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:62:0) after 1m0s
[19:09:38.611] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:62:0)
[19:09:38.611] Timer tick, asking Buildbucket for the build status
[19:09:38.975] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:38.975] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:63:0) after 1m0s
[19:10:38.995] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:63:0)
[19:10:38.995] Timer tick, asking Buildbucket for the build status
[19:10:39.612] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:39.612] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:64:0) after 1m0s
[19:11:39.611] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:64:0)
[19:11:39.611] Timer tick, asking Buildbucket for the build status
[19:11:39.718] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:39.718] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:65:0) after 1m0s
[19:12:39.713] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:65:0)
[19:12:39.713] Timer tick, asking Buildbucket for the build status
[19:12:39.808] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:39.808] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:66:0) after 1m0s
[19:13:39.830] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:66:0)
[19:13:39.830] Timer tick, asking Buildbucket for the build status
[19:13:39.921] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:39.921] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:67:0) after 1m0s
[19:14:40.063] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:67:0)
[19:14:40.063] Timer tick, asking Buildbucket for the build status
[19:14:40.535] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:40.535] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:68:0) after 1m0s
[19:15:40.544] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:68:0)
[19:15:40.544] Timer tick, asking Buildbucket for the build status
[19:15:40.879] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:40.879] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:69:0) after 1m0s
[19:16:41.008] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:69:0)
[19:16:41.008] Timer tick, asking Buildbucket for the build status
[19:16:41.338] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:41.338] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:70:0) after 1m0s
[19:17:41.357] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:70:0)
[19:17:41.357] Timer tick, asking Buildbucket for the build status
[19:17:41.567] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:41.567] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:71:0) after 1m0s
[19:18:41.614] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:71:0)
[19:18:41.614] Timer tick, asking Buildbucket for the build status
[19:18:41.765] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:41.765] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:72:0) after 1m0s
[19:19:41.888] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:72:0)
[19:19:41.888] Timer tick, asking Buildbucket for the build status
[19:19:44.416] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:44.416] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:73:0) after 1m0s
[19:20:44.462] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:73:0)
[19:20:44.462] Timer tick, asking Buildbucket for the build status
[19:20:44.550] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:44.551] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:74:0) after 1m0s
[19:21:44.587] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:74:0)
[19:21:44.587] Timer tick, asking Buildbucket for the build status
[19:21:45.457] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:45.457] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:75:0) after 1m0s
[19:22:45.531] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:75:0)
[19:22:45.531] Timer tick, asking Buildbucket for the build status
[19:22:45.975] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:45.975] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:76:0) after 1m0s
[19:23:46.013] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:76:0)
[19:23:46.013] Timer tick, asking Buildbucket for the build status
[19:23:46.460] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:46.460] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:77:0) after 1m0s
[19:24:46.493] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:77:0)
[19:24:46.493] Timer tick, asking Buildbucket for the build status
[19:24:46.585] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:46.585] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:78:0) after 1m0s
[19:25:46.607] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:78:0)
[19:25:46.607] Timer tick, asking Buildbucket for the build status
[19:25:47.007] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:47.007] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:79:0) after 1m0s
[19:26:47.052] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:79:0)
[19:26:47.052] Timer tick, asking Buildbucket for the build status
[19:26:48.327] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:48.327] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:80:0) after 1m0s
[19:27:48.345] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:80:0)
[19:27:48.345] Timer tick, asking Buildbucket for the build status
[19:27:48.904] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:48.904] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:81:0) after 1m0s
[19:28:48.926] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:81:0)
[19:28:48.926] Timer tick, asking Buildbucket for the build status
[19:28:49.513] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:49.513] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:82:0) after 1m0s
[19:29:49.548] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:82:0)
[19:29:49.548] Timer tick, asking Buildbucket for the build status
[19:29:49.641] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:49.641] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:83:0) after 1m0s
[19:30:49.665] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:83:0)
[19:30:49.665] Timer tick, asking Buildbucket for the build status
[19:30:50.016] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:50.016] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:84:0) after 1m0s
[19:31:50.039] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:84:0)
[19:31:50.039] Timer tick, asking Buildbucket for the build status
[19:31:50.158] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:50.158] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:85:0) after 1m0s
[19:32:50.198] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:85:0)
[19:32:50.198] Timer tick, asking Buildbucket for the build status
[19:32:51.216] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:51.216] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:86:0) after 1m0s
[19:33:51.269] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:86:0)
[19:33:51.269] Timer tick, asking Buildbucket for the build status
[19:33:52.109] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:52.109] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:87:0) after 1m0s
[19:34:52.165] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:87:0)
[19:34:52.165] Timer tick, asking Buildbucket for the build status
[19:34:52.311] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:52.311] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:88:0) after 1m0s
[19:35:52.376] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:88:0)
[19:35:52.376] Timer tick, asking Buildbucket for the build status
[19:35:53.269] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:53.269] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:89:0) after 1m0s
[19:36:53.329] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:89:0)
[19:36:53.329] Timer tick, asking Buildbucket for the build status
[19:36:53.490] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:53.490] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:90:0) after 1m0s
[19:37:53.541] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:90:0)
[19:37:53.541] Timer tick, asking Buildbucket for the build status
[19:37:53.788] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:53.788] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:91:0) after 1m0s
[19:38:53.812] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:91:0)
[19:38:53.812] Timer tick, asking Buildbucket for the build status
[19:38:53.946] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:53.946] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:92:0) after 1m0s
[19:39:54.019] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:92:0)
[19:39:54.019] Timer tick, asking Buildbucket for the build status
[19:39:54.169] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:54.169] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:93:0) after 1m0s
[19:40:54.237] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:93:0)
[19:40:54.237] Timer tick, asking Buildbucket for the build status
[19:40:54.841] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:54.841] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:94:0) after 1m0s
[19:41:54.962] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:94:0)
[19:41:54.962] Timer tick, asking Buildbucket for the build status
[19:41:55.041] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:55.041] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:95:0) after 1m0s
[19:42:55.126] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:95:0)
[19:42:55.126] Timer tick, asking Buildbucket for the build status
[19:42:55.212] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:55.212] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:96:0) after 1m0s
[19:43:55.284] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:96:0)
[19:43:55.284] Timer tick, asking Buildbucket for the build status
[19:43:56.072] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:56.072] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:97:0) after 1m0s
[19:44:56.117] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:97:0)
[19:44:56.118] Timer tick, asking Buildbucket for the build status
[19:44:56.360] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:56.360] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:98:0) after 1m0s
[19:45:56.382] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:98:0)
[19:45:56.382] Timer tick, asking Buildbucket for the build status
[19:45:56.799] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:56.799] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:99:0) after 1m0s
[19:46:56.841] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:99:0)
[19:46:56.841] Timer tick, asking Buildbucket for the build status
[19:46:56.967] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:56.967] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:100:0) after 1m0s
[19:47:56.988] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:100:0)
[19:47:56.988] Timer tick, asking Buildbucket for the build status
[19:47:57.119] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:57.119] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:101:0) after 1m0s
[19:48:57.154] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:101:0)
[19:48:57.154] Timer tick, asking Buildbucket for the build status
[19:48:57.249] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:57.249] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:102:0) after 1m0s
[19:49:57.279] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:102:0)
[19:49:57.279] Timer tick, asking Buildbucket for the build status
[19:49:57.390] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:57.390] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:103:0) after 1m0s
[19:50:57.425] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:103:0)
[19:50:57.425] Timer tick, asking Buildbucket for the build status
[19:50:58.327] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:58.327] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:104:0) after 1m0s
[19:51:58.435] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:104:0)
[19:51:58.435] Timer tick, asking Buildbucket for the build status
[19:51:58.601] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:58.601] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:105:0) after 1m0s
[19:52:58.704] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:105:0)
[19:52:58.704] Timer tick, asking Buildbucket for the build status
[19:53:00.013] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:00.013] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:106:0) after 1m0s
[19:54:00.180] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:106:0)
[19:54:00.180] Timer tick, asking Buildbucket for the build status
[19:54:00.645] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:00.645] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:107:0) after 1m0s
[19:55:00.717] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:107:0)
[19:55:00.717] Timer tick, asking Buildbucket for the build status
[19:55:00.819] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:00.819] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:108:0) after 1m0s
[19:56:00.843] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:108:0)
[19:56:00.843] Timer tick, asking Buildbucket for the build status
[19:56:00.974] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:00.974] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:109:0) after 1m0s
[19:57:01.028] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:109:0)
[19:57:01.028] Timer tick, asking Buildbucket for the build status
[19:57:01.116] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:01.116] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:110:0) after 1m0s
[19:58:01.185] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:110:0)
[19:58:01.185] Timer tick, asking Buildbucket for the build status
[19:58:01.510] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:01.510] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:111:0) after 1m0s
[19:59:01.534] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:111:0)
[19:59:01.534] Timer tick, asking Buildbucket for the build status
[19:59:01.657] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:01.657] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:112:0) after 1m0s
[20:00:01.897] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:112:0)
[20:00:01.897] Timer tick, asking Buildbucket for the build status
[20:00:02.022] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:02.022] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:113:0) after 1m0s
[20:01:02.077] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:113:0)
[20:01:02.077] Timer tick, asking Buildbucket for the build status
[20:01:02.219] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:02.219] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:114:0) after 1m0s
[20:02:02.251] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:114:0)
[20:02:02.251] Timer tick, asking Buildbucket for the build status
[20:02:02.882] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:02.882] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:115:0) after 1m0s
[20:03:03.002] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:115:0)
[20:03:03.002] Timer tick, asking Buildbucket for the build status
[20:03:03.258] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:03.258] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:116:0) after 1m0s
[20:04:03.336] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:116:0)
[20:04:03.336] Timer tick, asking Buildbucket for the build status
[20:04:03.939] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:03.939] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:117:0) after 1m0s
[20:05:04.088] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:117:0)
[20:05:04.088] Timer tick, asking Buildbucket for the build status
[20:05:05.091] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:05.091] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:118:0) after 1m0s
[20:06:05.181] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:118:0)
[20:06:05.181] Timer tick, asking Buildbucket for the build status
[20:06:05.315] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:05.315] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:119:0) after 1m0s
[20:07:05.348] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:119:0)
[20:07:05.348] Timer tick, asking Buildbucket for the build status
[20:07:05.578] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:05.579] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:120:0) after 1m0s
[20:08:05.749] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:120:0)
[20:08:05.749] Timer tick, asking Buildbucket for the build status
[20:08:05.870] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:05.870] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:121:0) after 1m0s
[20:09:05.967] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:121:0)
[20:09:05.967] Timer tick, asking Buildbucket for the build status
[20:09:06.950] Build 8910653386100112896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:06.950] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9076083448162296800:122:0) after 1m0s
[20:09:26.014] Received PubSub notification, asking Buildbucket for the build status
[20:09:26.469] Build 8910653386100112896: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[20:09:26.469] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1560542963904017",
"created_by": "project:chromium",
"created_ts": "1560535745485409",
"id": "8910653386100112896",
"parameters_json": "{\"builder_name\": \"Deterministic Linux\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"061e64bf593859f9c126b230de35c481b3774e75\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"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-trusty-8-22-97qg\", \"branch\": \"refs/heads/master\", \"buildername\": \"Deterministic Linux\", \"buildnumber\": 23728, \"got_angle_revision\": \"12f38c49d0ba6bec94808f072a3e29e0df85f081\", \"got_dawn_revision\": \"d1b4b5cba5571e3d380372db37348ab675f479d0\", \"got_nacl_revision\": \"c4cf6f0621b94cca4053b19fa01ef329f5663880\", \"got_revision\": \"061e64bf593859f9c126b230de35c481b3774e75\", \"got_revision_cp\": \"refs/heads/master@{#669287}\", \"got_swarming_client_revision\": \"96f125709acfd0b48fc1e5dae7d6ea42291726ac\", \"got_v8_revision\": \"8588d1575543e17087081281e2b29c7b001db294\", \"got_v8_revision_cp\": \"refs/heads/7.7.78@{#1}\", \"got_webrtc_revision\": \"3d61ab12e6d3bd2df884cdf40aeaf9b9f5fb6399\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#28290}\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"061e64bf593859f9c126b230de35c481b3774e75\"}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_662609c3a18ad850d61ba5f58749e60478bc4d9d576475e0c738a1c9e2d8d890_v2\", \"builder_78902ab2d70d1593a97658cf8910c0e117cf7950e257117b8c551c6fe1cfb403_v2\", \"builder_7e09b81c8d06479eaec2630704ae544ecbf4fef920ed2a3aec656c848d7a04d5_v2\", \"builder_a2d0deb167035deb42f2d46ec8df60bd17682f54960fd7d9f79bcb4fddee3411_v2\", \"builder_b9f10e617e36b89b59cf2468f05559e0083126a9b3741fee04e133349127cbf2_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-trusty-8-22-97qg\"], \"image\": [\"chrome-trusty-19041600-95c8804d634\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.6\"], \"server_version\": [\"4339-6285c57\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1560535784765235",
"status": "COMPLETED",
"status_changed_ts": "1560542964216930",
"tags": [
"build_address:luci.chromium.ci/Deterministic Linux/23728",
"builder:Deterministic Linux",
"buildset:commit/git/061e64bf593859f9c126b230de35c481b3774e75",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/061e64bf593859f9c126b230de35c481b3774e75",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9076083448162296800",
"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/8910653386100112896/+/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:456fff5c8c499e10",
"user_agent:luci-scheduler"
],
"updated_ts": "1560542964284016",
"url": "https://ci.chromium.org/b/8910653386100112896",
"utcnow_ts": "1560542966461557"
}
}
[20:09:26.469] Invocation finished in 2h0m22.334143652s with status SUCCEEDED