[23:45:08.749] New invocation is queued and will start shortly
[23:45:09.828] Starting the invocation (attempt 1)
[23:45:09.877] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:45:09.877] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:45:09.877] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9017627260719603216",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"846b2b9adba36d514928621228ddf991353000da\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9017627260719603216",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/846b2b9adba36d514928621228ddf991353000da",
"gitiles_ref:refs/heads/master"
]
}
[23:45:11.643] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1616283910022270",
"id": "8852197198722895632",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"846b2b9adba36d514928621228ddf991353000da\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1616283911390372",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/18095",
"builder:Windows deterministic",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/846b2b9adba36d514928621228ddf991353000da",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9017627260719603216",
"scheduler_job_id:chromium/Windows deterministic",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8852197198722895632/+/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": "1616283911390562",
"url": "https://ci.chromium.org/b/8852197198722895632",
"utcnow_ts": "1616283911598634"
}
}
[23:45:11.643] Task URL: https://ci.chromium.org/b/8852197198722895632
[23:45:11.643] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:2:0) after 1m0s
[23:46:11.662] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:2:0)
[23:46:11.688] Build status: SCHEDULED
[23:46:11.688] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:3:0) after 2m55s
[23:49:06.906] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:3:0)
[23:49:06.932] Build status: SCHEDULED
[23:49:06.932] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:4:0) after 2m46s
[23:51:53.042] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:4:0)
[23:51:53.042] Timer tick, asking Buildbucket for the build status
[23:51:53.117] Build 8852197198722895632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:51:53.117] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:5:0) after 1m0s
[23:52:53.242] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:5:0)
[23:52:53.242] Timer tick, asking Buildbucket for the build status
[23:52:53.301] Build 8852197198722895632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:52:53.301] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:6:0) after 1m0s
[23:53:03.299] Received PubSub notification, asking Buildbucket for the build status
[23:53:03.393] Build 8852197198722895632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:53.584] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:6:0)
[23:53:53.617] Build status: STARTED
[23:53:53.617] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:8:0) after 5m5s
[23:58:58.708] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:8:0)
[23:58:58.708] Timer tick, asking Buildbucket for the build status
[23:58:58.785] Build 8852197198722895632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:58.785] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:9:0) after 1m0s
[23:59:58.914] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:9:0)
[23:59:58.914] Timer tick, asking Buildbucket for the build status
[23:59:58.977] Build 8852197198722895632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:58.977] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:10:0) after 1m0s
[00:00:59.011] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:10:0)
[00:00:59.011] Timer tick, asking Buildbucket for the build status
[00:00:59.122] Build 8852197198722895632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:59.122] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:11:0) after 1m0s
[00:01:59.242] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:11:0)
[00:01:59.267] Build status: STARTED
[00:01:59.267] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:12:0) after 8m39s
[00:10:38.285] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:12:0)
[00:10:38.311] Build status: STARTED
[00:10:38.311] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:13:0) after 3m34s
[00:14:12.406] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:13:0)
[00:14:12.435] Build status: STARTED
[00:14:12.435] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:14:0) after 8m43s
[00:22:55.451] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:14:0)
[00:22:55.451] Timer tick, asking Buildbucket for the build status
[00:22:55.566] Build 8852197198722895632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:55.566] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:15:0) after 1m0s
[00:23:55.564] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:15:0)
[00:23:55.564] Timer tick, asking Buildbucket for the build status
[00:23:55.698] Build 8852197198722895632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:55.698] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:16:0) after 1m0s
[00:24:55.715] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:16:0)
[00:24:55.715] Timer tick, asking Buildbucket for the build status
[00:24:56.081] Build 8852197198722895632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:56.081] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:17:0) after 1m0s
[00:25:56.102] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:17:0)
[00:25:56.125] Build status: STARTED
[00:25:56.125] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:18:0) after 2m57s
[00:28:53.206] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:18:0)
[00:28:53.247] Build status: STARTED
[00:28:53.247] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:19:0) after 9m1s
[00:37:54.250] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:19:0)
[00:37:54.303] Build status: STARTED
[00:37:54.303] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:20:0) after 5m59s
[00:43:53.322] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:20:0)
[00:43:53.322] Timer tick, asking Buildbucket for the build status
[00:43:53.503] Build 8852197198722895632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:53.503] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:21:0) after 1m0s
[00:44:53.558] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:21:0)
[00:44:53.584] Build status: STARTED
[00:44:53.584] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:22:0) after 3m8s
[00:48:01.616] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:22:0)
[00:48:01.648] Build status: STARTED
[00:48:01.648] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:23:0) after 8m31s
[00:56:32.698] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:23:0)
[00:56:32.698] Timer tick, asking Buildbucket for the build status
[00:56:32.781] Build 8852197198722895632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:32.781] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:24:0) after 1m0s
[00:57:33.004] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:24:0)
[00:57:33.004] Timer tick, asking Buildbucket for the build status
[00:57:33.242] Build 8852197198722895632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:33.242] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:25:0) after 1m0s
[00:58:33.263] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:25:0)
[00:58:33.291] Build status: STARTED
[00:58:33.291] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:26:0) after 1m5s
[00:59:38.381] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:26:0)
[00:59:38.414] Build status: STARTED
[00:59:38.414] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:27:0) after 1m31s
[01:01:09.683] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:27:0)
[01:01:09.710] Build status: STARTED
[01:01:09.710] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:28:0) after 1m18s
[01:02:27.830] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:28:0)
[01:02:27.830] Timer tick, asking Buildbucket for the build status
[01:02:28.289] Build 8852197198722895632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:28.289] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:29:0) after 1m0s
[01:03:28.344] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:29:0)
[01:03:28.367] Build status: STARTED
[01:03:28.367] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:30:0) after 6m30s
[01:09:58.389] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:30:0)
[01:09:58.389] Timer tick, asking Buildbucket for the build status
[01:09:58.560] Build 8852197198722895632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:58.560] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:31:0) after 1m0s
[01:10:58.586] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:31:0)
[01:10:58.612] Build status: STARTED
[01:10:58.612] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:32:0) after 6m52s
[01:17:50.704] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:32:0)
[01:17:50.731] Build status: STARTED
[01:17:50.731] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:33:0) after 8m31s
[01:26:21.751] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:33:0)
[01:26:21.788] Build status: STARTED
[01:26:21.788] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:34:0) after 3m33s
[01:29:54.853] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:34:0)
[01:29:54.853] Timer tick, asking Buildbucket for the build status
[01:29:55.016] Build 8852197198722895632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:55.016] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:35:0) after 1m0s
[01:30:55.034] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:35:0)
[01:30:55.065] Build status: STARTED
[01:30:55.065] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:36:0) after 5m56s
[01:36:51.085] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:36:0)
[01:36:51.085] Timer tick, asking Buildbucket for the build status
[01:36:51.177] Build 8852197198722895632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:51.177] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:37:0) after 1m0s
[01:37:51.199] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:37:0)
[01:37:51.199] Timer tick, asking Buildbucket for the build status
[01:37:51.270] Build 8852197198722895632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:51.270] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:38:0) after 1m0s
[01:38:51.303] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:38:0)
[01:38:51.337] Build status: STARTED
[01:38:51.337] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:39:0) after 1m29s
[01:40:20.352] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:39:0)
[01:40:20.352] Timer tick, asking Buildbucket for the build status
[01:40:20.509] Build 8852197198722895632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:20.509] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:40:0) after 1m0s
[01:41:20.532] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:40:0)
[01:41:20.532] Timer tick, asking Buildbucket for the build status
[01:41:20.751] Build 8852197198722895632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:20.751] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:41:0) after 1m0s
[01:42:20.769] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:41:0)
[01:42:20.769] Timer tick, asking Buildbucket for the build status
[01:42:20.838] Build 8852197198722895632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:20.838] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:42:0) after 1m0s
[01:43:20.929] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:42:0)
[01:43:20.929] Timer tick, asking Buildbucket for the build status
[01:43:21.005] Build 8852197198722895632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:21.005] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:43:0) after 1m0s
[01:44:21.071] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:43:0)
[01:44:21.071] Timer tick, asking Buildbucket for the build status
[01:44:21.273] Build 8852197198722895632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:21.273] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:44:0) after 1m0s
[01:45:21.353] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:44:0)
[01:45:21.380] Build status: STARTED
[01:45:21.380] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:45:0) after 1m6s
[01:46:27.399] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:45:0)
[01:46:27.431] Build status: STARTED
[01:46:27.431] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:46:0) after 8m41s
[01:55:08.453] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:46:0)
[01:55:08.477] Build status: STARTED
[01:55:08.477] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:47:0) after 5m31s
[02:00:39.498] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:47:0)
[02:00:39.523] Build status: STARTED
[02:00:39.523] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:48:0) after 2m14s
[02:02:53.542] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:48:0)
[02:02:53.569] Build status: STARTED
[02:02:53.569] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:49:0) after 9m59s
[02:12:52.589] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:49:0)
[02:12:52.618] Build status: STARTED
[02:12:52.618] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:50:0) after 6m8s
[02:19:00.643] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:50:0)
[02:19:00.643] Timer tick, asking Buildbucket for the build status
[02:19:00.719] Build 8852197198722895632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:00.719] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:51:0) after 1m0s
[02:20:00.929] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:51:0)
[02:20:00.929] Timer tick, asking Buildbucket for the build status
[02:20:01.106] Build 8852197198722895632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:01.106] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:52:0) after 1m0s
[02:21:01.122] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:52:0)
[02:21:01.151] Build status: STARTED
[02:21:01.151] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:53:0) after 9m24s
[02:30:25.301] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:53:0)
[02:30:25.327] Build status: STARTED
[02:30:25.327] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:54:0) after 8m8s
[02:38:33.349] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:54:0)
[02:38:33.375] Build status: STARTED
[02:38:33.375] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:55:0) after 7m26s
[02:45:59.675] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:55:0)
[02:45:59.700] Build status: STARTED
[02:45:59.700] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:56:0) after 1m13s
[02:47:12.718] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:56:0)
[02:47:12.718] Timer tick, asking Buildbucket for the build status
[02:47:12.830] Build 8852197198722895632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:12.830] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:57:0) after 1m0s
[02:48:12.950] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:57:0)
[02:48:12.950] Timer tick, asking Buildbucket for the build status
[02:48:13.012] Build 8852197198722895632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:13.012] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:58:0) after 1m0s
[02:49:13.069] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:58:0)
[02:49:13.069] Timer tick, asking Buildbucket for the build status
[02:49:13.140] Build 8852197198722895632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:13.140] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:59:0) after 1m0s
[02:50:13.228] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:59:0)
[02:50:13.252] Build status: STARTED
[02:50:13.252] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:60:0) after 1m12s
[02:51:25.272] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:60:0)
[02:51:25.296] Build status: STARTED
[02:51:25.296] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:61:0) after 6m25s
[02:57:50.281] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:61:0)
[02:57:50.307] Build status: STARTED
[02:57:50.307] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:62:0) after 1m18s
[02:59:08.350] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:62:0)
[02:59:08.350] Timer tick, asking Buildbucket for the build status
[02:59:08.603] Build 8852197198722895632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:08.603] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:63:0) after 1m0s
[03:00:08.711] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:63:0)
[03:00:08.740] Build status: STARTED
[03:00:08.740] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:64:0) after 6m15s
[03:06:23.816] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:64:0)
[03:06:23.846] Build status: STARTED
[03:06:23.846] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:65:0) after 4m56s
[03:11:19.946] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:65:0)
[03:11:19.946] Timer tick, asking Buildbucket for the build status
[03:11:20.026] Build 8852197198722895632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:20.026] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:66:0) after 1m0s
[03:12:20.046] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:66:0)
[03:12:20.046] Timer tick, asking Buildbucket for the build status
[03:12:20.110] Build 8852197198722895632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:20.110] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:67:0) after 1m0s
[03:13:20.157] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:67:0)
[03:13:20.157] Timer tick, asking Buildbucket for the build status
[03:13:20.232] Build 8852197198722895632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:20.232] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:68:0) after 1m0s
[03:14:20.307] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:68:0)
[03:14:20.332] Build status: STARTED
[03:14:20.332] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:69:0) after 7m40s
[03:22:00.397] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:69:0)
[03:22:00.421] Build status: STARTED
[03:22:00.421] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:70:0) after 6m47s
[03:28:47.449] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:70:0)
[03:28:47.483] Build status: STARTED
[03:28:47.483] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:71:0) after 9m51s
[03:38:38.683] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:71:0)
[03:38:38.710] Build status: STARTED
[03:38:38.710] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:72:0) after 3m0s
[03:41:38.845] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:72:0)
[03:41:38.873] Build status: STARTED
[03:41:38.873] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:73:0) after 1m18s
[03:42:56.908] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:73:0)
[03:42:56.908] Timer tick, asking Buildbucket for the build status
[03:42:57.018] Build 8852197198722895632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:57.018] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:74:0) after 1m0s
[03:43:57.037] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:74:0)
[03:43:57.037] Timer tick, asking Buildbucket for the build status
[03:43:57.151] Build 8852197198722895632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:57.151] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:75:0) after 1m0s
[03:44:57.347] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:75:0)
[03:44:57.373] Build status: STARTED
[03:44:57.373] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:76:0) after 1m28s
[03:46:25.418] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:76:0)
[03:46:25.418] Timer tick, asking Buildbucket for the build status
[03:46:25.501] Build 8852197198722895632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:25.501] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9017627260719603216:77:0) after 1m0s
[03:47:23.967] Received PubSub notification, asking Buildbucket for the build status
[03:47:23.992] Build:
{
"id": "8852197198722895632",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Windows deterministic"
},
"number": 18095,
"createdBy": "project:chromium",
"createTime": "2021-03-20T23:45:10.022270Z",
"startTime": "2021-03-20T23:53:02.659497Z",
"endTime": "2021-03-21T03:47:23.073966Z",
"updateTime": "2021-03-21T03:47:23.328588Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "846b2b9adba36d514928621228ddf991353000da",
"ref": "refs/heads/master"
}
},
"canary": true
}
[03:47:23.992] Invocation finished in 4h2m15.258113636s with status SUCCEEDED