[16:53:26.955] New invocation is queued and will start shortly
[16:53:29.272] Starting the invocation (attempt 1)
[16:53:29.368] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:53:29.368] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:53:29.368] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9060596124519149296",
"parameters_json": "{\"builder_name\":\"Deterministic Linux (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"9619d5ec7de5edd109981a12fa9d223b7b6e68a3\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Deterministic Linux (dbg)",
"scheduler_invocation_id:9060596124519149296",
"scheduler_job_id:chromium/Deterministic Linux (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/9619d5ec7de5edd109981a12fa9d223b7b6e68a3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9619d5ec7de5edd109981a12fa9d223b7b6e68a3",
"gitiles_ref:refs/heads/master"
]
}
[16:53:30.419] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1575305609556664",
"id": "8895166061111944576",
"parameters_json": "{\"builder_name\": \"Deterministic Linux (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"9619d5ec7de5edd109981a12fa9d223b7b6e68a3\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1575305610169371",
"tags": [
"build_address:luci.chromium.ci/Deterministic Linux (dbg)/13096",
"builder:Deterministic Linux (dbg)",
"buildset:commit/git/9619d5ec7de5edd109981a12fa9d223b7b6e68a3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9619d5ec7de5edd109981a12fa9d223b7b6e68a3",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9060596124519149296",
"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/8895166061111944576/+/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": "1575305610169525",
"url": "https://ci.chromium.org/b/8895166061111944576",
"utcnow_ts": "1575305610402798"
}
}
[16:53:30.419] Task URL: https://ci.chromium.org/b/8895166061111944576
[16:53:30.419] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:2:0) after 1m0s
[16:54:30.437] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:2:0)
[16:54:30.437] Timer tick, asking Buildbucket for the build status
[16:54:31.089] Build 8895166061111944576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:54:31.089] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:3:0) after 1m0s
[16:54:38.348] Received PubSub notification, asking Buildbucket for the build status
[16:54:38.498] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:31.088] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:3:0)
[16:55:31.088] Timer tick, asking Buildbucket for the build status
[16:55:31.649] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:31.649] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:5:0) after 1m0s
[16:56:32.186] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:5:0)
[16:56:32.186] Timer tick, asking Buildbucket for the build status
[16:56:32.643] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:32.643] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:6:0) after 1m0s
[16:57:33.006] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:6:0)
[16:57:33.006] Timer tick, asking Buildbucket for the build status
[16:57:33.165] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:33.165] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:7:0) after 1m0s
[16:58:33.320] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:7:0)
[16:58:33.320] Timer tick, asking Buildbucket for the build status
[16:58:33.476] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:33.476] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:8:0) after 1m0s
[16:59:33.621] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:8:0)
[16:59:33.621] Timer tick, asking Buildbucket for the build status
[16:59:34.202] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:34.202] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:9:0) after 1m0s
[17:00:34.282] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:9:0)
[17:00:34.282] Timer tick, asking Buildbucket for the build status
[17:00:34.419] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:34.419] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:10:0) after 1m0s
[17:01:34.630] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:10:0)
[17:01:34.630] Timer tick, asking Buildbucket for the build status
[17:01:34.796] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:34.796] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:11:0) after 1m0s
[17:02:34.866] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:11:0)
[17:02:34.866] Timer tick, asking Buildbucket for the build status
[17:02:35.043] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:35.043] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:12:0) after 1m0s
[17:03:35.295] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:12:0)
[17:03:35.295] Timer tick, asking Buildbucket for the build status
[17:03:35.719] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:35.719] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:13:0) after 1m0s
[17:04:35.979] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:13:0)
[17:04:35.979] Timer tick, asking Buildbucket for the build status
[17:04:36.227] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:36.227] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:14:0) after 1m0s
[17:05:36.265] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:14:0)
[17:05:36.265] Timer tick, asking Buildbucket for the build status
[17:05:36.513] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:36.514] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:15:0) after 1m0s
[17:06:36.601] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:15:0)
[17:06:36.601] Timer tick, asking Buildbucket for the build status
[17:06:36.937] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:36.937] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:16:0) after 1m0s
[17:07:37.210] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:16:0)
[17:07:37.210] Timer tick, asking Buildbucket for the build status
[17:07:37.431] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:37.431] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:17:0) after 1m0s
[17:08:37.669] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:17:0)
[17:08:37.669] Timer tick, asking Buildbucket for the build status
[17:08:37.871] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:37.871] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:18:0) after 1m0s
[17:09:38.013] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:18:0)
[17:09:38.013] Timer tick, asking Buildbucket for the build status
[17:09:38.416] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:38.416] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:19:0) after 1m0s
[17:10:38.864] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:19:0)
[17:10:38.864] Timer tick, asking Buildbucket for the build status
[17:10:39.077] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:39.077] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:20:0) after 1m0s
[17:11:39.210] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:20:0)
[17:11:39.210] Timer tick, asking Buildbucket for the build status
[17:11:39.691] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:39.691] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:21:0) after 1m0s
[17:12:39.777] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:21:0)
[17:12:39.777] Timer tick, asking Buildbucket for the build status
[17:12:40.042] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:40.042] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:22:0) after 1m0s
[17:13:40.155] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:22:0)
[17:13:40.155] Timer tick, asking Buildbucket for the build status
[17:13:40.328] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:40.328] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:23:0) after 1m0s
[17:14:41.205] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:23:0)
[17:14:41.205] Timer tick, asking Buildbucket for the build status
[17:14:41.614] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:41.615] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:24:0) after 1m0s
[17:15:41.786] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:24:0)
[17:15:41.786] Timer tick, asking Buildbucket for the build status
[17:15:41.898] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:41.898] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:25:0) after 1m0s
[17:16:42.227] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:25:0)
[17:16:42.227] Timer tick, asking Buildbucket for the build status
[17:16:42.691] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:42.691] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:26:0) after 1m0s
[17:17:42.888] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:26:0)
[17:17:42.888] Timer tick, asking Buildbucket for the build status
[17:17:43.095] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:43.095] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:27:0) after 1m0s
[17:18:43.241] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:27:0)
[17:18:43.241] Timer tick, asking Buildbucket for the build status
[17:18:43.372] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:43.372] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:28:0) after 1m0s
[17:19:43.381] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:28:0)
[17:19:43.381] Timer tick, asking Buildbucket for the build status
[17:19:45.215] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:45.215] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:29:0) after 1m0s
[17:20:45.412] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:29:0)
[17:20:45.412] Timer tick, asking Buildbucket for the build status
[17:20:45.585] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:45.585] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:30:0) after 1m0s
[17:21:45.838] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:30:0)
[17:21:45.839] Timer tick, asking Buildbucket for the build status
[17:21:45.996] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:46.001] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:31:0) after 1m0s
[17:22:46.036] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:31:0)
[17:22:46.036] Timer tick, asking Buildbucket for the build status
[17:22:46.294] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:46.294] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:32:0) after 1m0s
[17:23:46.606] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:32:0)
[17:23:46.606] Timer tick, asking Buildbucket for the build status
[17:23:46.754] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:46.754] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:33:0) after 1m0s
[17:24:46.778] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:33:0)
[17:24:46.778] Timer tick, asking Buildbucket for the build status
[17:24:46.946] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:46.946] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:34:0) after 1m0s
[17:25:47.127] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:34:0)
[17:25:47.127] Timer tick, asking Buildbucket for the build status
[17:25:47.615] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:47.615] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:35:0) after 1m0s
[17:26:47.680] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:35:0)
[17:26:47.680] Timer tick, asking Buildbucket for the build status
[17:26:48.608] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:48.608] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:36:0) after 1m0s
[17:27:48.693] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:36:0)
[17:27:48.693] Timer tick, asking Buildbucket for the build status
[17:27:48.906] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:48.906] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:37:0) after 1m0s
[17:28:49.137] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:37:0)
[17:28:49.137] Timer tick, asking Buildbucket for the build status
[17:28:49.875] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:49.875] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:38:0) after 1m0s
[17:29:49.931] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:38:0)
[17:29:49.931] Timer tick, asking Buildbucket for the build status
[17:29:50.171] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:50.171] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:39:0) after 1m0s
[17:30:50.236] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:39:0)
[17:30:50.236] Timer tick, asking Buildbucket for the build status
[17:30:50.485] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:50.485] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:40:0) after 1m0s
[17:31:50.577] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:40:0)
[17:31:50.577] Timer tick, asking Buildbucket for the build status
[17:31:50.702] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:50.702] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:41:0) after 1m0s
[17:32:50.746] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:41:0)
[17:32:50.746] Timer tick, asking Buildbucket for the build status
[17:32:51.461] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:51.461] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:42:0) after 1m0s
[17:33:51.513] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:42:0)
[17:33:51.513] Timer tick, asking Buildbucket for the build status
[17:33:51.859] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:51.859] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:43:0) after 1m0s
[17:34:51.904] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:43:0)
[17:34:51.904] Timer tick, asking Buildbucket for the build status
[17:34:52.699] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:52.699] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:44:0) after 1m0s
[17:35:52.744] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:44:0)
[17:35:52.744] Timer tick, asking Buildbucket for the build status
[17:35:52.975] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:52.975] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:45:0) after 1m0s
[17:36:53.048] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:45:0)
[17:36:53.048] Timer tick, asking Buildbucket for the build status
[17:36:53.749] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:53.749] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:46:0) after 1m0s
[17:37:53.831] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:46:0)
[17:37:53.831] Timer tick, asking Buildbucket for the build status
[17:37:54.098] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:54.098] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:47:0) after 1m0s
[17:38:54.207] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:47:0)
[17:38:54.208] Timer tick, asking Buildbucket for the build status
[17:38:54.775] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:54.775] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:48:0) after 1m0s
[17:39:54.998] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:48:0)
[17:39:54.998] Timer tick, asking Buildbucket for the build status
[17:39:55.188] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:55.188] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:49:0) after 1m0s
[17:40:55.274] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:49:0)
[17:40:55.274] Timer tick, asking Buildbucket for the build status
[17:40:55.488] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:55.488] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:50:0) after 1m0s
[17:41:55.545] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:50:0)
[17:41:55.545] Timer tick, asking Buildbucket for the build status
[17:41:55.710] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:55.710] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:51:0) after 1m0s
[17:42:55.778] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:51:0)
[17:42:55.778] Timer tick, asking Buildbucket for the build status
[17:42:55.970] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:55.970] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:52:0) after 1m0s
[17:43:56.316] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:52:0)
[17:43:56.316] Timer tick, asking Buildbucket for the build status
[17:43:57.177] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:57.177] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:53:0) after 1m0s
[17:44:57.368] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:53:0)
[17:44:57.368] Timer tick, asking Buildbucket for the build status
[17:44:57.539] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:57.539] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:54:0) after 1m0s
[17:45:57.803] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:54:0)
[17:45:57.803] Timer tick, asking Buildbucket for the build status
[17:45:58.079] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:58.079] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:55:0) after 1m0s
[17:46:58.165] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:55:0)
[17:46:58.165] Timer tick, asking Buildbucket for the build status
[17:46:58.627] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:58.627] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:56:0) after 1m0s
[17:47:58.974] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:56:0)
[17:47:58.974] Timer tick, asking Buildbucket for the build status
[17:47:59.064] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:59.064] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:57:0) after 1m0s
[17:48:59.390] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:57:0)
[17:48:59.390] Timer tick, asking Buildbucket for the build status
[17:48:59.987] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:59.987] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:58:0) after 1m0s
[17:50:00.276] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:58:0)
[17:50:00.276] Timer tick, asking Buildbucket for the build status
[17:50:00.588] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:00.588] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:59:0) after 1m0s
[17:51:00.694] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:59:0)
[17:51:00.694] Timer tick, asking Buildbucket for the build status
[17:51:01.074] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:01.074] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:60:0) after 1m0s
[17:52:01.128] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:60:0)
[17:52:01.128] Timer tick, asking Buildbucket for the build status
[17:52:01.301] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:01.301] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:61:0) after 1m0s
[17:53:01.513] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:61:0)
[17:53:01.513] Timer tick, asking Buildbucket for the build status
[17:53:01.628] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:01.628] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:62:0) after 1m0s
[17:54:01.727] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:62:0)
[17:54:01.727] Timer tick, asking Buildbucket for the build status
[17:54:02.292] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:02.292] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:63:0) after 1m0s
[17:55:02.455] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:63:0)
[17:55:02.455] Timer tick, asking Buildbucket for the build status
[17:55:02.665] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:02.665] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:64:0) after 1m0s
[17:56:02.726] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:64:0)
[17:56:02.726] Timer tick, asking Buildbucket for the build status
[17:56:03.276] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:03.276] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:65:0) after 1m0s
[17:57:03.321] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:65:0)
[17:57:03.321] Timer tick, asking Buildbucket for the build status
[17:57:03.476] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:03.476] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:66:0) after 1m0s
[17:58:03.541] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:66:0)
[17:58:03.541] Timer tick, asking Buildbucket for the build status
[17:58:04.006] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:04.006] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:67:0) after 1m0s
[17:59:04.069] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:67:0)
[17:59:04.069] Timer tick, asking Buildbucket for the build status
[17:59:04.343] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:04.343] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:68:0) after 1m0s
[18:00:04.461] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:68:0)
[18:00:04.461] Timer tick, asking Buildbucket for the build status
[18:00:04.826] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:04.826] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:69:0) after 1m0s
[18:01:04.822] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:69:0)
[18:01:04.822] Timer tick, asking Buildbucket for the build status
[18:01:05.364] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:05.364] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:70:0) after 1m0s
[18:02:05.423] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:70:0)
[18:02:05.423] Timer tick, asking Buildbucket for the build status
[18:02:05.607] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:05.607] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:71:0) after 1m0s
[18:03:05.652] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:71:0)
[18:03:05.653] Timer tick, asking Buildbucket for the build status
[18:03:05.845] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:05.845] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:72:0) after 1m0s
[18:04:05.879] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:72:0)
[18:04:05.879] Timer tick, asking Buildbucket for the build status
[18:04:06.066] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:06.066] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:73:0) after 1m0s
[18:05:06.088] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:73:0)
[18:05:06.088] Timer tick, asking Buildbucket for the build status
[18:05:06.857] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:06.857] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:74:0) after 1m0s
[18:06:06.924] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:74:0)
[18:06:06.924] Timer tick, asking Buildbucket for the build status
[18:06:08.610] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:08.610] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:75:0) after 1m0s
[18:07:08.718] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:75:0)
[18:07:08.719] Timer tick, asking Buildbucket for the build status
[18:07:08.962] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:08.962] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:76:0) after 1m0s
[18:08:09.066] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:76:0)
[18:08:09.066] Timer tick, asking Buildbucket for the build status
[18:08:09.862] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:09.862] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:77:0) after 1m0s
[18:09:09.959] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:77:0)
[18:09:09.959] Timer tick, asking Buildbucket for the build status
[18:09:10.329] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:10.329] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:78:0) after 1m0s
[18:10:10.450] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:78:0)
[18:10:10.450] Timer tick, asking Buildbucket for the build status
[18:10:10.708] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:10.708] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:79:0) after 1m0s
[18:11:10.753] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:79:0)
[18:11:10.753] Timer tick, asking Buildbucket for the build status
[18:11:11.072] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:11.072] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:80:0) after 1m0s
[18:12:11.145] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:80:0)
[18:12:11.145] Timer tick, asking Buildbucket for the build status
[18:12:11.417] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:11.417] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:81:0) after 1m0s
[18:13:11.513] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:81:0)
[18:13:11.513] Timer tick, asking Buildbucket for the build status
[18:13:12.405] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:12.405] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:82:0) after 1m0s
[18:14:12.506] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:82:0)
[18:14:12.506] Timer tick, asking Buildbucket for the build status
[18:14:13.117] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:13.117] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:83:0) after 1m0s
[18:15:13.140] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:83:0)
[18:15:13.140] Timer tick, asking Buildbucket for the build status
[18:15:13.947] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:13.947] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:84:0) after 1m0s
[18:16:14.062] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:84:0)
[18:16:14.062] Timer tick, asking Buildbucket for the build status
[18:16:14.748] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:14.748] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:85:0) after 1m0s
[18:17:14.814] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:85:0)
[18:17:14.814] Timer tick, asking Buildbucket for the build status
[18:17:15.370] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:15.370] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:86:0) after 1m0s
[18:18:15.367] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:86:0)
[18:18:15.367] Timer tick, asking Buildbucket for the build status
[18:18:15.715] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:15.715] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:87:0) after 1m0s
[18:19:15.704] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:87:0)
[18:19:15.704] Timer tick, asking Buildbucket for the build status
[18:19:18.562] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:18.562] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:88:0) after 1m0s
[18:20:18.661] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:88:0)
[18:20:18.661] Timer tick, asking Buildbucket for the build status
[18:20:18.858] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:18.858] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:89:0) after 1m0s
[18:21:18.957] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:89:0)
[18:21:18.957] Timer tick, asking Buildbucket for the build status
[18:21:19.722] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:19.722] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:90:0) after 1m0s
[18:22:19.766] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:90:0)
[18:22:19.766] Timer tick, asking Buildbucket for the build status
[18:22:19.921] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:19.921] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:91:0) after 1m0s
[18:23:19.983] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:91:0)
[18:23:19.983] Timer tick, asking Buildbucket for the build status
[18:23:20.377] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:20.377] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:92:0) after 1m0s
[18:24:20.398] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:92:0)
[18:24:20.398] Timer tick, asking Buildbucket for the build status
[18:24:20.956] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:20.956] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:93:0) after 1m0s
[18:25:20.977] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:93:0)
[18:25:20.977] Timer tick, asking Buildbucket for the build status
[18:25:21.442] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:21.442] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:94:0) after 1m0s
[18:26:21.482] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:94:0)
[18:26:21.482] Timer tick, asking Buildbucket for the build status
[18:26:21.626] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:21.626] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:95:0) after 1m0s
[18:27:21.697] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:95:0)
[18:27:21.697] Timer tick, asking Buildbucket for the build status
[18:27:21.816] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:21.816] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:96:0) after 1m0s
[18:28:21.888] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:96:0)
[18:28:21.888] Timer tick, asking Buildbucket for the build status
[18:28:22.128] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:22.129] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:97:0) after 1m0s
[18:29:22.246] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:97:0)
[18:29:22.246] Timer tick, asking Buildbucket for the build status
[18:29:22.449] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:22.449] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:98:0) after 1m0s
[18:30:22.626] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:98:0)
[18:30:22.626] Timer tick, asking Buildbucket for the build status
[18:30:23.955] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:23.955] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:99:0) after 1m0s
[18:31:24.091] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:99:0)
[18:31:24.091] Timer tick, asking Buildbucket for the build status
[18:31:24.242] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:24.242] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:100:0) after 1m0s
[18:32:24.369] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:100:0)
[18:32:24.369] Timer tick, asking Buildbucket for the build status
[18:32:24.637] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:24.637] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:101:0) after 1m0s
[18:33:24.694] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:101:0)
[18:33:24.694] Timer tick, asking Buildbucket for the build status
[18:33:24.936] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:24.936] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:102:0) after 1m0s
[18:34:25.035] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:102:0)
[18:34:25.035] Timer tick, asking Buildbucket for the build status
[18:34:27.314] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:27.314] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:103:0) after 1m0s
[18:35:27.380] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:103:0)
[18:35:27.380] Timer tick, asking Buildbucket for the build status
[18:35:27.878] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:27.878] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:104:0) after 1m0s
[18:36:27.927] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:104:0)
[18:36:27.927] Timer tick, asking Buildbucket for the build status
[18:36:28.082] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:28.082] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:105:0) after 1m0s
[18:37:28.179] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:105:0)
[18:37:28.179] Timer tick, asking Buildbucket for the build status
[18:37:29.196] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:29.196] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:106:0) after 1m0s
[18:38:29.388] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:106:0)
[18:38:29.388] Timer tick, asking Buildbucket for the build status
[18:38:29.777] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:29.777] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:107:0) after 1m0s
[18:39:29.821] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:107:0)
[18:39:29.821] Timer tick, asking Buildbucket for the build status
[18:39:30.062] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:30.062] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:108:0) after 1m0s
[18:40:30.102] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:108:0)
[18:40:30.102] Timer tick, asking Buildbucket for the build status
[18:40:31.868] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:31.868] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:109:0) after 1m0s
[18:41:32.012] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:109:0)
[18:41:32.012] Timer tick, asking Buildbucket for the build status
[18:41:33.409] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:33.409] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:110:0) after 1m0s
[18:42:33.718] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:110:0)
[18:42:33.718] Timer tick, asking Buildbucket for the build status
[18:42:33.862] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:33.862] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:111:0) after 1m0s
[18:43:34.147] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:111:0)
[18:43:34.147] Timer tick, asking Buildbucket for the build status
[18:43:34.342] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:34.342] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:112:0) after 1m0s
[18:44:35.309] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:112:0)
[18:44:35.309] Timer tick, asking Buildbucket for the build status
[18:44:36.317] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:36.317] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:113:0) after 1m0s
[18:45:36.475] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:113:0)
[18:45:36.475] Timer tick, asking Buildbucket for the build status
[18:45:37.145] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:37.145] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:114:0) after 1m0s
[18:46:37.344] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:114:0)
[18:46:37.344] Timer tick, asking Buildbucket for the build status
[18:46:37.580] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:37.580] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:115:0) after 1m0s
[18:47:37.655] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:115:0)
[18:47:37.655] Timer tick, asking Buildbucket for the build status
[18:47:38.421] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:38.421] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:116:0) after 1m0s
[18:48:38.493] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:116:0)
[18:48:38.493] Timer tick, asking Buildbucket for the build status
[18:48:39.100] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:39.100] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:117:0) after 1m0s
[18:49:39.243] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:117:0)
[18:49:39.243] Timer tick, asking Buildbucket for the build status
[18:49:40.184] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:40.184] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:118:0) after 1m0s
[18:50:40.271] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:118:0)
[18:50:40.271] Timer tick, asking Buildbucket for the build status
[18:50:41.729] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:41.729] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:119:0) after 1m0s
[18:51:41.923] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:119:0)
[18:51:41.923] Timer tick, asking Buildbucket for the build status
[18:51:42.055] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:42.055] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:120:0) after 1m0s
[18:52:42.453] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:120:0)
[18:52:42.453] Timer tick, asking Buildbucket for the build status
[18:52:42.629] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:42.629] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:121:0) after 1m0s
[18:53:42.696] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:121:0)
[18:53:42.696] Timer tick, asking Buildbucket for the build status
[18:53:43.564] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:43.564] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:122:0) after 1m0s
[18:54:43.681] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:122:0)
[18:54:43.681] Timer tick, asking Buildbucket for the build status
[18:54:44.615] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:44.615] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:123:0) after 1m0s
[18:55:44.756] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:123:0)
[18:55:44.756] Timer tick, asking Buildbucket for the build status
[18:55:45.296] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:45.296] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:124:0) after 1m0s
[18:56:45.544] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:124:0)
[18:56:45.544] Timer tick, asking Buildbucket for the build status
[18:56:48.610] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:48.610] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:125:0) after 1m0s
[18:57:48.702] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:125:0)
[18:57:48.702] Timer tick, asking Buildbucket for the build status
[18:57:49.195] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:49.195] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:126:0) after 1m0s
[18:58:49.250] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:126:0)
[18:58:49.250] Timer tick, asking Buildbucket for the build status
[18:58:49.458] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:49.458] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:127:0) after 1m0s
[18:59:49.556] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:127:0)
[18:59:49.556] Timer tick, asking Buildbucket for the build status
[18:59:49.722] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:49.722] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:128:0) after 1m0s
[19:00:49.968] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:128:0)
[19:00:49.968] Timer tick, asking Buildbucket for the build status
[19:00:50.833] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:50.833] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:129:0) after 1m0s
[19:01:50.917] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:129:0)
[19:01:50.918] Timer tick, asking Buildbucket for the build status
[19:01:51.672] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:51.672] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:130:0) after 1m0s
[19:02:51.764] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:130:0)
[19:02:51.764] Timer tick, asking Buildbucket for the build status
[19:02:52.284] Build 8895166061111944576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:52.284] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9060596124519149296:131:0) after 1m0s
[19:03:43.740] Received PubSub notification, asking Buildbucket for the build status
[19:03:43.869] Build 8895166061111944576: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[19:03:43.869] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1575313422971602",
"created_by": "project:chromium",
"created_ts": "1575305609556664",
"failure_reason": "BUILD_FAILURE",
"id": "8895166061111944576",
"parameters_json": "{\"builder_name\": \"Deterministic Linux (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"9619d5ec7de5edd109981a12fa9d223b7b6e68a3\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"Step('compare_build_artifacts') (retcode: 1)\"}, \"properties\": {\"$build/goma\": {\"jobs\": 500.0, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-32-0-rkx0\", \"branch\": \"refs/heads/master\", \"buildername\": \"Deterministic Linux (dbg)\", \"buildnumber\": 13096, \"got_angle_revision\": \"8784a9284f653b9bc6fa54d75217e4f2a190376c\", \"got_dawn_revision\": \"969df2b3a21c126b2e0015ce2392c135f32a6a08\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"9619d5ec7de5edd109981a12fa9d223b7b6e68a3\", \"got_revision_cp\": \"refs/heads/master@{#720524}\", \"got_swarming_client_revision\": \"885b3febcc170a60f25795304e60927b77d1e92d\", \"got_swiftshader_revision\": \"b64fbfec4dcd3f81c776198641b1fc71ac8d64a4\", \"got_v8_revision\": \"6d4b96eb7059cc04fcacff9dbd080de50ac74aeb\", \"got_v8_revision_cp\": \"refs/heads/8.0.422@{#1}\", \"got_webrtc_revision\": \"89313451d8e2338845716b0ab8760ed0552580f9\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#29962}\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"9619d5ec7de5edd109981a12fa9d223b7b6e68a3\"}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_940b8dd4638ac05ec7d44839e381a2baf02720b3eac518443ac04ffe6b1809c0_v2\", \"builder_d3d2610b5230a0e8818d022113159013c6345fba0843daf9ae715f6a2f1da36d_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-32-0-rkx0\"], \"image\": [\"chrome-xenial-19112404-1fe53dcaaf2\"], \"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\": [\"4679-6acb960\"], \"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": "1575305677630953",
"status": "COMPLETED",
"status_changed_ts": "1575305677994119",
"tags": [
"build_address:luci.chromium.ci/Deterministic Linux (dbg)/13096",
"builder:Deterministic Linux (dbg)",
"buildset:commit/git/9619d5ec7de5edd109981a12fa9d223b7b6e68a3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9619d5ec7de5edd109981a12fa9d223b7b6e68a3",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9060596124519149296",
"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/8895166061111944576/+/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:48e0598e86d35810",
"user_agent:luci-scheduler"
],
"updated_ts": "1575313423068296",
"url": "https://ci.chromium.org/b/8895166061111944576",
"utcnow_ts": "1575313423858764"
}
}
[19:03:43.869] Invocation finished in 2h10m16.945423226s with status FAILED