[19:28:48.324] New invocation is queued and will start shortly
[19:28:49.468] Starting the invocation (attempt 1)
[19:28:49.505] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:28:49.505] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:28:49.505] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9084232158569744368",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"781e496e366ce508c2e16fa1c2e0c3ed110b8e2e\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9084232158569744368",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/781e496e366ce508c2e16fa1c2e0c3ed110b8e2e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/781e496e366ce508c2e16fa1c2e0c3ed110b8e2e",
"gitiles_ref:refs/heads/master"
]
}
[19:28:50.474] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "project:chromium",
"created_ts": "1552764529675663",
"id": "8918802096490161040",
"lease_expiration_ts": "1552883330176004",
"lease_key": "354811086",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"781e496e366ce508c2e16fa1c2e0c3ed110b8e2e\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1552764530290769",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/12454",
"builder:Windows deterministic",
"buildset:commit/git/781e496e366ce508c2e16fa1c2e0c3ed110b8e2e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/781e496e366ce508c2e16fa1c2e0c3ed110b8e2e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9084232158569744368",
"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/8918802096490161040/+/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:43a0cbf556a3aa10",
"user_agent:luci-scheduler"
],
"updated_ts": "1552764530290889",
"url": "https://ci.chromium.org/b/8918802096490161040",
"utcnow_ts": "1552764530465798"
}
}
[19:28:50.474] Task URL: https://ci.chromium.org/b/8918802096490161040
[19:28:50.474] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:2:0) after 1m0s
[19:28:52.189] Received PubSub notification, asking Buildbucket for the build status
[19:28:52.971] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:50.525] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:2:0)
[19:29:50.525] Timer tick, asking Buildbucket for the build status
[19:29:51.123] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:51.123] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:4:0) after 1m0s
[19:30:51.136] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:4:0)
[19:30:51.136] Timer tick, asking Buildbucket for the build status
[19:30:51.380] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:51.380] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:5:0) after 1m0s
[19:31:51.378] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:5:0)
[19:31:51.378] Timer tick, asking Buildbucket for the build status
[19:31:51.486] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:51.486] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:6:0) after 1m0s
[19:32:51.504] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:6:0)
[19:32:51.504] Timer tick, asking Buildbucket for the build status
[19:32:51.598] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:51.598] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:7:0) after 1m0s
[19:33:51.700] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:7:0)
[19:33:51.700] Timer tick, asking Buildbucket for the build status
[19:33:51.830] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:51.830] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:8:0) after 1m0s
[19:34:51.806] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:8:0)
[19:34:51.806] Timer tick, asking Buildbucket for the build status
[19:34:52.760] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:52.760] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:9:0) after 1m0s
[19:35:52.788] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:9:0)
[19:35:52.788] Timer tick, asking Buildbucket for the build status
[19:35:53.462] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:53.462] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:10:0) after 1m0s
[19:36:53.480] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:10:0)
[19:36:53.480] Timer tick, asking Buildbucket for the build status
[19:36:53.878] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:53.878] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:11:0) after 1m0s
[19:37:53.896] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:11:0)
[19:37:53.896] Timer tick, asking Buildbucket for the build status
[19:37:54.473] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:54.473] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:12:0) after 1m0s
[19:38:54.513] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:12:0)
[19:38:54.513] Timer tick, asking Buildbucket for the build status
[19:38:54.647] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:54.647] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:13:0) after 1m0s
[19:39:54.659] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:13:0)
[19:39:54.659] Timer tick, asking Buildbucket for the build status
[19:39:54.787] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:54.787] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:14:0) after 1m0s
[19:40:54.791] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:14:0)
[19:40:54.791] Timer tick, asking Buildbucket for the build status
[19:40:54.975] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:54.975] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:15:0) after 1m0s
[19:41:54.982] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:15:0)
[19:41:54.982] Timer tick, asking Buildbucket for the build status
[19:41:55.316] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:55.316] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:16:0) after 1m0s
[19:42:55.333] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:16:0)
[19:42:55.333] Timer tick, asking Buildbucket for the build status
[19:42:55.500] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:55.500] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:17:0) after 1m0s
[19:43:55.518] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:17:0)
[19:43:55.518] Timer tick, asking Buildbucket for the build status
[19:43:55.756] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:55.756] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:18:0) after 1m0s
[19:44:55.738] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:18:0)
[19:44:55.738] Timer tick, asking Buildbucket for the build status
[19:44:55.846] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:55.846] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:19:0) after 1m0s
[19:45:55.863] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:19:0)
[19:45:55.863] Timer tick, asking Buildbucket for the build status
[19:45:56.331] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:56.331] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:20:0) after 1m0s
[19:46:56.349] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:20:0)
[19:46:56.349] Timer tick, asking Buildbucket for the build status
[19:46:56.486] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:56.486] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:21:0) after 1m0s
[19:47:56.498] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:21:0)
[19:47:56.498] Timer tick, asking Buildbucket for the build status
[19:47:57.008] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:57.008] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:22:0) after 1m0s
[19:48:57.029] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:22:0)
[19:48:57.029] Timer tick, asking Buildbucket for the build status
[19:48:57.501] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:57.501] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:23:0) after 1m0s
[19:49:57.520] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:23:0)
[19:49:57.520] Timer tick, asking Buildbucket for the build status
[19:49:58.917] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:58.917] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:24:0) after 1m0s
[19:50:58.935] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:24:0)
[19:50:58.935] Timer tick, asking Buildbucket for the build status
[19:50:59.402] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:59.402] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:25:0) after 1m0s
[19:51:59.420] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:25:0)
[19:51:59.420] Timer tick, asking Buildbucket for the build status
[19:52:00.425] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:00.425] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:26:0) after 1m0s
[19:53:00.447] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:26:0)
[19:53:00.447] Timer tick, asking Buildbucket for the build status
[19:53:01.395] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:01.395] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:27:0) after 1m0s
[19:54:01.429] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:27:0)
[19:54:01.429] Timer tick, asking Buildbucket for the build status
[19:54:01.915] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:01.915] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:28:0) after 1m0s
[19:55:01.938] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:28:0)
[19:55:01.938] Timer tick, asking Buildbucket for the build status
[19:55:02.568] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:02.568] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:29:0) after 1m0s
[19:56:02.579] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:29:0)
[19:56:02.579] Timer tick, asking Buildbucket for the build status
[19:56:02.700] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:02.701] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:30:0) after 1m0s
[19:57:02.684] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:30:0)
[19:57:02.684] Timer tick, asking Buildbucket for the build status
[19:57:02.818] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:02.818] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:31:0) after 1m0s
[19:58:02.842] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:31:0)
[19:58:02.842] Timer tick, asking Buildbucket for the build status
[19:58:02.957] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:02.957] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:32:0) after 1m0s
[19:59:02.965] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:32:0)
[19:59:02.965] Timer tick, asking Buildbucket for the build status
[19:59:03.053] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:03.053] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:33:0) after 1m0s
[20:00:03.106] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:33:0)
[20:00:03.106] Timer tick, asking Buildbucket for the build status
[20:00:04.092] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:04.092] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:34:0) after 1m0s
[20:01:04.113] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:34:0)
[20:01:04.113] Timer tick, asking Buildbucket for the build status
[20:01:04.373] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:04.373] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:35:0) after 1m0s
[20:02:04.392] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:35:0)
[20:02:04.392] Timer tick, asking Buildbucket for the build status
[20:02:04.512] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:04.512] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:36:0) after 1m0s
[20:03:04.569] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:36:0)
[20:03:04.569] Timer tick, asking Buildbucket for the build status
[20:03:04.692] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:04.692] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:37:0) after 1m0s
[20:04:04.716] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:37:0)
[20:04:04.716] Timer tick, asking Buildbucket for the build status
[20:04:04.823] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:04.823] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:38:0) after 1m0s
[20:05:04.841] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:38:0)
[20:05:04.841] Timer tick, asking Buildbucket for the build status
[20:05:04.962] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:04.962] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:39:0) after 1m0s
[20:06:04.981] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:39:0)
[20:06:04.981] Timer tick, asking Buildbucket for the build status
[20:06:05.106] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:05.106] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:40:0) after 1m0s
[20:07:05.109] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:40:0)
[20:07:05.109] Timer tick, asking Buildbucket for the build status
[20:07:05.229] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:05.229] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:41:0) after 1m0s
[20:08:05.236] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:41:0)
[20:08:05.236] Timer tick, asking Buildbucket for the build status
[20:08:05.364] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:05.364] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:42:0) after 1m0s
[20:09:05.357] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:42:0)
[20:09:05.357] Timer tick, asking Buildbucket for the build status
[20:09:06.057] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:06.057] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:43:0) after 1m0s
[20:10:06.106] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:43:0)
[20:10:06.106] Timer tick, asking Buildbucket for the build status
[20:10:06.237] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:06.237] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:44:0) after 1m0s
[20:11:06.250] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:44:0)
[20:11:06.250] Timer tick, asking Buildbucket for the build status
[20:11:06.431] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:06.431] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:45:0) after 1m0s
[20:12:06.437] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:45:0)
[20:12:06.437] Timer tick, asking Buildbucket for the build status
[20:12:06.613] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:06.613] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:46:0) after 1m0s
[20:13:06.633] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:46:0)
[20:13:06.633] Timer tick, asking Buildbucket for the build status
[20:13:06.977] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:06.977] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:47:0) after 1m0s
[20:14:06.957] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:47:0)
[20:14:06.957] Timer tick, asking Buildbucket for the build status
[20:14:07.075] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:07.075] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:48:0) after 1m0s
[20:15:07.068] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:48:0)
[20:15:07.068] Timer tick, asking Buildbucket for the build status
[20:15:07.298] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:07.298] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:49:0) after 1m0s
[20:16:07.284] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:49:0)
[20:16:07.284] Timer tick, asking Buildbucket for the build status
[20:16:07.502] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:07.502] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:50:0) after 1m0s
[20:17:07.544] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:50:0)
[20:17:07.544] Timer tick, asking Buildbucket for the build status
[20:17:07.647] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:07.647] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:51:0) after 1m0s
[20:18:07.672] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:51:0)
[20:18:07.672] Timer tick, asking Buildbucket for the build status
[20:18:07.901] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:07.901] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:52:0) after 1m0s
[20:19:07.920] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:52:0)
[20:19:07.920] Timer tick, asking Buildbucket for the build status
[20:19:08.352] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:08.352] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:53:0) after 1m0s
[20:20:08.340] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:53:0)
[20:20:08.340] Timer tick, asking Buildbucket for the build status
[20:20:08.471] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:08.471] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:54:0) after 1m0s
[20:21:08.463] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:54:0)
[20:21:08.463] Timer tick, asking Buildbucket for the build status
[20:21:08.616] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:08.616] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:55:0) after 1m0s
[20:22:08.599] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:55:0)
[20:22:08.599] Timer tick, asking Buildbucket for the build status
[20:22:08.885] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:08.885] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:56:0) after 1m0s
[20:23:08.906] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:56:0)
[20:23:08.906] Timer tick, asking Buildbucket for the build status
[20:23:09.573] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:09.573] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:57:0) after 1m0s
[20:24:09.597] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:57:0)
[20:24:09.597] Timer tick, asking Buildbucket for the build status
[20:24:10.591] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:10.591] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:58:0) after 1m0s
[20:25:10.584] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:58:0)
[20:25:10.584] Timer tick, asking Buildbucket for the build status
[20:25:10.681] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:10.681] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:59:0) after 1m0s
[20:26:10.702] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:59:0)
[20:26:10.702] Timer tick, asking Buildbucket for the build status
[20:26:10.872] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:10.872] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:60:0) after 1m0s
[20:27:10.856] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:60:0)
[20:27:10.856] Timer tick, asking Buildbucket for the build status
[20:27:10.966] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:10.966] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:61:0) after 1m0s
[20:28:10.957] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:61:0)
[20:28:10.957] Timer tick, asking Buildbucket for the build status
[20:28:11.359] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:11.359] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:62:0) after 1m0s
[20:29:11.381] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:62:0)
[20:29:11.381] Timer tick, asking Buildbucket for the build status
[20:29:11.525] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:11.526] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:63:0) after 1m0s
[20:30:11.514] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:63:0)
[20:30:11.514] Timer tick, asking Buildbucket for the build status
[20:30:11.685] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:11.685] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:64:0) after 1m0s
[20:31:11.702] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:64:0)
[20:31:11.702] Timer tick, asking Buildbucket for the build status
[20:31:12.117] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:12.117] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:65:0) after 1m0s
[20:32:12.119] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:65:0)
[20:32:12.120] Timer tick, asking Buildbucket for the build status
[20:32:12.219] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:12.219] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:66:0) after 1m0s
[20:33:12.243] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:66:0)
[20:33:12.243] Timer tick, asking Buildbucket for the build status
[20:33:12.352] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:12.352] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:67:0) after 1m0s
[20:34:12.372] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:67:0)
[20:34:12.372] Timer tick, asking Buildbucket for the build status
[20:34:12.480] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:12.480] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:68:0) after 1m0s
[20:35:12.469] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:68:0)
[20:35:12.469] Timer tick, asking Buildbucket for the build status
[20:35:12.571] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:12.571] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:69:0) after 1m0s
[20:36:12.591] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:69:0)
[20:36:12.591] Timer tick, asking Buildbucket for the build status
[20:36:13.395] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:13.395] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:70:0) after 1m0s
[20:37:13.413] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:70:0)
[20:37:13.413] Timer tick, asking Buildbucket for the build status
[20:37:13.549] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:13.549] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:71:0) after 1m0s
[20:38:13.583] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:71:0)
[20:38:13.583] Timer tick, asking Buildbucket for the build status
[20:38:13.684] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:13.684] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:72:0) after 1m0s
[20:39:13.673] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:72:0)
[20:39:13.673] Timer tick, asking Buildbucket for the build status
[20:39:14.673] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:14.673] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:73:0) after 1m0s
[20:40:14.693] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:73:0)
[20:40:14.693] Timer tick, asking Buildbucket for the build status
[20:40:15.285] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:15.285] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:74:0) after 1m0s
[20:41:15.269] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:74:0)
[20:41:15.269] Timer tick, asking Buildbucket for the build status
[20:41:15.504] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:15.504] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:75:0) after 1m0s
[20:42:15.497] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:75:0)
[20:42:15.497] Timer tick, asking Buildbucket for the build status
[20:42:15.599] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:15.599] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:76:0) after 1m0s
[20:43:15.595] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:76:0)
[20:43:15.595] Timer tick, asking Buildbucket for the build status
[20:43:16.072] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:16.072] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:77:0) after 1m0s
[20:44:16.089] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:77:0)
[20:44:16.089] Timer tick, asking Buildbucket for the build status
[20:44:16.337] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:16.337] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:78:0) after 1m0s
[20:45:16.345] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:78:0)
[20:45:16.345] Timer tick, asking Buildbucket for the build status
[20:45:16.465] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:16.465] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:79:0) after 1m0s
[20:46:16.512] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:79:0)
[20:46:16.512] Timer tick, asking Buildbucket for the build status
[20:46:16.612] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:16.612] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:80:0) after 1m0s
[20:47:16.637] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:80:0)
[20:47:16.637] Timer tick, asking Buildbucket for the build status
[20:47:16.882] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:16.882] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:81:0) after 1m0s
[20:48:16.904] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:81:0)
[20:48:16.904] Timer tick, asking Buildbucket for the build status
[20:48:16.994] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:16.994] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:82:0) after 1m0s
[20:49:16.984] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:82:0)
[20:49:16.984] Timer tick, asking Buildbucket for the build status
[20:49:17.860] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:17.860] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:83:0) after 1m0s
[20:50:17.882] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:83:0)
[20:50:17.882] Timer tick, asking Buildbucket for the build status
[20:50:17.990] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:17.990] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:84:0) after 1m0s
[20:51:18.000] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:84:0)
[20:51:18.000] Timer tick, asking Buildbucket for the build status
[20:51:18.106] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:18.106] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:85:0) after 1m0s
[20:52:18.092] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:85:0)
[20:52:18.092] Timer tick, asking Buildbucket for the build status
[20:52:18.572] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:18.572] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:86:0) after 1m0s
[20:53:18.573] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:86:0)
[20:53:18.573] Timer tick, asking Buildbucket for the build status
[20:53:18.722] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:18.722] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:87:0) after 1m0s
[20:54:18.693] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:87:0)
[20:54:18.693] Timer tick, asking Buildbucket for the build status
[20:54:19.561] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:19.561] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:88:0) after 1m0s
[20:55:19.581] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:88:0)
[20:55:19.581] Timer tick, asking Buildbucket for the build status
[20:55:19.738] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:19.738] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:89:0) after 1m0s
[20:56:19.719] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:89:0)
[20:56:19.719] Timer tick, asking Buildbucket for the build status
[20:56:19.843] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:19.843] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:90:0) after 1m0s
[20:57:19.852] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:90:0)
[20:57:19.852] Timer tick, asking Buildbucket for the build status
[20:57:20.330] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:20.330] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:91:0) after 1m0s
[20:58:20.325] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:91:0)
[20:58:20.325] Timer tick, asking Buildbucket for the build status
[20:58:21.188] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:21.188] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:92:0) after 1m0s
[20:59:21.196] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:92:0)
[20:59:21.196] Timer tick, asking Buildbucket for the build status
[20:59:21.305] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:21.305] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:93:0) after 1m0s
[21:00:21.301] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:93:0)
[21:00:21.301] Timer tick, asking Buildbucket for the build status
[21:00:22.183] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:22.183] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:94:0) after 1m0s
[21:01:22.208] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:94:0)
[21:01:22.208] Timer tick, asking Buildbucket for the build status
[21:01:22.372] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:22.372] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:95:0) after 1m0s
[21:02:22.357] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:95:0)
[21:02:22.357] Timer tick, asking Buildbucket for the build status
[21:02:22.447] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:22.447] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:96:0) after 1m0s
[21:03:22.443] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:96:0)
[21:03:22.443] Timer tick, asking Buildbucket for the build status
[21:03:23.559] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:23.559] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:97:0) after 1m0s
[21:04:23.532] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:97:0)
[21:04:23.532] Timer tick, asking Buildbucket for the build status
[21:04:23.980] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:23.980] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:98:0) after 1m0s
[21:05:23.985] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:98:0)
[21:05:23.985] Timer tick, asking Buildbucket for the build status
[21:05:24.090] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:24.090] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:99:0) after 1m0s
[21:06:24.122] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:99:0)
[21:06:24.122] Timer tick, asking Buildbucket for the build status
[21:06:24.313] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:24.313] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:100:0) after 1m0s
[21:07:24.342] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:100:0)
[21:07:24.342] Timer tick, asking Buildbucket for the build status
[21:07:25.190] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:25.190] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:101:0) after 1m0s
[21:08:25.218] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:101:0)
[21:08:25.218] Timer tick, asking Buildbucket for the build status
[21:08:26.074] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:26.074] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:102:0) after 1m0s
[21:09:26.101] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:102:0)
[21:09:26.101] Timer tick, asking Buildbucket for the build status
[21:09:26.266] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:26.266] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:103:0) after 1m0s
[21:10:26.303] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:103:0)
[21:10:26.303] Timer tick, asking Buildbucket for the build status
[21:10:26.659] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:26.659] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:104:0) after 1m0s
[21:11:26.682] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:104:0)
[21:11:26.682] Timer tick, asking Buildbucket for the build status
[21:11:27.095] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:27.095] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:105:0) after 1m0s
[21:12:27.119] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:105:0)
[21:12:27.119] Timer tick, asking Buildbucket for the build status
[21:12:27.229] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:27.229] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:106:0) after 1m0s
[21:13:27.293] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:106:0)
[21:13:27.293] Timer tick, asking Buildbucket for the build status
[21:13:27.563] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:27.563] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:107:0) after 1m0s
[21:14:27.609] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:107:0)
[21:14:27.609] Timer tick, asking Buildbucket for the build status
[21:14:27.815] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:27.815] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:108:0) after 1m0s
[21:15:27.831] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:108:0)
[21:15:27.831] Timer tick, asking Buildbucket for the build status
[21:15:27.955] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:27.955] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:109:0) after 1m0s
[21:16:27.994] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:109:0)
[21:16:27.994] Timer tick, asking Buildbucket for the build status
[21:16:28.123] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:28.123] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:110:0) after 1m0s
[21:17:28.128] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:110:0)
[21:17:28.128] Timer tick, asking Buildbucket for the build status
[21:17:28.321] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:28.321] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:111:0) after 1m0s
[21:18:28.346] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:111:0)
[21:18:28.346] Timer tick, asking Buildbucket for the build status
[21:18:28.462] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:28.462] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:112:0) after 1m0s
[21:19:28.479] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:112:0)
[21:19:28.479] Timer tick, asking Buildbucket for the build status
[21:19:28.908] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:28.908] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:113:0) after 1m0s
[21:20:28.946] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:113:0)
[21:20:28.946] Timer tick, asking Buildbucket for the build status
[21:20:29.576] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:29.576] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:114:0) after 1m0s
[21:21:29.598] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:114:0)
[21:21:29.598] Timer tick, asking Buildbucket for the build status
[21:21:29.701] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:29.701] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:115:0) after 1m0s
[21:22:29.693] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:115:0)
[21:22:29.693] Timer tick, asking Buildbucket for the build status
[21:22:29.965] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:29.965] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:116:0) after 1m0s
[21:23:29.985] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:116:0)
[21:23:29.985] Timer tick, asking Buildbucket for the build status
[21:23:31.183] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:31.183] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:117:0) after 1m0s
[21:24:31.194] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:117:0)
[21:24:31.194] Timer tick, asking Buildbucket for the build status
[21:24:31.376] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:31.376] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:118:0) after 1m0s
[21:25:31.398] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:118:0)
[21:25:31.398] Timer tick, asking Buildbucket for the build status
[21:25:32.180] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:32.180] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:119:0) after 1m0s
[21:26:32.161] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:119:0)
[21:26:32.161] Timer tick, asking Buildbucket for the build status
[21:26:32.697] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:32.697] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:120:0) after 1m0s
[21:27:32.716] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:120:0)
[21:27:32.716] Timer tick, asking Buildbucket for the build status
[21:27:33.209] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:33.209] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:121:0) after 1m0s
[21:28:33.203] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:121:0)
[21:28:33.203] Timer tick, asking Buildbucket for the build status
[21:28:33.396] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:33.396] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:122:0) after 1m0s
[21:29:33.416] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:122:0)
[21:29:33.416] Timer tick, asking Buildbucket for the build status
[21:29:33.715] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:33.715] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:123:0) after 1m0s
[21:30:33.688] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:123:0)
[21:30:33.688] Timer tick, asking Buildbucket for the build status
[21:30:34.013] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:34.013] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:124:0) after 1m0s
[21:31:34.016] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:124:0)
[21:31:34.016] Timer tick, asking Buildbucket for the build status
[21:31:34.469] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:34.469] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:125:0) after 1m0s
[21:32:34.459] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:125:0)
[21:32:34.459] Timer tick, asking Buildbucket for the build status
[21:32:34.565] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:34.565] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:126:0) after 1m0s
[21:33:34.578] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:126:0)
[21:33:34.578] Timer tick, asking Buildbucket for the build status
[21:33:34.866] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:34.866] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:127:0) after 1m0s
[21:34:34.842] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:127:0)
[21:34:34.842] Timer tick, asking Buildbucket for the build status
[21:34:34.966] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:34.966] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:128:0) after 1m0s
[21:35:34.953] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:128:0)
[21:35:34.953] Timer tick, asking Buildbucket for the build status
[21:35:35.212] Build 8918802096490161040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:35.212] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084232158569744368:129:0) after 1m0s
[21:36:04.800] Received PubSub notification, asking Buildbucket for the build status
[21:36:04.961] Build 8918802096490161040: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[21:36:04.961] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1552772164079127",
"created_by": "project:chromium",
"created_ts": "1552764529675663",
"id": "8918802096490161040",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"781e496e366ce508c2e16fa1c2e0c3ed110b8e2e\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"swarming\": {\"bot_dimensions\": {\"gce\": [\"1\"], \"machine_type\": [\"n1-standard-8\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"], \"locale\": [\"en_US.cp1252\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"builder\": [\"Windows deterministic\"], \"id\": [\"windows-deterministic-0-v3qq\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4201-b04f7f8\"], \"image\": [\"chrome-win10-1703-2018-04-20-61acaf8983f\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"integrity\": [\"high\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"pool\": [\"luci.chromium.ci\"], \"caches\": [\"builder_e8fffaf9fac16164b1ec16ebaa1f377b792d39a582f7640830518d8ff7303d0c_v2\", \"git\", \"goma_v2\", \"vpython\"]}}, \"ui\": {\"info\": \"\"}, \"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"got_nacl_revision\": \"1ed9878b8eb68c1e0d0268754dafcf83e747fa8f\", \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"69008a87185992aa348ece3f893f6a84f8786ae8\", \"got_revision\": \"781e496e366ce508c2e16fa1c2e0c3ed110b8e2e\", \"got_swarming_client_revision\": \"7a61cf37d6a0163f0ec02d495289a1d038e62457\", \"recipe\": \"swarming/deterministic_build\", \"mastername\": \"chromium.win\", \"got_angle_revision\": \"38e282570676f3e434482f8a02ea539b286fd1fa\", \"got_v8_revision\": \"c35de7d07160523c4742ce65addd1d2134f48ffb\", \"got_revision_cp\": \"refs/heads/master@{#641453}\", \"got_dawn_revision\": \"931311700c7c4b76a15c23eee8792dda125fb97e\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#27145}\", \"buildnumber\": 12454, \"path_config\": \"generic\", \"revision\": \"781e496e366ce508c2e16fa1c2e0c3ed110b8e2e\", \"got_v8_revision_cp\": \"refs/heads/7.5.58@{#1}\", \"bot_id\": \"windows-deterministic-0-v3qq\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1552764531691662",
"status": "COMPLETED",
"status_changed_ts": "1552772164443523",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/12454",
"builder:Windows deterministic",
"buildset:commit/git/781e496e366ce508c2e16fa1c2e0c3ed110b8e2e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/781e496e366ce508c2e16fa1c2e0c3ed110b8e2e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9084232158569744368",
"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/8918802096490161040/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Windows",
"swarming_tag:recipe_name:swarming/deterministic_build",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:43a0cbf556a3aa10",
"user_agent:luci-scheduler"
],
"updated_ts": "1552772164447275",
"url": "https://ci.chromium.org/b/8918802096490161040",
"utcnow_ts": "1552772164952902"
}
}
[21:36:04.961] Invocation finished in 2h7m16.653801211s with status SUCCEEDED