[20:55:31.428] New invocation is queued and will start shortly
[20:55:32.672] Starting the invocation (attempt 1)
[20:55:32.727] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:55:32.728] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:55:32.728] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9053333137185292656",
"parameters_json": "{\"builder_name\":\"Deterministic Linux (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"dc25d70847aa5d7d7c1893677259d05b9c377e8f\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Deterministic Linux (dbg)",
"scheduler_invocation_id:9053333137185292656",
"scheduler_job_id:chromium/Deterministic Linux (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/dc25d70847aa5d7d7c1893677259d05b9c377e8f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dc25d70847aa5d7d7c1893677259d05b9c377e8f",
"gitiles_ref:refs/heads/master"
]
}
[20:55:33.668] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1582232133015248",
"id": "8887903074849763040",
"parameters_json": "{\"builder_name\": \"Deterministic Linux (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"dc25d70847aa5d7d7c1893677259d05b9c377e8f\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1582232133517799",
"tags": [
"build_address:luci.chromium.ci/Deterministic Linux (dbg)/15139",
"builder:Deterministic Linux (dbg)",
"buildset:commit/git/dc25d70847aa5d7d7c1893677259d05b9c377e8f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dc25d70847aa5d7d7c1893677259d05b9c377e8f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9053333137185292656",
"scheduler_job_id:chromium/Deterministic Linux (dbg)",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8887903074849763040/+/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": "1582232133517905",
"url": "https://ci.chromium.org/b/8887903074849763040",
"utcnow_ts": "1582232133649757"
}
}
[20:55:33.668] Task URL: https://ci.chromium.org/b/8887903074849763040
[20:55:33.668] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:2:0) after 1m0s
[20:56:33.727] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:2:0)
[20:56:33.727] Timer tick, asking Buildbucket for the build status
[20:56:33.834] Build 8887903074849763040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:56:33.834] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:3:0) after 1m0s
[20:56:56.501] Received PubSub notification, asking Buildbucket for the build status
[20:56:56.609] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:33.868] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:3:0)
[20:57:33.868] Timer tick, asking Buildbucket for the build status
[20:57:34.319] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:34.319] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:5:0) after 1m0s
[20:58:34.448] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:5:0)
[20:58:34.448] Timer tick, asking Buildbucket for the build status
[20:58:34.662] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:34.662] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:6:0) after 1m0s
[20:59:34.783] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:6:0)
[20:59:34.783] Timer tick, asking Buildbucket for the build status
[20:59:34.892] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:34.892] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:7:0) after 1m0s
[21:00:35.048] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:7:0)
[21:00:35.048] Timer tick, asking Buildbucket for the build status
[21:00:35.517] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:35.517] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:8:0) after 1m0s
[21:01:35.592] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:8:0)
[21:01:35.592] Timer tick, asking Buildbucket for the build status
[21:01:35.683] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:35.683] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:9:0) after 1m0s
[21:02:35.831] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:9:0)
[21:02:35.831] Timer tick, asking Buildbucket for the build status
[21:02:35.965] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:35.965] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:10:0) after 1m0s
[21:03:36.026] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:10:0)
[21:03:36.026] Timer tick, asking Buildbucket for the build status
[21:03:36.126] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:36.126] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:11:0) after 1m0s
[21:04:36.199] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:11:0)
[21:04:36.199] Timer tick, asking Buildbucket for the build status
[21:04:36.322] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:36.322] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:12:0) after 1m0s
[21:05:36.445] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:12:0)
[21:05:36.445] Timer tick, asking Buildbucket for the build status
[21:05:36.994] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:36.994] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:13:0) after 1m0s
[21:06:37.054] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:13:0)
[21:06:37.054] Timer tick, asking Buildbucket for the build status
[21:06:37.222] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:37.222] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:14:0) after 1m0s
[21:07:37.279] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:14:0)
[21:07:37.279] Timer tick, asking Buildbucket for the build status
[21:07:39.241] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:39.241] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:15:0) after 1m0s
[21:08:39.310] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:15:0)
[21:08:39.310] Timer tick, asking Buildbucket for the build status
[21:08:39.431] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:39.431] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:16:0) after 1m0s
[21:09:39.520] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:16:0)
[21:09:39.520] Timer tick, asking Buildbucket for the build status
[21:09:39.841] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:39.841] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:17:0) after 1m0s
[21:10:39.947] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:17:0)
[21:10:39.947] Timer tick, asking Buildbucket for the build status
[21:10:40.542] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:40.542] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:18:0) after 1m0s
[21:11:40.642] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:18:0)
[21:11:40.642] Timer tick, asking Buildbucket for the build status
[21:11:40.718] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:40.718] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:19:0) after 1m0s
[21:12:40.786] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:19:0)
[21:12:40.786] Timer tick, asking Buildbucket for the build status
[21:12:41.142] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:41.142] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:20:0) after 1m0s
[21:13:41.162] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:20:0)
[21:13:41.162] Timer tick, asking Buildbucket for the build status
[21:13:41.414] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:41.414] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:21:0) after 1m0s
[21:14:41.453] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:21:0)
[21:14:41.453] Timer tick, asking Buildbucket for the build status
[21:14:41.642] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:41.642] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:22:0) after 1m0s
[21:15:41.718] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:22:0)
[21:15:41.718] Timer tick, asking Buildbucket for the build status
[21:15:42.324] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:42.324] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:23:0) after 1m0s
[21:16:42.353] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:23:0)
[21:16:42.353] Timer tick, asking Buildbucket for the build status
[21:16:42.525] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:42.525] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:24:0) after 1m0s
[21:17:42.661] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:24:0)
[21:17:42.661] Timer tick, asking Buildbucket for the build status
[21:17:42.828] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:42.828] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:25:0) after 1m0s
[21:18:42.919] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:25:0)
[21:18:42.919] Timer tick, asking Buildbucket for the build status
[21:18:43.066] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:43.066] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:26:0) after 1m0s
[21:19:43.121] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:26:0)
[21:19:43.121] Timer tick, asking Buildbucket for the build status
[21:19:43.253] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:43.253] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:27:0) after 1m0s
[21:20:43.350] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:27:0)
[21:20:43.350] Timer tick, asking Buildbucket for the build status
[21:20:43.541] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:43.541] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:28:0) after 1m0s
[21:21:43.625] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:28:0)
[21:21:43.625] Timer tick, asking Buildbucket for the build status
[21:21:43.769] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:43.769] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:29:0) after 1m0s
[21:22:43.904] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:29:0)
[21:22:43.904] Timer tick, asking Buildbucket for the build status
[21:22:44.305] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:44.305] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:30:0) after 1m0s
[21:23:44.401] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:30:0)
[21:23:44.401] Timer tick, asking Buildbucket for the build status
[21:23:44.632] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:44.632] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:31:0) after 1m0s
[21:24:44.692] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:31:0)
[21:24:44.692] Timer tick, asking Buildbucket for the build status
[21:24:44.834] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:44.834] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:32:0) after 1m0s
[21:25:44.896] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:32:0)
[21:25:44.896] Timer tick, asking Buildbucket for the build status
[21:25:45.270] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:45.271] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:33:0) after 1m0s
[21:26:45.334] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:33:0)
[21:26:45.334] Timer tick, asking Buildbucket for the build status
[21:26:45.478] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:45.478] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:34:0) after 1m0s
[21:27:45.482] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:34:0)
[21:27:45.482] Timer tick, asking Buildbucket for the build status
[21:27:45.622] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:45.622] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:35:0) after 1m0s
[21:28:45.691] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:35:0)
[21:28:45.691] Timer tick, asking Buildbucket for the build status
[21:28:45.785] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:45.785] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:36:0) after 1m0s
[21:29:45.863] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:36:0)
[21:29:45.863] Timer tick, asking Buildbucket for the build status
[21:29:45.949] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:45.949] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:37:0) after 1m0s
[21:30:46.012] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:37:0)
[21:30:46.012] Timer tick, asking Buildbucket for the build status
[21:30:46.183] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:46.183] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:38:0) after 1m0s
[21:31:46.232] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:38:0)
[21:31:46.232] Timer tick, asking Buildbucket for the build status
[21:31:46.514] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:46.514] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:39:0) after 1m0s
[21:32:46.549] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:39:0)
[21:32:46.549] Timer tick, asking Buildbucket for the build status
[21:32:46.700] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:46.700] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:40:0) after 1m0s
[21:33:46.731] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:40:0)
[21:33:46.731] Timer tick, asking Buildbucket for the build status
[21:33:46.818] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:46.818] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:41:0) after 1m0s
[21:34:46.842] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:41:0)
[21:34:46.842] Timer tick, asking Buildbucket for the build status
[21:34:47.062] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:47.062] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:42:0) after 1m0s
[21:35:47.142] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:42:0)
[21:35:47.142] Timer tick, asking Buildbucket for the build status
[21:35:47.333] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:47.334] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:43:0) after 1m0s
[21:36:47.436] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:43:0)
[21:36:47.436] Timer tick, asking Buildbucket for the build status
[21:36:47.816] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:47.816] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:44:0) after 1m0s
[21:37:48.137] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:44:0)
[21:37:48.137] Timer tick, asking Buildbucket for the build status
[21:37:48.327] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:48.327] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:45:0) after 1m0s
[21:38:48.551] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:45:0)
[21:38:48.551] Timer tick, asking Buildbucket for the build status
[21:38:48.761] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:48.761] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:46:0) after 1m0s
[21:39:48.852] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:46:0)
[21:39:48.852] Timer tick, asking Buildbucket for the build status
[21:39:49.145] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:49.145] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:47:0) after 1m0s
[21:40:49.229] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:47:0)
[21:40:49.229] Timer tick, asking Buildbucket for the build status
[21:40:49.333] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:49.333] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:48:0) after 1m0s
[21:41:49.487] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:48:0)
[21:41:49.487] Timer tick, asking Buildbucket for the build status
[21:41:49.580] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:49.580] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:49:0) after 1m0s
[21:42:49.608] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:49:0)
[21:42:49.608] Timer tick, asking Buildbucket for the build status
[21:42:50.048] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:50.048] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:50:0) after 1m0s
[21:43:50.300] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:50:0)
[21:43:50.300] Timer tick, asking Buildbucket for the build status
[21:43:50.412] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:50.412] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:51:0) after 1m0s
[21:44:50.606] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:51:0)
[21:44:50.606] Timer tick, asking Buildbucket for the build status
[21:44:50.861] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:50.861] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:52:0) after 1m0s
[21:45:50.925] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:52:0)
[21:45:50.925] Timer tick, asking Buildbucket for the build status
[21:45:51.168] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:51.168] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:53:0) after 1m0s
[21:46:51.204] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:53:0)
[21:46:51.204] Timer tick, asking Buildbucket for the build status
[21:46:51.480] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:51.480] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:54:0) after 1m0s
[21:47:51.476] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:54:0)
[21:47:51.476] Timer tick, asking Buildbucket for the build status
[21:47:51.757] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:51.757] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:55:0) after 1m0s
[21:48:51.855] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:55:0)
[21:48:51.855] Timer tick, asking Buildbucket for the build status
[21:48:51.944] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:51.944] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:56:0) after 1m0s
[21:49:52.078] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:56:0)
[21:49:52.078] Timer tick, asking Buildbucket for the build status
[21:49:52.174] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:52.174] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:57:0) after 1m0s
[21:50:52.234] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:57:0)
[21:50:52.234] Timer tick, asking Buildbucket for the build status
[21:50:52.493] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:52.493] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:58:0) after 1m0s
[21:51:52.856] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:58:0)
[21:51:52.856] Timer tick, asking Buildbucket for the build status
[21:51:53.021] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:53.021] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:59:0) after 1m0s
[21:52:53.263] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:59:0)
[21:52:53.263] Timer tick, asking Buildbucket for the build status
[21:52:53.576] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:53.576] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:60:0) after 1m0s
[21:53:53.708] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:60:0)
[21:53:53.708] Timer tick, asking Buildbucket for the build status
[21:53:53.858] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:53.858] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:61:0) after 1m0s
[21:54:53.950] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:61:0)
[21:54:53.950] Timer tick, asking Buildbucket for the build status
[21:54:54.095] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:54.095] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:62:0) after 1m0s
[21:55:54.182] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:62:0)
[21:55:54.182] Timer tick, asking Buildbucket for the build status
[21:55:54.444] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:54.444] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:63:0) after 1m0s
[21:56:54.483] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:63:0)
[21:56:54.483] Timer tick, asking Buildbucket for the build status
[21:56:54.791] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:54.791] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:64:0) after 1m0s
[21:57:54.811] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:64:0)
[21:57:54.811] Timer tick, asking Buildbucket for the build status
[21:57:54.888] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:54.888] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:65:0) after 1m0s
[21:58:54.966] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:65:0)
[21:58:54.966] Timer tick, asking Buildbucket for the build status
[21:58:55.474] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:55.474] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:66:0) after 1m0s
[21:59:55.532] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:66:0)
[21:59:55.532] Timer tick, asking Buildbucket for the build status
[21:59:55.871] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:55.871] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:67:0) after 1m0s
[22:00:55.945] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:67:0)
[22:00:55.945] Timer tick, asking Buildbucket for the build status
[22:00:56.122] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:56.122] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:68:0) after 1m0s
[22:01:56.184] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:68:0)
[22:01:56.184] Timer tick, asking Buildbucket for the build status
[22:01:56.358] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:56.358] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:69:0) after 1m0s
[22:02:56.445] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:69:0)
[22:02:56.445] Timer tick, asking Buildbucket for the build status
[22:02:56.735] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:56.735] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:70:0) after 1m0s
[22:03:56.778] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:70:0)
[22:03:56.778] Timer tick, asking Buildbucket for the build status
[22:03:56.884] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:56.884] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:71:0) after 1m0s
[22:04:56.948] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:71:0)
[22:04:56.948] Timer tick, asking Buildbucket for the build status
[22:04:57.044] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:57.044] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:72:0) after 1m0s
[22:05:57.106] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:72:0)
[22:05:57.106] Timer tick, asking Buildbucket for the build status
[22:05:58.885] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:58.885] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:73:0) after 1m0s
[22:06:58.924] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:73:0)
[22:06:58.924] Timer tick, asking Buildbucket for the build status
[22:06:59.196] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:59.196] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:74:0) after 1m0s
[22:07:59.285] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:74:0)
[22:07:59.285] Timer tick, asking Buildbucket for the build status
[22:08:15.812] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:15.812] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:75:0) after 1m0s
[22:09:15.839] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:75:0)
[22:09:15.839] Timer tick, asking Buildbucket for the build status
[22:09:16.481] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:16.481] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:76:0) after 1m0s
[22:10:16.552] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:76:0)
[22:10:16.552] Timer tick, asking Buildbucket for the build status
[22:10:17.089] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:17.089] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:77:0) after 1m0s
[22:11:17.221] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:77:0)
[22:11:17.221] Timer tick, asking Buildbucket for the build status
[22:11:17.475] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:17.475] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:78:0) after 1m0s
[22:12:17.577] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:78:0)
[22:12:17.577] Timer tick, asking Buildbucket for the build status
[22:12:17.805] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:17.805] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:79:0) after 1m0s
[22:13:17.921] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:79:0)
[22:13:17.921] Timer tick, asking Buildbucket for the build status
[22:13:18.085] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:18.085] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:80:0) after 1m0s
[22:14:18.131] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:80:0)
[22:14:18.131] Timer tick, asking Buildbucket for the build status
[22:14:18.446] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:18.446] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:81:0) after 1m0s
[22:15:18.527] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:81:0)
[22:15:18.527] Timer tick, asking Buildbucket for the build status
[22:15:18.668] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:18.668] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:82:0) after 1m0s
[22:16:18.853] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:82:0)
[22:16:18.853] Timer tick, asking Buildbucket for the build status
[22:16:19.112] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:19.112] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:83:0) after 1m0s
[22:17:19.219] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:83:0)
[22:17:19.219] Timer tick, asking Buildbucket for the build status
[22:17:19.618] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:19.618] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:84:0) after 1m0s
[22:18:19.777] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:84:0)
[22:18:19.777] Timer tick, asking Buildbucket for the build status
[22:18:20.177] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:20.177] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:85:0) after 1m0s
[22:19:20.427] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:85:0)
[22:19:20.427] Timer tick, asking Buildbucket for the build status
[22:19:20.547] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:20.547] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:86:0) after 1m0s
[22:20:20.650] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:86:0)
[22:20:20.650] Timer tick, asking Buildbucket for the build status
[22:20:21.441] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:21.441] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:87:0) after 1m0s
[22:21:21.471] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:87:0)
[22:21:21.471] Timer tick, asking Buildbucket for the build status
[22:21:21.758] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:21.758] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:88:0) after 1m0s
[22:22:21.863] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:88:0)
[22:22:21.863] Timer tick, asking Buildbucket for the build status
[22:22:22.508] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:22.508] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:89:0) after 1m0s
[22:23:22.552] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:89:0)
[22:23:22.552] Timer tick, asking Buildbucket for the build status
[22:23:22.644] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:22.645] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:90:0) after 1m0s
[22:24:22.746] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:90:0)
[22:24:22.746] Timer tick, asking Buildbucket for the build status
[22:24:23.113] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:23.113] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:91:0) after 1m0s
[22:25:23.157] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:91:0)
[22:25:23.157] Timer tick, asking Buildbucket for the build status
[22:25:24.179] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:24.179] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:92:0) after 1m0s
[22:26:24.203] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:92:0)
[22:26:24.203] Timer tick, asking Buildbucket for the build status
[22:26:24.317] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:24.317] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:93:0) after 1m0s
[22:27:24.382] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:93:0)
[22:27:24.382] Timer tick, asking Buildbucket for the build status
[22:27:24.542] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:24.542] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:94:0) after 1m0s
[22:28:24.624] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:94:0)
[22:28:24.624] Timer tick, asking Buildbucket for the build status
[22:28:24.828] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:24.828] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:95:0) after 1m0s
[22:29:24.890] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:95:0)
[22:29:24.890] Timer tick, asking Buildbucket for the build status
[22:29:25.358] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:25.358] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:96:0) after 1m0s
[22:30:25.544] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:96:0)
[22:30:25.544] Timer tick, asking Buildbucket for the build status
[22:30:26.263] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:26.263] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:97:0) after 1m0s
[22:31:26.696] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:97:0)
[22:31:26.696] Timer tick, asking Buildbucket for the build status
[22:31:28.076] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:28.076] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:98:0) after 1m0s
[22:32:28.299] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:98:0)
[22:32:28.299] Timer tick, asking Buildbucket for the build status
[22:32:28.432] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:28.432] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:99:0) after 1m0s
[22:33:28.638] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:99:0)
[22:33:28.638] Timer tick, asking Buildbucket for the build status
[22:33:28.802] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:28.802] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:100:0) after 1m0s
[22:34:28.845] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:100:0)
[22:34:28.845] Timer tick, asking Buildbucket for the build status
[22:34:28.987] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:28.987] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:101:0) after 1m0s
[22:35:29.247] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:101:0)
[22:35:29.247] Timer tick, asking Buildbucket for the build status
[22:35:29.509] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:29.509] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:102:0) after 1m0s
[22:36:29.598] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:102:0)
[22:36:29.598] Timer tick, asking Buildbucket for the build status
[22:36:30.481] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:30.481] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:103:0) after 1m0s
[22:37:30.668] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:103:0)
[22:37:30.668] Timer tick, asking Buildbucket for the build status
[22:37:30.870] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:30.870] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:104:0) after 1m0s
[22:38:30.926] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:104:0)
[22:38:30.926] Timer tick, asking Buildbucket for the build status
[22:38:31.132] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:31.132] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:105:0) after 1m0s
[22:39:31.209] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:105:0)
[22:39:31.209] Timer tick, asking Buildbucket for the build status
[22:39:31.574] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:31.574] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:106:0) after 1m0s
[22:40:31.637] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:106:0)
[22:40:31.637] Timer tick, asking Buildbucket for the build status
[22:40:31.837] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:31.837] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:107:0) after 1m0s
[22:41:31.896] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:107:0)
[22:41:31.896] Timer tick, asking Buildbucket for the build status
[22:41:32.126] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:32.126] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:108:0) after 1m0s
[22:42:32.199] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:108:0)
[22:42:32.199] Timer tick, asking Buildbucket for the build status
[22:42:32.726] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:32.726] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:109:0) after 1m0s
[22:43:32.769] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:109:0)
[22:43:32.769] Timer tick, asking Buildbucket for the build status
[22:43:32.870] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:32.870] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:110:0) after 1m0s
[22:44:33.032] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:110:0)
[22:44:33.032] Timer tick, asking Buildbucket for the build status
[22:44:33.126] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:33.126] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:111:0) after 1m0s
[22:45:33.147] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:111:0)
[22:45:33.147] Timer tick, asking Buildbucket for the build status
[22:45:33.794] Build 8887903074849763040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:33.794] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9053333137185292656:112:0) after 1m0s
[22:45:38.192] Received PubSub notification, asking Buildbucket for the build status
[22:45:40.463] Build 8887903074849763040: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[22:45:40.463] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1582238737823826",
"created_by": "project:chromium",
"created_ts": "1582232133015248",
"failure_reason": "BUILD_FAILURE",
"id": "8887903074849763040",
"parameters_json": "{\"builder_name\": \"Deterministic Linux (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"dc25d70847aa5d7d7c1893677259d05b9c377e8f\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"Step('compare_build_artifacts') (retcode: 1)\"}, \"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-32-15-8s13\", \"branch\": \"refs/heads/master\", \"buildername\": \"Deterministic Linux (dbg)\", \"buildnumber\": 15139, \"got_angle_revision\": \"b4357d46c6c64cc1e98f90fe2051af25ed8f74f6\", \"got_dawn_revision\": \"b330ea6b491e3e000c11fe919e8099dece1c939b\", \"got_nacl_revision\": \"530e1f0dae34020864c81e5cd52be54d9c656690\", \"got_revision\": \"dc25d70847aa5d7d7c1893677259d05b9c377e8f\", \"got_revision_cp\": \"refs/heads/master@{#743239}\", \"got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"got_swiftshader_revision\": \"3cfba7f05be4a68edb53fe1faca764c678bec4f5\", \"got_v8_revision\": \"c9de129a2763794ba38c79591852a761f2a8e912\", \"got_v8_revision_cp\": \"refs/heads/8.2.135@{#1}\", \"got_webrtc_revision\": \"d881983a38b40619970710a03ad346663ad5d9db\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30572}\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"dc25d70847aa5d7d7c1893677259d05b9c377e8f\"}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_940b8dd4638ac05ec7d44839e381a2baf02720b3eac518443ac04ffe6b1809c0_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-32-15-8s13\"], \"image\": [\"chrome-xenial-20012600-8e1a7e485b7\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-32\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"4825-3d129d6\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"]}}, \"ui\": {\"info\": \"Step('compare_build_artifacts') (retcode: 1)\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1582232215495735",
"status": "COMPLETED",
"status_changed_ts": "1582232216087893",
"tags": [
"build_address:luci.chromium.ci/Deterministic Linux (dbg)/15139",
"builder:Deterministic Linux (dbg)",
"buildset:commit/git/dc25d70847aa5d7d7c1893677259d05b9c377e8f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dc25d70847aa5d7d7c1893677259d05b9c377e8f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9053333137185292656",
"scheduler_job_id:chromium/Deterministic Linux (dbg)",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8887903074849763040/+/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:4a7d33e88ab49210",
"user_agent:luci-scheduler"
],
"updated_ts": "1582238737905591",
"url": "https://ci.chromium.org/b/8887903074849763040",
"utcnow_ts": "1582238740454312"
}
}
[22:45:40.463] Invocation finished in 1h50m9.059199822s with status FAILED