[22:42:06.263] New invocation is queued and will start shortly
[22:42:07.344] Starting the invocation (attempt 1)
[22:42:07.384] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:42:07.385] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:42:07.385] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9052964043841149152",
"parameters_json": "{\"builder_name\":\"Deterministic Linux (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"131ce69980b4298eb19e7f999f0b6e680a54f363\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Deterministic Linux (dbg)",
"scheduler_invocation_id:9052964043841149152",
"scheduler_job_id:chromium/Deterministic Linux (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/131ce69980b4298eb19e7f999f0b6e680a54f363",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/131ce69980b4298eb19e7f999f0b6e680a54f363",
"gitiles_ref:refs/heads/master"
]
}
[22:42:08.054] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1582584127506816",
"id": "8887533981874090752",
"parameters_json": "{\"builder_name\": \"Deterministic Linux (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"131ce69980b4298eb19e7f999f0b6e680a54f363\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1582584127914797",
"tags": [
"build_address:luci.chromium.ci/Deterministic Linux (dbg)/15239",
"builder:Deterministic Linux (dbg)",
"buildset:commit/git/131ce69980b4298eb19e7f999f0b6e680a54f363",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/131ce69980b4298eb19e7f999f0b6e680a54f363",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9052964043841149152",
"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/8887533981874090752/+/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": "1582584127914914",
"url": "https://ci.chromium.org/b/8887533981874090752",
"utcnow_ts": "1582584128044830"
}
}
[22:42:08.054] Task URL: https://ci.chromium.org/b/8887533981874090752
[22:42:08.054] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:2:0) after 1m0s
[22:43:08.221] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:2:0)
[22:43:08.221] Timer tick, asking Buildbucket for the build status
[22:43:08.331] Build 8887533981874090752: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:43:08.331] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:3:0) after 1m0s
[22:43:45.889] Received PubSub notification, asking Buildbucket for the build status
[22:43:45.953] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:08.568] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:3:0)
[22:44:08.568] Timer tick, asking Buildbucket for the build status
[22:44:08.746] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:08.746] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:5:0) after 1m0s
[22:45:08.932] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:5:0)
[22:45:08.932] Timer tick, asking Buildbucket for the build status
[22:45:09.034] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:09.034] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:6:0) after 1m0s
[22:46:09.208] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:6:0)
[22:46:09.208] Timer tick, asking Buildbucket for the build status
[22:46:09.380] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:09.380] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:7:0) after 1m0s
[22:47:09.490] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:7:0)
[22:47:09.490] Timer tick, asking Buildbucket for the build status
[22:47:09.794] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:09.794] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:8:0) after 1m0s
[22:48:09.834] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:8:0)
[22:48:09.834] Timer tick, asking Buildbucket for the build status
[22:48:09.969] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:09.969] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:9:0) after 1m0s
[22:49:10.131] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:9:0)
[22:49:10.131] Timer tick, asking Buildbucket for the build status
[22:49:10.226] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:10.226] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:10:0) after 1m0s
[22:50:10.281] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:10:0)
[22:50:10.281] Timer tick, asking Buildbucket for the build status
[22:50:10.481] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:10.481] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:11:0) after 1m0s
[22:51:10.516] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:11:0)
[22:51:10.516] Timer tick, asking Buildbucket for the build status
[22:51:10.652] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:10.652] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:12:0) after 1m0s
[22:52:10.810] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:12:0)
[22:52:10.810] Timer tick, asking Buildbucket for the build status
[22:52:10.927] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:10.927] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:13:0) after 1m0s
[22:53:11.344] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:13:0)
[22:53:11.344] Timer tick, asking Buildbucket for the build status
[22:53:11.481] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:11.481] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:14:0) after 1m0s
[22:54:11.727] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:14:0)
[22:54:11.727] Timer tick, asking Buildbucket for the build status
[22:54:12.060] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:12.060] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:15:0) after 1m0s
[22:55:12.122] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:15:0)
[22:55:12.122] Timer tick, asking Buildbucket for the build status
[22:55:12.242] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:12.242] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:16:0) after 1m0s
[22:56:12.442] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:16:0)
[22:56:12.442] Timer tick, asking Buildbucket for the build status
[22:56:12.550] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:12.550] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:17:0) after 1m0s
[22:57:12.636] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:17:0)
[22:57:12.636] Timer tick, asking Buildbucket for the build status
[22:57:12.762] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:12.762] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:18:0) after 1m0s
[22:58:12.765] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:18:0)
[22:58:12.765] Timer tick, asking Buildbucket for the build status
[22:58:12.874] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:12.874] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:19:0) after 1m0s
[22:59:12.946] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:19:0)
[22:59:12.946] Timer tick, asking Buildbucket for the build status
[22:59:13.141] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:13.141] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:20:0) after 1m0s
[23:00:13.483] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:20:0)
[23:00:13.483] Timer tick, asking Buildbucket for the build status
[23:00:13.787] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:13.787] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:21:0) after 1m0s
[23:01:14.038] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:21:0)
[23:01:14.038] Timer tick, asking Buildbucket for the build status
[23:01:14.171] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:14.171] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:22:0) after 1m0s
[23:02:14.396] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:22:0)
[23:02:14.396] Timer tick, asking Buildbucket for the build status
[23:02:14.570] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:14.570] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:23:0) after 1m0s
[23:03:14.615] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:23:0)
[23:03:14.615] Timer tick, asking Buildbucket for the build status
[23:03:14.801] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:14.801] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:24:0) after 1m0s
[23:04:14.960] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:24:0)
[23:04:14.960] Timer tick, asking Buildbucket for the build status
[23:04:15.220] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:15.220] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:25:0) after 1m0s
[23:05:15.270] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:25:0)
[23:05:15.270] Timer tick, asking Buildbucket for the build status
[23:05:15.365] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:15.365] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:26:0) after 1m0s
[23:06:15.447] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:26:0)
[23:06:15.447] Timer tick, asking Buildbucket for the build status
[23:06:15.779] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:15.779] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:27:0) after 1m0s
[23:07:15.838] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:27:0)
[23:07:15.838] Timer tick, asking Buildbucket for the build status
[23:07:15.967] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:15.967] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:28:0) after 1m0s
[23:08:16.013] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:28:0)
[23:08:16.013] Timer tick, asking Buildbucket for the build status
[23:08:16.155] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:16.155] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:29:0) after 1m0s
[23:09:16.208] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:29:0)
[23:09:16.208] Timer tick, asking Buildbucket for the build status
[23:09:16.328] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:16.328] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:30:0) after 1m0s
[23:10:16.439] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:30:0)
[23:10:16.439] Timer tick, asking Buildbucket for the build status
[23:10:16.701] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:16.701] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:31:0) after 1m0s
[23:11:16.768] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:31:0)
[23:11:16.768] Timer tick, asking Buildbucket for the build status
[23:11:18.386] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:18.386] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:32:0) after 1m0s
[23:12:18.487] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:32:0)
[23:12:18.487] Timer tick, asking Buildbucket for the build status
[23:12:18.720] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:18.720] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:33:0) after 1m0s
[23:13:18.838] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:33:0)
[23:13:18.838] Timer tick, asking Buildbucket for the build status
[23:13:19.214] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:19.214] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:34:0) after 1m0s
[23:14:19.553] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:34:0)
[23:14:19.553] Timer tick, asking Buildbucket for the build status
[23:14:19.682] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:19.682] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:35:0) after 1m0s
[23:15:20.105] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:35:0)
[23:15:20.105] Timer tick, asking Buildbucket for the build status
[23:15:20.834] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:20.834] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:36:0) after 1m0s
[23:16:21.046] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:36:0)
[23:16:21.046] Timer tick, asking Buildbucket for the build status
[23:16:21.237] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:21.237] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:37:0) after 1m0s
[23:17:21.320] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:37:0)
[23:17:21.320] Timer tick, asking Buildbucket for the build status
[23:17:21.489] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:21.489] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:38:0) after 1m0s
[23:18:21.553] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:38:0)
[23:18:21.553] Timer tick, asking Buildbucket for the build status
[23:18:21.712] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:21.712] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:39:0) after 1m0s
[23:19:21.864] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:39:0)
[23:19:21.864] Timer tick, asking Buildbucket for the build status
[23:19:22.162] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:22.162] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:40:0) after 1m0s
[23:20:22.195] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:40:0)
[23:20:22.195] Timer tick, asking Buildbucket for the build status
[23:20:22.429] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:22.429] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:41:0) after 1m0s
[23:21:22.719] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:41:0)
[23:21:22.719] Timer tick, asking Buildbucket for the build status
[23:21:23.370] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:23.370] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:42:0) after 1m0s
[23:22:23.409] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:42:0)
[23:22:23.409] Timer tick, asking Buildbucket for the build status
[23:22:23.564] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:23.564] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:43:0) after 1m0s
[23:23:23.639] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:43:0)
[23:23:23.639] Timer tick, asking Buildbucket for the build status
[23:23:23.778] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:23.778] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:44:0) after 1m0s
[23:24:23.810] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:44:0)
[23:24:23.810] Timer tick, asking Buildbucket for the build status
[23:24:24.108] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:24.108] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:45:0) after 1m0s
[23:25:24.105] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:45:0)
[23:25:24.105] Timer tick, asking Buildbucket for the build status
[23:25:24.302] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:24.302] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:46:0) after 1m0s
[23:26:24.324] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:46:0)
[23:26:24.324] Timer tick, asking Buildbucket for the build status
[23:26:24.537] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:24.537] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:47:0) after 1m0s
[23:27:24.542] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:47:0)
[23:27:24.542] Timer tick, asking Buildbucket for the build status
[23:27:24.978] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:24.978] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:48:0) after 1m0s
[23:28:25.028] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:48:0)
[23:28:25.028] Timer tick, asking Buildbucket for the build status
[23:28:25.277] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:25.277] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:49:0) after 1m0s
[23:29:25.306] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:49:0)
[23:29:25.306] Timer tick, asking Buildbucket for the build status
[23:29:25.502] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:25.502] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:50:0) after 1m0s
[23:30:25.544] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:50:0)
[23:30:25.545] Timer tick, asking Buildbucket for the build status
[23:30:25.744] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:25.744] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:51:0) after 1m0s
[23:31:25.868] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:51:0)
[23:31:25.868] Timer tick, asking Buildbucket for the build status
[23:31:26.161] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:26.161] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:52:0) after 1m0s
[23:32:26.302] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:52:0)
[23:32:26.302] Timer tick, asking Buildbucket for the build status
[23:32:26.537] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:26.537] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:53:0) after 1m0s
[23:33:26.596] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:53:0)
[23:33:26.596] Timer tick, asking Buildbucket for the build status
[23:33:27.079] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:27.079] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:54:0) after 1m0s
[23:34:27.135] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:54:0)
[23:34:27.135] Timer tick, asking Buildbucket for the build status
[23:34:27.409] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:27.409] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:55:0) after 1m0s
[23:35:27.424] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:55:0)
[23:35:27.424] Timer tick, asking Buildbucket for the build status
[23:35:27.610] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:27.610] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:56:0) after 1m0s
[23:36:27.673] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:56:0)
[23:36:27.673] Timer tick, asking Buildbucket for the build status
[23:36:27.952] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:27.952] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:57:0) after 1m0s
[23:37:27.975] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:57:0)
[23:37:27.975] Timer tick, asking Buildbucket for the build status
[23:37:28.062] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:28.062] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:58:0) after 1m0s
[23:38:28.041] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:58:0)
[23:38:28.041] Timer tick, asking Buildbucket for the build status
[23:38:28.448] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:28.448] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:59:0) after 1m0s
[23:39:28.547] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:59:0)
[23:39:28.547] Timer tick, asking Buildbucket for the build status
[23:39:28.723] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:28.723] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:60:0) after 1m0s
[23:40:28.849] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:60:0)
[23:40:28.849] Timer tick, asking Buildbucket for the build status
[23:40:29.128] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:29.128] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:61:0) after 1m0s
[23:41:29.155] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:61:0)
[23:41:29.155] Timer tick, asking Buildbucket for the build status
[23:41:29.771] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:29.771] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:62:0) after 1m0s
[23:42:29.988] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:62:0)
[23:42:29.988] Timer tick, asking Buildbucket for the build status
[23:42:30.163] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:30.163] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:63:0) after 1m0s
[23:43:30.263] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:63:0)
[23:43:30.263] Timer tick, asking Buildbucket for the build status
[23:43:30.379] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:30.379] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:64:0) after 1m0s
[23:44:30.367] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:64:0)
[23:44:30.367] Timer tick, asking Buildbucket for the build status
[23:44:30.532] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:30.532] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:65:0) after 1m0s
[23:45:30.695] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:65:0)
[23:45:30.695] Timer tick, asking Buildbucket for the build status
[23:45:30.908] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:30.908] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:66:0) after 1m0s
[23:46:30.918] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:66:0)
[23:46:30.918] Timer tick, asking Buildbucket for the build status
[23:46:31.239] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:31.239] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:67:0) after 1m0s
[23:47:31.316] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:67:0)
[23:47:31.316] Timer tick, asking Buildbucket for the build status
[23:47:31.845] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:31.845] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:68:0) after 1m0s
[23:48:31.877] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:68:0)
[23:48:31.877] Timer tick, asking Buildbucket for the build status
[23:48:32.062] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:32.062] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:69:0) after 1m0s
[23:49:32.100] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:69:0)
[23:49:32.100] Timer tick, asking Buildbucket for the build status
[23:49:32.218] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:32.218] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:70:0) after 1m0s
[23:50:32.245] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:70:0)
[23:50:32.245] Timer tick, asking Buildbucket for the build status
[23:50:32.627] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:32.627] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:71:0) after 1m0s
[23:51:32.647] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:71:0)
[23:51:32.647] Timer tick, asking Buildbucket for the build status
[23:51:33.684] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:33.684] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:72:0) after 1m0s
[23:52:33.678] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:72:0)
[23:52:33.678] Timer tick, asking Buildbucket for the build status
[23:52:33.973] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:33.973] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:73:0) after 1m0s
[23:53:34.022] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:73:0)
[23:53:34.022] Timer tick, asking Buildbucket for the build status
[23:53:34.361] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:34.361] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:74:0) after 1m0s
[23:54:34.456] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:74:0)
[23:54:34.456] Timer tick, asking Buildbucket for the build status
[23:54:34.592] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:34.592] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:75:0) after 1m0s
[23:55:34.693] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:75:0)
[23:55:34.693] Timer tick, asking Buildbucket for the build status
[23:55:34.849] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:34.849] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:76:0) after 1m0s
[23:56:34.912] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:76:0)
[23:56:34.912] Timer tick, asking Buildbucket for the build status
[23:56:35.123] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:35.123] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:77:0) after 1m0s
[23:57:35.164] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:77:0)
[23:57:35.164] Timer tick, asking Buildbucket for the build status
[23:57:35.339] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:35.339] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:78:0) after 1m0s
[23:58:35.458] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:78:0)
[23:58:35.459] Timer tick, asking Buildbucket for the build status
[23:58:35.588] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:35.588] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:79:0) after 1m0s
[23:59:35.582] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:79:0)
[23:59:35.582] Timer tick, asking Buildbucket for the build status
[23:59:35.735] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:35.735] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:80:0) after 1m0s
[00:00:35.811] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:80:0)
[00:00:35.811] Timer tick, asking Buildbucket for the build status
[00:00:35.994] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:35.994] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:81:0) after 1m0s
[00:01:36.948] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:81:0)
[00:01:36.948] Timer tick, asking Buildbucket for the build status
[00:01:38.922] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:38.922] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:82:0) after 1m0s
[00:02:38.911] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:82:0)
[00:02:38.911] Timer tick, asking Buildbucket for the build status
[00:02:39.469] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:39.469] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:83:0) after 1m0s
[00:03:39.474] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:83:0)
[00:03:39.474] Timer tick, asking Buildbucket for the build status
[00:03:39.779] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:39.779] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:84:0) after 1m0s
[00:04:39.806] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:84:0)
[00:04:39.806] Timer tick, asking Buildbucket for the build status
[00:04:39.883] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:39.883] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:85:0) after 1m0s
[00:05:39.951] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:85:0)
[00:05:39.951] Timer tick, asking Buildbucket for the build status
[00:05:40.299] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:40.299] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:86:0) after 1m0s
[00:06:40.369] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:86:0)
[00:06:40.369] Timer tick, asking Buildbucket for the build status
[00:06:40.817] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:40.817] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:87:0) after 1m0s
[00:07:40.902] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:87:0)
[00:07:40.902] Timer tick, asking Buildbucket for the build status
[00:07:41.028] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:41.028] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:88:0) after 1m0s
[00:08:41.063] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:88:0)
[00:08:41.063] Timer tick, asking Buildbucket for the build status
[00:08:41.278] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:41.278] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:89:0) after 1m0s
[00:09:41.516] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:89:0)
[00:09:41.516] Timer tick, asking Buildbucket for the build status
[00:09:41.615] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:41.615] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:90:0) after 1m0s
[00:10:41.813] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:90:0)
[00:10:41.813] Timer tick, asking Buildbucket for the build status
[00:10:41.957] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:41.957] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:91:0) after 1m0s
[00:11:42.092] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:91:0)
[00:11:42.092] Timer tick, asking Buildbucket for the build status
[00:11:43.496] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:43.496] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:92:0) after 1m0s
[00:12:43.697] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:92:0)
[00:12:43.697] Timer tick, asking Buildbucket for the build status
[00:12:43.908] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:43.908] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:93:0) after 1m0s
[00:13:43.901] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:93:0)
[00:13:43.901] Timer tick, asking Buildbucket for the build status
[00:13:44.243] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:44.243] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:94:0) after 1m0s
[00:14:44.338] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:94:0)
[00:14:44.338] Timer tick, asking Buildbucket for the build status
[00:14:44.487] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:44.487] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:95:0) after 1m0s
[00:15:44.589] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:95:0)
[00:15:44.589] Timer tick, asking Buildbucket for the build status
[00:15:44.699] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:44.699] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:96:0) after 1m0s
[00:16:44.988] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:96:0)
[00:16:44.988] Timer tick, asking Buildbucket for the build status
[00:16:45.357] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:45.357] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:97:0) after 1m0s
[00:17:45.683] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:97:0)
[00:17:45.683] Timer tick, asking Buildbucket for the build status
[00:17:45.778] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:45.778] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:98:0) after 1m0s
[00:18:45.874] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:98:0)
[00:18:45.874] Timer tick, asking Buildbucket for the build status
[00:18:45.998] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:45.998] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:99:0) after 1m0s
[00:19:46.269] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:99:0)
[00:19:46.269] Timer tick, asking Buildbucket for the build status
[00:19:46.481] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:46.481] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:100:0) after 1m0s
[00:20:46.637] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:100:0)
[00:20:46.637] Timer tick, asking Buildbucket for the build status
[00:20:46.801] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:46.801] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:101:0) after 1m0s
[00:21:46.886] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:101:0)
[00:21:46.886] Timer tick, asking Buildbucket for the build status
[00:21:47.424] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:47.424] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:102:0) after 1m0s
[00:22:47.654] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:102:0)
[00:22:47.654] Timer tick, asking Buildbucket for the build status
[00:22:47.822] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:47.822] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:103:0) after 1m0s
[00:23:47.926] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:103:0)
[00:23:47.926] Timer tick, asking Buildbucket for the build status
[00:23:48.023] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:48.023] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:104:0) after 1m0s
[00:24:48.188] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:104:0)
[00:24:48.188] Timer tick, asking Buildbucket for the build status
[00:24:48.317] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:48.317] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:105:0) after 1m0s
[00:25:48.391] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:105:0)
[00:25:48.392] Timer tick, asking Buildbucket for the build status
[00:25:48.527] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:48.527] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:106:0) after 1m0s
[00:26:48.659] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:106:0)
[00:26:48.659] Timer tick, asking Buildbucket for the build status
[00:26:49.034] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:49.034] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:107:0) after 1m0s
[00:27:49.489] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:107:0)
[00:27:49.489] Timer tick, asking Buildbucket for the build status
[00:27:49.683] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:49.683] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:108:0) after 1m0s
[00:28:49.765] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:108:0)
[00:28:49.765] Timer tick, asking Buildbucket for the build status
[00:28:50.177] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:50.177] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:109:0) after 1m0s
[00:29:50.301] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:109:0)
[00:29:50.301] Timer tick, asking Buildbucket for the build status
[00:29:50.503] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:50.503] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:110:0) after 1m0s
[00:30:50.527] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:110:0)
[00:30:50.527] Timer tick, asking Buildbucket for the build status
[00:30:50.639] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:50.639] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:111:0) after 1m0s
[00:31:50.717] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:111:0)
[00:31:50.717] Timer tick, asking Buildbucket for the build status
[00:31:51.148] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:51.148] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:112:0) after 1m0s
[00:32:51.316] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:112:0)
[00:32:51.316] Timer tick, asking Buildbucket for the build status
[00:32:51.723] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:51.723] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:113:0) after 1m0s
[00:33:51.814] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:113:0)
[00:33:51.814] Timer tick, asking Buildbucket for the build status
[00:33:52.022] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:52.022] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:114:0) after 1m0s
[00:34:52.126] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:114:0)
[00:34:52.126] Timer tick, asking Buildbucket for the build status
[00:34:52.275] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:52.275] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:115:0) after 1m0s
[00:35:52.416] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:115:0)
[00:35:52.416] Timer tick, asking Buildbucket for the build status
[00:35:52.547] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:52.547] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:116:0) after 1m0s
[00:36:52.761] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:116:0)
[00:36:52.761] Timer tick, asking Buildbucket for the build status
[00:36:52.895] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:52.895] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:117:0) after 1m0s
[00:37:53.004] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:117:0)
[00:37:53.004] Timer tick, asking Buildbucket for the build status
[00:37:53.253] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:53.253] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:118:0) after 1m0s
[00:38:53.242] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:118:0)
[00:38:53.242] Timer tick, asking Buildbucket for the build status
[00:38:53.569] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:53.569] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:119:0) after 1m0s
[00:39:53.631] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:119:0)
[00:39:53.631] Timer tick, asking Buildbucket for the build status
[00:39:53.748] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:53.749] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:120:0) after 1m0s
[00:40:53.877] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:120:0)
[00:40:53.877] Timer tick, asking Buildbucket for the build status
[00:40:53.981] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:53.981] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:121:0) after 1m0s
[00:41:54.031] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:121:0)
[00:41:54.031] Timer tick, asking Buildbucket for the build status
[00:41:54.298] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:54.298] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:122:0) after 1m0s
[00:42:54.373] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:122:0)
[00:42:54.373] Timer tick, asking Buildbucket for the build status
[00:42:54.702] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:54.702] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:123:0) after 1m0s
[00:43:54.762] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:123:0)
[00:43:54.762] Timer tick, asking Buildbucket for the build status
[00:43:54.913] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:54.913] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:124:0) after 1m0s
[00:44:55.007] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:124:0)
[00:44:55.007] Timer tick, asking Buildbucket for the build status
[00:44:55.207] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:55.207] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:125:0) after 1m0s
[00:45:55.312] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:125:0)
[00:45:55.312] Timer tick, asking Buildbucket for the build status
[00:45:55.438] Build 8887533981874090752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:55.438] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9052964043841149152:126:0) after 1m0s
[00:46:11.104] Received PubSub notification, asking Buildbucket for the build status
[00:46:11.235] Build 8887533981874090752: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[00:46:11.235] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1582591570271667",
"created_by": "project:chromium",
"created_ts": "1582584127506816",
"failure_reason": "BUILD_FAILURE",
"id": "8887533981874090752",
"parameters_json": "{\"builder_name\": \"Deterministic Linux (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"131ce69980b4298eb19e7f999f0b6e680a54f363\"}}",
"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\": 15239, \"got_angle_revision\": \"c7f63a01d34473a95514661841a20d19668356a8\", \"got_dawn_revision\": \"1249ada986fc62a95439b8c8ec805fd3dff1e46d\", \"got_nacl_revision\": \"530e1f0dae34020864c81e5cd52be54d9c656690\", \"got_revision\": \"131ce69980b4298eb19e7f999f0b6e680a54f363\", \"got_revision_cp\": \"refs/heads/master@{#744099}\", \"got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"got_swiftshader_revision\": \"214ec337a0b2745e6c41fbb3d562fa517122812f\", \"got_v8_revision\": \"def23d3c03e73e345896a5a93de79197b9c9cc7b\", \"got_v8_revision_cp\": \"refs/heads/8.2.161@{#1}\", \"got_webrtc_revision\": \"1dea1ea412021819bddbc9400dcca7d9dd2eba74\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30581}\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"131ce69980b4298eb19e7f999f0b6e680a54f363\"}, \"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": "1582584224392879",
"status": "COMPLETED",
"status_changed_ts": "1582584225708154",
"tags": [
"build_address:luci.chromium.ci/Deterministic Linux (dbg)/15239",
"builder:Deterministic Linux (dbg)",
"buildset:commit/git/131ce69980b4298eb19e7f999f0b6e680a54f363",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/131ce69980b4298eb19e7f999f0b6e680a54f363",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9052964043841149152",
"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/8887533981874090752/+/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:4a922eeb19c2ba10",
"user_agent:luci-scheduler"
],
"updated_ts": "1582591570450830",
"url": "https://ci.chromium.org/b/8887533981874090752",
"utcnow_ts": "1582591571224550"
}
}
[00:46:11.235] Invocation finished in 2h4m4.987840408s with status FAILED