[11:45:42.943] New invocation is queued and will start shortly
[11:45:44.321] Starting the invocation (attempt 1)
[11:45:44.372] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:45:44.372] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:45:44.372] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9045032807216811760",
"parameters_json": "{\"builder_name\":\"Deterministic Linux\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"5dfa8d5f706580fe983e138952553cd6af11ee8b\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Deterministic Linux",
"scheduler_invocation_id:9045032807216811760",
"scheduler_job_id:chromium/Deterministic Linux",
"user_agent:luci-scheduler",
"buildset:commit/git/5dfa8d5f706580fe983e138952553cd6af11ee8b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5dfa8d5f706580fe983e138952553cd6af11ee8b",
"gitiles_ref:refs/heads/master"
]
}
[11:45:46.382] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1590147945485490",
"id": "8879602743873221728",
"parameters_json": "{\"builder_name\": \"Deterministic Linux\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5dfa8d5f706580fe983e138952553cd6af11ee8b\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1590147946154650",
"tags": [
"build_address:luci.chromium.ci/Deterministic Linux/27975",
"builder:Deterministic Linux",
"buildset:commit/git/5dfa8d5f706580fe983e138952553cd6af11ee8b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5dfa8d5f706580fe983e138952553cd6af11ee8b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9045032807216811760",
"scheduler_job_id:chromium/Deterministic Linux",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8879602743873221728/+/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": "1590147946154802",
"url": "https://ci.chromium.org/b/8879602743873221728",
"utcnow_ts": "1590147946356408"
}
}
[11:45:46.382] Task URL: https://ci.chromium.org/b/8879602743873221728
[11:45:46.382] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:2:0) after 1m0s
[11:46:31.946] Received PubSub notification, asking Buildbucket for the build status
[11:46:32.437] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:46.431] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:2:0)
[11:46:46.431] Timer tick, asking Buildbucket for the build status
[11:46:46.747] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:46.747] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:4:0) after 1m0s
[11:47:46.768] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:4:0)
[11:47:46.768] Timer tick, asking Buildbucket for the build status
[11:47:46.991] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:46.991] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:5:0) after 1m0s
[11:48:47.067] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:5:0)
[11:48:47.067] Timer tick, asking Buildbucket for the build status
[11:48:47.680] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:47.680] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:6:0) after 1m0s
[11:49:47.756] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:6:0)
[11:49:47.756] Timer tick, asking Buildbucket for the build status
[11:49:47.940] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:47.940] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:7:0) after 1m0s
[11:50:47.966] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:7:0)
[11:50:47.966] Timer tick, asking Buildbucket for the build status
[11:50:49.710] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:49.710] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:8:0) after 1m0s
[11:51:49.833] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:8:0)
[11:51:49.833] Timer tick, asking Buildbucket for the build status
[11:51:50.029] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:50.029] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:9:0) after 1m0s
[11:52:50.152] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:9:0)
[11:52:50.152] Timer tick, asking Buildbucket for the build status
[11:52:50.324] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:50.324] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:10:0) after 1m0s
[11:53:50.401] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:10:0)
[11:53:50.401] Timer tick, asking Buildbucket for the build status
[11:53:50.676] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:50.676] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:11:0) after 1m0s
[11:54:50.803] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:11:0)
[11:54:50.803] Timer tick, asking Buildbucket for the build status
[11:54:51.054] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:51.054] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:12:0) after 1m0s
[11:55:51.079] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:12:0)
[11:55:51.079] Timer tick, asking Buildbucket for the build status
[11:55:51.298] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:51.298] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:13:0) after 1m0s
[11:56:51.347] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:13:0)
[11:56:51.347] Timer tick, asking Buildbucket for the build status
[11:56:51.520] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:51.520] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:14:0) after 1m0s
[11:57:51.719] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:14:0)
[11:57:51.719] Timer tick, asking Buildbucket for the build status
[11:57:51.966] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:51.966] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:15:0) after 1m0s
[11:58:51.988] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:15:0)
[11:58:51.988] Timer tick, asking Buildbucket for the build status
[11:58:52.212] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:52.212] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:16:0) after 1m0s
[11:59:52.207] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:16:0)
[11:59:52.207] Timer tick, asking Buildbucket for the build status
[11:59:52.456] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:52.456] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:17:0) after 1m0s
[12:00:52.438] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:17:0)
[12:00:52.438] Timer tick, asking Buildbucket for the build status
[12:00:52.625] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:52.625] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:18:0) after 1m0s
[12:01:52.736] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:18:0)
[12:01:52.736] Timer tick, asking Buildbucket for the build status
[12:01:52.837] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:52.837] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:19:0) after 1m0s
[12:02:52.921] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:19:0)
[12:02:52.921] Timer tick, asking Buildbucket for the build status
[12:02:53.068] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:53.068] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:20:0) after 1m0s
[12:03:53.094] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:20:0)
[12:03:53.094] Timer tick, asking Buildbucket for the build status
[12:03:53.288] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:53.288] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:21:0) after 1m0s
[12:04:53.315] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:21:0)
[12:04:53.315] Timer tick, asking Buildbucket for the build status
[12:04:53.524] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:53.524] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:22:0) after 1m0s
[12:05:53.565] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:22:0)
[12:05:53.565] Timer tick, asking Buildbucket for the build status
[12:05:53.739] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:53.739] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:23:0) after 1m0s
[12:06:53.761] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:23:0)
[12:06:53.761] Timer tick, asking Buildbucket for the build status
[12:06:54.054] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:54.054] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:24:0) after 1m0s
[12:07:54.121] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:24:0)
[12:07:54.121] Timer tick, asking Buildbucket for the build status
[12:07:54.238] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:54.238] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:25:0) after 1m0s
[12:08:54.258] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:25:0)
[12:08:54.258] Timer tick, asking Buildbucket for the build status
[12:08:54.494] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:54.494] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:26:0) after 1m0s
[12:09:54.521] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:26:0)
[12:09:54.521] Timer tick, asking Buildbucket for the build status
[12:09:54.718] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:54.718] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:27:0) after 1m0s
[12:10:54.795] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:27:0)
[12:10:54.795] Timer tick, asking Buildbucket for the build status
[12:10:54.969] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:54.969] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:28:0) after 1m0s
[12:11:55.038] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:28:0)
[12:11:55.038] Timer tick, asking Buildbucket for the build status
[12:11:55.612] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:55.612] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:29:0) after 1m0s
[12:12:55.652] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:29:0)
[12:12:55.652] Timer tick, asking Buildbucket for the build status
[12:12:55.959] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:55.959] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:30:0) after 1m0s
[12:13:56.042] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:30:0)
[12:13:56.042] Timer tick, asking Buildbucket for the build status
[12:13:56.255] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:56.255] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:31:0) after 1m0s
[12:14:56.294] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:31:0)
[12:14:56.294] Timer tick, asking Buildbucket for the build status
[12:14:56.450] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:56.450] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:32:0) after 1m0s
[12:15:56.497] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:32:0)
[12:15:56.497] Timer tick, asking Buildbucket for the build status
[12:15:56.982] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:56.982] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:33:0) after 1m0s
[12:16:57.082] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:33:0)
[12:16:57.082] Timer tick, asking Buildbucket for the build status
[12:16:57.609] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:57.609] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:34:0) after 1m0s
[12:17:57.645] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:34:0)
[12:17:57.646] Timer tick, asking Buildbucket for the build status
[12:17:57.831] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:57.831] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:35:0) after 1m0s
[12:18:57.854] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:35:0)
[12:18:57.854] Timer tick, asking Buildbucket for the build status
[12:18:58.074] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:58.074] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:36:0) after 1m0s
[12:19:58.146] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:36:0)
[12:19:58.146] Timer tick, asking Buildbucket for the build status
[12:19:58.331] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:58.331] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:37:0) after 1m0s
[12:20:58.357] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:37:0)
[12:20:58.357] Timer tick, asking Buildbucket for the build status
[12:20:58.577] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:58.577] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:38:0) after 1m0s
[12:21:58.608] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:38:0)
[12:21:58.608] Timer tick, asking Buildbucket for the build status
[12:21:58.837] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:58.837] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:39:0) after 1m0s
[12:22:58.859] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:39:0)
[12:22:58.859] Timer tick, asking Buildbucket for the build status
[12:22:59.843] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:59.843] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:40:0) after 1m0s
[12:23:59.875] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:40:0)
[12:23:59.875] Timer tick, asking Buildbucket for the build status
[12:24:00.457] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:00.457] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:41:0) after 1m0s
[12:25:00.489] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:41:0)
[12:25:00.489] Timer tick, asking Buildbucket for the build status
[12:25:00.778] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:00.778] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:42:0) after 1m0s
[12:26:01.023] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:42:0)
[12:26:01.023] Timer tick, asking Buildbucket for the build status
[12:26:01.165] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:01.165] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:43:0) after 1m0s
[12:27:01.676] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:43:0)
[12:27:01.676] Timer tick, asking Buildbucket for the build status
[12:27:01.837] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:01.837] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:44:0) after 1m0s
[12:28:01.933] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:44:0)
[12:28:01.933] Timer tick, asking Buildbucket for the build status
[12:28:02.344] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:02.344] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:45:0) after 1m0s
[12:29:02.454] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:45:0)
[12:29:02.454] Timer tick, asking Buildbucket for the build status
[12:29:02.745] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:02.745] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:46:0) after 1m0s
[12:30:02.767] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:46:0)
[12:30:02.767] Timer tick, asking Buildbucket for the build status
[12:30:03.456] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:03.456] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:47:0) after 1m0s
[12:31:03.465] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:47:0)
[12:31:03.465] Timer tick, asking Buildbucket for the build status
[12:31:03.893] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:03.893] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:48:0) after 1m0s
[12:32:03.916] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:48:0)
[12:32:03.916] Timer tick, asking Buildbucket for the build status
[12:32:04.156] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:04.156] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:49:0) after 1m0s
[12:33:04.205] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:49:0)
[12:33:04.205] Timer tick, asking Buildbucket for the build status
[12:33:04.387] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:04.387] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:50:0) after 1m0s
[12:34:04.421] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:50:0)
[12:34:04.421] Timer tick, asking Buildbucket for the build status
[12:34:04.648] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:04.648] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:51:0) after 1m0s
[12:35:04.694] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:51:0)
[12:35:04.694] Timer tick, asking Buildbucket for the build status
[12:35:04.805] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:04.805] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:52:0) after 1m0s
[12:36:04.860] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:52:0)
[12:36:04.860] Timer tick, asking Buildbucket for the build status
[12:36:05.105] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:05.106] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:53:0) after 1m0s
[12:37:05.186] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:53:0)
[12:37:05.186] Timer tick, asking Buildbucket for the build status
[12:37:05.322] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:05.322] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:54:0) after 1m0s
[12:38:05.409] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:54:0)
[12:38:05.409] Timer tick, asking Buildbucket for the build status
[12:38:05.605] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:05.605] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:55:0) after 1m0s
[12:39:05.637] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:55:0)
[12:39:05.637] Timer tick, asking Buildbucket for the build status
[12:39:05.760] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:05.760] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:56:0) after 1m0s
[12:40:05.834] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:56:0)
[12:40:05.834] Timer tick, asking Buildbucket for the build status
[12:40:05.986] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:05.986] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:57:0) after 1m0s
[12:41:06.008] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:57:0)
[12:41:06.008] Timer tick, asking Buildbucket for the build status
[12:41:06.302] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:06.302] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:58:0) after 1m0s
[12:42:06.381] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:58:0)
[12:42:06.381] Timer tick, asking Buildbucket for the build status
[12:42:06.707] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:06.707] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:59:0) after 1m0s
[12:43:06.747] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:59:0)
[12:43:06.747] Timer tick, asking Buildbucket for the build status
[12:43:06.893] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:06.893] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:60:0) after 1m0s
[12:44:06.936] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:60:0)
[12:44:06.936] Timer tick, asking Buildbucket for the build status
[12:44:07.075] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:07.075] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:61:0) after 1m0s
[12:45:07.179] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:61:0)
[12:45:07.179] Timer tick, asking Buildbucket for the build status
[12:45:07.567] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:07.567] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:62:0) after 1m0s
[12:46:07.605] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:62:0)
[12:46:07.605] Timer tick, asking Buildbucket for the build status
[12:46:07.879] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:07.879] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:63:0) after 1m0s
[12:47:07.858] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:63:0)
[12:47:07.858] Timer tick, asking Buildbucket for the build status
[12:47:07.995] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:07.995] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:64:0) after 1m0s
[12:48:08.051] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:64:0)
[12:48:08.051] Timer tick, asking Buildbucket for the build status
[12:48:08.434] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:08.435] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:65:0) after 1m0s
[12:49:08.474] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:65:0)
[12:49:08.474] Timer tick, asking Buildbucket for the build status
[12:49:08.590] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:08.590] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:66:0) after 1m0s
[12:50:08.617] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:66:0)
[12:50:08.617] Timer tick, asking Buildbucket for the build status
[12:50:08.715] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:08.715] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:67:0) after 1m0s
[12:51:08.738] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:67:0)
[12:51:08.738] Timer tick, asking Buildbucket for the build status
[12:51:08.854] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:08.854] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:68:0) after 1m0s
[12:52:08.896] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:68:0)
[12:52:08.896] Timer tick, asking Buildbucket for the build status
[12:52:09.085] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:09.085] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:69:0) after 1m0s
[12:53:09.129] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:69:0)
[12:53:09.129] Timer tick, asking Buildbucket for the build status
[12:53:09.256] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:09.256] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:70:0) after 1m0s
[12:54:09.276] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:70:0)
[12:54:09.276] Timer tick, asking Buildbucket for the build status
[12:54:09.418] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:09.418] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:71:0) after 1m0s
[12:55:09.445] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:71:0)
[12:55:09.445] Timer tick, asking Buildbucket for the build status
[12:55:09.660] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:09.660] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:72:0) after 1m0s
[12:56:09.714] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:72:0)
[12:56:09.714] Timer tick, asking Buildbucket for the build status
[12:56:09.806] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:09.806] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:73:0) after 1m0s
[12:57:09.831] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:73:0)
[12:57:09.831] Timer tick, asking Buildbucket for the build status
[12:57:09.944] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:09.944] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:74:0) after 1m0s
[12:58:09.979] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:74:0)
[12:58:09.979] Timer tick, asking Buildbucket for the build status
[12:58:10.193] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:10.193] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:75:0) after 1m0s
[12:59:10.171] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:75:0)
[12:59:10.171] Timer tick, asking Buildbucket for the build status
[12:59:10.346] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:10.346] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:76:0) after 1m0s
[13:00:10.372] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:76:0)
[13:00:10.372] Timer tick, asking Buildbucket for the build status
[13:00:11.787] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:11.787] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:77:0) after 1m0s
[13:01:11.828] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:77:0)
[13:01:11.828] Timer tick, asking Buildbucket for the build status
[13:01:11.932] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:11.932] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:78:0) after 1m0s
[13:02:11.962] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:78:0)
[13:02:11.962] Timer tick, asking Buildbucket for the build status
[13:02:12.776] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:12.776] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:79:0) after 1m0s
[13:03:12.850] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:79:0)
[13:03:12.850] Timer tick, asking Buildbucket for the build status
[13:03:12.955] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:12.955] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:80:0) after 1m0s
[13:04:13.032] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:80:0)
[13:04:13.032] Timer tick, asking Buildbucket for the build status
[13:04:13.362] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:13.362] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:81:0) after 1m0s
[13:05:13.400] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:81:0)
[13:05:13.400] Timer tick, asking Buildbucket for the build status
[13:05:13.538] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:13.538] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:82:0) after 1m0s
[13:06:13.619] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:82:0)
[13:06:13.620] Timer tick, asking Buildbucket for the build status
[13:06:13.793] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:13.793] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:83:0) after 1m0s
[13:07:13.978] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:83:0)
[13:07:13.978] Timer tick, asking Buildbucket for the build status
[13:07:14.147] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:14.147] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:84:0) after 1m0s
[13:08:14.289] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:84:0)
[13:08:14.289] Timer tick, asking Buildbucket for the build status
[13:08:14.397] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:14.397] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:85:0) after 1m0s
[13:09:14.640] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:85:0)
[13:09:14.640] Timer tick, asking Buildbucket for the build status
[13:09:14.772] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:14.772] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:86:0) after 1m0s
[13:10:14.939] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:86:0)
[13:10:14.939] Timer tick, asking Buildbucket for the build status
[13:10:15.113] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:15.113] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:87:0) after 1m0s
[13:11:15.290] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:87:0)
[13:11:15.290] Timer tick, asking Buildbucket for the build status
[13:11:15.431] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:15.431] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:88:0) after 1m0s
[13:12:15.562] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:88:0)
[13:12:15.562] Timer tick, asking Buildbucket for the build status
[13:12:15.746] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:15.746] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:89:0) after 1m0s
[13:13:15.775] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:89:0)
[13:13:15.775] Timer tick, asking Buildbucket for the build status
[13:13:16.062] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:16.062] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:90:0) after 1m0s
[13:14:16.143] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:90:0)
[13:14:16.143] Timer tick, asking Buildbucket for the build status
[13:14:16.375] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:16.375] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:91:0) after 1m0s
[13:15:16.402] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:91:0)
[13:15:16.402] Timer tick, asking Buildbucket for the build status
[13:15:16.544] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:16.546] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:92:0) after 1m0s
[13:16:16.595] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:92:0)
[13:16:16.595] Timer tick, asking Buildbucket for the build status
[13:16:16.693] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:16.693] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:93:0) after 1m0s
[13:17:17.003] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:93:0)
[13:17:17.003] Timer tick, asking Buildbucket for the build status
[13:17:17.197] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:17.197] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:94:0) after 1m0s
[13:18:17.344] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:94:0)
[13:18:17.344] Timer tick, asking Buildbucket for the build status
[13:18:17.595] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:17.595] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:95:0) after 1m0s
[13:19:17.698] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:95:0)
[13:19:17.698] Timer tick, asking Buildbucket for the build status
[13:19:17.847] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:17.847] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:96:0) after 1m0s
[13:20:17.981] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:96:0)
[13:20:17.981] Timer tick, asking Buildbucket for the build status
[13:20:18.181] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:18.181] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:97:0) after 1m0s
[13:21:18.502] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:97:0)
[13:21:18.502] Timer tick, asking Buildbucket for the build status
[13:21:18.650] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:18.650] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:98:0) after 1m0s
[13:22:18.809] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:98:0)
[13:22:18.809] Timer tick, asking Buildbucket for the build status
[13:22:18.916] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:18.916] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:99:0) after 1m0s
[13:23:19.181] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:99:0)
[13:23:19.181] Timer tick, asking Buildbucket for the build status
[13:23:19.337] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:19.337] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:100:0) after 1m0s
[13:24:19.361] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:100:0)
[13:24:19.361] Timer tick, asking Buildbucket for the build status
[13:24:19.683] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:19.683] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:101:0) after 1m0s
[13:25:19.714] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:101:0)
[13:25:19.714] Timer tick, asking Buildbucket for the build status
[13:25:19.837] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:19.837] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:102:0) after 1m0s
[13:26:19.988] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:102:0)
[13:26:19.988] Timer tick, asking Buildbucket for the build status
[13:26:20.133] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:20.133] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:103:0) after 1m0s
[13:27:20.199] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:103:0)
[13:27:20.199] Timer tick, asking Buildbucket for the build status
[13:27:20.385] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:20.385] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:104:0) after 1m0s
[13:28:20.418] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:104:0)
[13:28:20.418] Timer tick, asking Buildbucket for the build status
[13:28:20.627] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:20.627] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:105:0) after 1m0s
[13:29:20.676] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:105:0)
[13:29:20.676] Timer tick, asking Buildbucket for the build status
[13:29:20.819] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:20.820] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:106:0) after 1m0s
[13:30:20.887] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:106:0)
[13:30:20.887] Timer tick, asking Buildbucket for the build status
[13:30:22.192] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:22.192] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:107:0) after 1m0s
[13:31:22.290] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:107:0)
[13:31:22.290] Timer tick, asking Buildbucket for the build status
[13:31:22.412] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:22.412] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:108:0) after 1m0s
[13:32:22.432] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:108:0)
[13:32:22.432] Timer tick, asking Buildbucket for the build status
[13:32:22.530] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:22.530] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:109:0) after 1m0s
[13:33:22.597] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:109:0)
[13:33:22.597] Timer tick, asking Buildbucket for the build status
[13:33:22.933] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:22.933] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:110:0) after 1m0s
[13:34:22.969] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:110:0)
[13:34:22.969] Timer tick, asking Buildbucket for the build status
[13:34:23.096] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:23.096] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:111:0) after 1m0s
[13:35:23.135] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:111:0)
[13:35:23.135] Timer tick, asking Buildbucket for the build status
[13:35:23.412] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:23.412] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:112:0) after 1m0s
[13:36:23.441] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:112:0)
[13:36:23.441] Timer tick, asking Buildbucket for the build status
[13:36:23.600] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:23.600] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:113:0) after 1m0s
[13:37:23.629] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:113:0)
[13:37:23.629] Timer tick, asking Buildbucket for the build status
[13:37:23.796] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:23.796] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:114:0) after 1m0s
[13:38:23.967] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:114:0)
[13:38:23.967] Timer tick, asking Buildbucket for the build status
[13:38:24.081] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:24.081] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:115:0) after 1m0s
[13:39:24.334] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:115:0)
[13:39:24.334] Timer tick, asking Buildbucket for the build status
[13:39:24.669] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:24.669] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:116:0) after 1m0s
[13:40:24.878] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:116:0)
[13:40:24.878] Timer tick, asking Buildbucket for the build status
[13:40:25.306] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:25.306] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:117:0) after 1m0s
[13:41:25.352] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:117:0)
[13:41:25.352] Timer tick, asking Buildbucket for the build status
[13:41:25.498] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:25.498] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:118:0) after 1m0s
[13:42:25.528] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:118:0)
[13:42:25.528] Timer tick, asking Buildbucket for the build status
[13:42:25.689] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:25.689] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:119:0) after 1m0s
[13:43:25.715] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:119:0)
[13:43:25.715] Timer tick, asking Buildbucket for the build status
[13:43:25.861] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:25.861] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:120:0) after 1m0s
[13:44:25.895] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:120:0)
[13:44:25.895] Timer tick, asking Buildbucket for the build status
[13:44:26.156] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:26.156] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:121:0) after 1m0s
[13:45:26.217] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:121:0)
[13:45:26.217] Timer tick, asking Buildbucket for the build status
[13:45:27.451] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:27.451] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:122:0) after 1m0s
[13:46:27.480] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:122:0)
[13:46:27.480] Timer tick, asking Buildbucket for the build status
[13:46:27.609] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:27.609] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:123:0) after 1m0s
[13:47:27.659] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:123:0)
[13:47:27.659] Timer tick, asking Buildbucket for the build status
[13:47:27.916] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:27.916] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:124:0) after 1m0s
[13:48:27.939] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:124:0)
[13:48:27.939] Timer tick, asking Buildbucket for the build status
[13:48:28.090] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:28.090] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:125:0) after 1m0s
[13:49:28.238] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:125:0)
[13:49:28.238] Timer tick, asking Buildbucket for the build status
[13:49:28.557] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:28.557] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:126:0) after 1m0s
[13:50:28.581] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:126:0)
[13:50:28.581] Timer tick, asking Buildbucket for the build status
[13:50:31.897] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:31.898] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:127:0) after 1m0s
[13:51:31.993] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:127:0)
[13:51:31.993] Timer tick, asking Buildbucket for the build status
[13:51:32.104] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:32.104] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:128:0) after 1m0s
[13:52:32.309] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:128:0)
[13:52:32.309] Timer tick, asking Buildbucket for the build status
[13:52:32.458] Build 8879602743873221728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:32.458] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:9045032807216811760:129:0) after 1m0s
[13:53:04.991] Received PubSub notification, asking Buildbucket for the build status
[13:53:05.210] Build 8879602743873221728: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[13:53:05.210] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1590155582548794",
"created_by": "project:chromium",
"created_ts": "1590147945485490",
"id": "8879602743873221728",
"parameters_json": "{\"builder_name\": \"Deterministic Linux\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5dfa8d5f706580fe983e138952553cd6af11ee8b\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"jobs\": 500.0, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-east1-d-8-103-juxr\", \"branch\": \"refs/heads/master\", \"buildername\": \"Deterministic Linux\", \"buildnumber\": 27975, \"got_angle_revision\": \"0ee5ef3583d84fa53831b618db900692247e428c\", \"got_dawn_revision\": \"65ee6497d620110546181c1dfbd0cc3064f9f7f9\", \"got_nacl_revision\": \"87616f7ce2ea8383efd3731960c15f4ba5852d93\", \"got_revision\": \"5dfa8d5f706580fe983e138952553cd6af11ee8b\", \"got_revision_cp\": \"refs/heads/master@{#771328}\", \"got_swarming_client_revision\": \"7e8636295f4763a5f5aace1e700b17a4af3c81b7\", \"got_swiftshader_revision\": \"cc5cda0f997d8280712920f2749bbdfe7a0f4dc3\", \"got_v8_revision\": \"414d114aaaad4f0cac357eb2705a71e3ca7bf898\", \"got_v8_revision_cp\": \"refs/heads/8.5.34@{#1}\", \"got_webrtc_revision\": \"3cc1a6509bf6143f9d52d76a7648a9c8d3bdccea\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#31336}\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5dfa8d5f706580fe983e138952553cd6af11ee8b\"}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_78902ab2d70d1593a97658cf8910c0e117cf7950e257117b8c551c6fe1cfb403_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-east1-d-8-103-juxr\"], \"image\": [\"chrome-xenial-20041200-f56df5dd7ad\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5100-d2625c1\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1590147990844947",
"status": "COMPLETED",
"status_changed_ts": "1590155584477366",
"tags": [
"build_address:luci.chromium.ci/Deterministic Linux/27975",
"builder:Deterministic Linux",
"buildset:commit/git/5dfa8d5f706580fe983e138952553cd6af11ee8b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5dfa8d5f706580fe983e138952553cd6af11ee8b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9045032807216811760",
"scheduler_job_id:chromium/Deterministic Linux",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8879602743873221728/+/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:4c5505a2ff73ef10",
"user_agent:luci-scheduler"
],
"updated_ts": "1590155584482768",
"url": "https://ci.chromium.org/b/8879602743873221728",
"utcnow_ts": "1590155585187964"
}
}
[13:53:05.210] Invocation finished in 2h7m22.296704187s with status SUCCEEDED