[19:12:28.149] New invocation is queued and will start shortly
[19:12:29.211] Starting the invocation (attempt 1)
[19:12:29.279] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:12:29.279] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:12:29.279] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9083055425794159616",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"d4412c97ff1cb80e0affaebfc6f0ec455a5bf6cb\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9083055425794159616",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/d4412c97ff1cb80e0affaebfc6f0ec455a5bf6cb",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d4412c97ff1cb80e0affaebfc6f0ec455a5bf6cb",
"gitiles_ref:refs/heads/master"
]
}
[19:12:34.423] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "project:chromium",
"created_ts": "1553886753574332",
"id": "8917625359442777152",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"d4412c97ff1cb80e0affaebfc6f0ec455a5bf6cb\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1553886754203949",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/12625",
"builder:Windows deterministic",
"buildset:commit/git/d4412c97ff1cb80e0affaebfc6f0ec455a5bf6cb",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d4412c97ff1cb80e0affaebfc6f0ec455a5bf6cb",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9083055425794159616",
"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/8917625359442777152/+/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": "1553886754204077",
"url": "https://ci.chromium.org/b/8917625359442777152",
"utcnow_ts": "1553886754412261"
}
}
[19:12:34.423] Task URL: https://ci.chromium.org/b/8917625359442777152
[19:12:34.423] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:2:0) after 1m0s
[19:12:38.268] Received PubSub notification, asking Buildbucket for the build status
[19:12:38.697] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:34.445] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:2:0)
[19:13:34.445] Timer tick, asking Buildbucket for the build status
[19:13:34.561] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:34.561] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:4:0) after 1m0s
[19:14:34.615] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:4:0)
[19:14:34.615] Timer tick, asking Buildbucket for the build status
[19:14:35.003] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:35.003] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:5:0) after 1m0s
[19:15:34.982] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:5:0)
[19:15:34.982] Timer tick, asking Buildbucket for the build status
[19:15:35.281] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:35.281] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:6:0) after 1m0s
[19:16:35.300] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:6:0)
[19:16:35.300] Timer tick, asking Buildbucket for the build status
[19:16:35.433] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:35.433] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:7:0) after 1m0s
[19:17:35.453] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:7:0)
[19:17:35.453] Timer tick, asking Buildbucket for the build status
[19:17:35.891] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:35.891] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:8:0) after 1m0s
[19:18:35.921] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:8:0)
[19:18:35.921] Timer tick, asking Buildbucket for the build status
[19:18:36.289] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:36.289] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:9:0) after 1m0s
[19:19:36.312] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:9:0)
[19:19:36.312] Timer tick, asking Buildbucket for the build status
[19:19:36.431] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:36.431] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:10:0) after 1m0s
[19:20:36.451] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:10:0)
[19:20:36.451] Timer tick, asking Buildbucket for the build status
[19:20:36.533] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:36.533] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:11:0) after 1m0s
[19:21:36.548] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:11:0)
[19:21:36.548] Timer tick, asking Buildbucket for the build status
[19:21:36.725] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:36.725] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:12:0) after 1m0s
[19:22:36.721] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:12:0)
[19:22:36.721] Timer tick, asking Buildbucket for the build status
[19:22:36.860] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:36.860] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:13:0) after 1m0s
[19:23:36.840] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:13:0)
[19:23:36.840] Timer tick, asking Buildbucket for the build status
[19:23:37.558] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:37.558] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:14:0) after 1m0s
[19:24:37.556] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:14:0)
[19:24:37.556] Timer tick, asking Buildbucket for the build status
[19:24:37.716] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:37.716] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:15:0) after 1m0s
[19:25:37.723] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:15:0)
[19:25:37.723] Timer tick, asking Buildbucket for the build status
[19:25:38.007] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:38.007] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:16:0) after 1m0s
[19:26:38.035] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:16:0)
[19:26:38.035] Timer tick, asking Buildbucket for the build status
[19:26:38.181] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:38.181] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:17:0) after 1m0s
[19:27:38.191] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:17:0)
[19:27:38.191] Timer tick, asking Buildbucket for the build status
[19:27:38.674] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:38.674] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:18:0) after 1m0s
[19:28:38.728] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:18:0)
[19:28:38.728] Timer tick, asking Buildbucket for the build status
[19:28:38.818] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:38.818] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:19:0) after 1m0s
[19:29:38.813] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:19:0)
[19:29:38.813] Timer tick, asking Buildbucket for the build status
[19:29:38.918] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:38.918] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:20:0) after 1m0s
[19:30:38.927] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:20:0)
[19:30:38.927] Timer tick, asking Buildbucket for the build status
[19:30:39.299] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:39.299] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:21:0) after 1m0s
[19:31:39.313] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:21:0)
[19:31:39.313] Timer tick, asking Buildbucket for the build status
[19:31:39.399] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:39.399] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:22:0) after 1m0s
[19:32:39.412] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:22:0)
[19:32:39.412] Timer tick, asking Buildbucket for the build status
[19:32:39.533] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:39.533] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:23:0) after 1m0s
[19:33:39.529] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:23:0)
[19:33:39.529] Timer tick, asking Buildbucket for the build status
[19:33:39.647] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:39.647] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:24:0) after 1m0s
[19:34:39.621] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:24:0)
[19:34:39.621] Timer tick, asking Buildbucket for the build status
[19:34:39.745] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:39.745] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:25:0) after 1m0s
[19:35:39.764] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:25:0)
[19:35:39.764] Timer tick, asking Buildbucket for the build status
[19:35:39.852] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:39.852] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:26:0) after 1m0s
[19:36:39.876] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:26:0)
[19:36:39.876] Timer tick, asking Buildbucket for the build status
[19:36:40.334] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:40.337] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:27:0) after 1m0s
[19:37:40.356] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:27:0)
[19:37:40.356] Timer tick, asking Buildbucket for the build status
[19:37:40.595] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:40.595] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:28:0) after 1m0s
[19:38:40.619] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:28:0)
[19:38:40.619] Timer tick, asking Buildbucket for the build status
[19:38:40.962] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:40.962] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:29:0) after 1m0s
[19:39:40.957] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:29:0)
[19:39:40.957] Timer tick, asking Buildbucket for the build status
[19:39:41.208] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:41.208] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:30:0) after 1m0s
[19:40:41.227] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:30:0)
[19:40:41.227] Timer tick, asking Buildbucket for the build status
[19:40:41.329] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:41.329] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:31:0) after 1m0s
[19:41:41.319] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:31:0)
[19:41:41.319] Timer tick, asking Buildbucket for the build status
[19:41:41.404] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:41.404] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:32:0) after 1m0s
[19:42:41.419] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:32:0)
[19:42:41.419] Timer tick, asking Buildbucket for the build status
[19:42:41.785] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:41.785] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:33:0) after 1m0s
[19:43:41.811] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:33:0)
[19:43:41.811] Timer tick, asking Buildbucket for the build status
[19:43:41.927] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:41.927] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:34:0) after 1m0s
[19:44:41.956] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:34:0)
[19:44:41.957] Timer tick, asking Buildbucket for the build status
[19:44:42.186] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:42.186] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:35:0) after 1m0s
[19:45:42.206] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:35:0)
[19:45:42.206] Timer tick, asking Buildbucket for the build status
[19:45:42.327] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:42.327] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:36:0) after 1m0s
[19:46:42.345] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:36:0)
[19:46:42.345] Timer tick, asking Buildbucket for the build status
[19:46:42.777] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:42.777] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:37:0) after 1m0s
[19:47:42.807] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:37:0)
[19:47:42.807] Timer tick, asking Buildbucket for the build status
[19:47:42.917] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:42.917] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:38:0) after 1m0s
[19:48:42.950] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:38:0)
[19:48:42.950] Timer tick, asking Buildbucket for the build status
[19:48:43.098] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:43.098] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:39:0) after 1m0s
[19:49:43.117] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:39:0)
[19:49:43.117] Timer tick, asking Buildbucket for the build status
[19:49:43.206] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:43.206] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:40:0) after 1m0s
[19:50:43.232] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:40:0)
[19:50:43.232] Timer tick, asking Buildbucket for the build status
[19:50:43.938] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:43.938] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:41:0) after 1m0s
[19:51:43.923] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:41:0)
[19:51:43.923] Timer tick, asking Buildbucket for the build status
[19:51:44.058] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:44.058] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:42:0) after 1m0s
[19:52:44.046] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:42:0)
[19:52:44.046] Timer tick, asking Buildbucket for the build status
[19:52:44.140] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:44.140] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:43:0) after 1m0s
[19:53:44.121] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:43:0)
[19:53:44.121] Timer tick, asking Buildbucket for the build status
[19:53:44.234] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:44.234] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:44:0) after 1m0s
[19:54:44.256] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:44:0)
[19:54:44.256] Timer tick, asking Buildbucket for the build status
[19:54:44.357] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:44.357] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:45:0) after 1m0s
[19:55:44.368] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:45:0)
[19:55:44.368] Timer tick, asking Buildbucket for the build status
[19:55:44.832] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:44.832] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:46:0) after 1m0s
[19:56:44.856] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:46:0)
[19:56:44.856] Timer tick, asking Buildbucket for the build status
[19:56:44.961] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:44.961] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:47:0) after 1m0s
[19:57:44.959] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:47:0)
[19:57:44.959] Timer tick, asking Buildbucket for the build status
[19:57:45.228] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:45.228] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:48:0) after 1m0s
[19:58:45.247] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:48:0)
[19:58:45.247] Timer tick, asking Buildbucket for the build status
[19:58:45.332] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:45.332] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:49:0) after 1m0s
[19:59:45.344] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:49:0)
[19:59:45.344] Timer tick, asking Buildbucket for the build status
[19:59:45.489] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:45.489] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:50:0) after 1m0s
[20:00:45.523] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:50:0)
[20:00:45.523] Timer tick, asking Buildbucket for the build status
[20:00:45.628] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:45.628] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:51:0) after 1m0s
[20:01:45.611] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:51:0)
[20:01:45.611] Timer tick, asking Buildbucket for the build status
[20:01:45.835] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:45.835] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:52:0) after 1m0s
[20:02:45.806] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:52:0)
[20:02:45.806] Timer tick, asking Buildbucket for the build status
[20:02:46.105] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:46.105] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:53:0) after 1m0s
[20:03:46.129] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:53:0)
[20:03:46.129] Timer tick, asking Buildbucket for the build status
[20:03:46.567] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:46.567] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:54:0) after 1m0s
[20:04:46.539] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:54:0)
[20:04:46.539] Timer tick, asking Buildbucket for the build status
[20:04:46.657] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:46.657] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:55:0) after 1m0s
[20:05:46.639] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:55:0)
[20:05:46.639] Timer tick, asking Buildbucket for the build status
[20:05:46.759] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:46.759] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:56:0) after 1m0s
[20:06:46.803] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:56:0)
[20:06:46.803] Timer tick, asking Buildbucket for the build status
[20:06:46.907] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:46.907] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:57:0) after 1m0s
[20:07:46.930] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:57:0)
[20:07:46.930] Timer tick, asking Buildbucket for the build status
[20:07:47.078] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:47.078] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:58:0) after 1m0s
[20:08:47.103] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:58:0)
[20:08:47.103] Timer tick, asking Buildbucket for the build status
[20:08:47.700] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:47.700] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:59:0) after 1m0s
[20:09:47.709] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:59:0)
[20:09:47.709] Timer tick, asking Buildbucket for the build status
[20:09:48.089] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:48.089] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:60:0) after 1m0s
[20:10:48.103] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:60:0)
[20:10:48.103] Timer tick, asking Buildbucket for the build status
[20:10:48.193] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:48.193] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:61:0) after 1m0s
[20:11:48.215] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:61:0)
[20:11:48.215] Timer tick, asking Buildbucket for the build status
[20:11:48.491] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:48.491] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:62:0) after 1m0s
[20:12:48.513] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:62:0)
[20:12:48.513] Timer tick, asking Buildbucket for the build status
[20:12:48.727] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:48.727] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:63:0) after 1m0s
[20:13:48.727] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:63:0)
[20:13:48.727] Timer tick, asking Buildbucket for the build status
[20:13:49.599] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:49.599] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:64:0) after 1m0s
[20:14:49.619] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:64:0)
[20:14:49.619] Timer tick, asking Buildbucket for the build status
[20:14:49.698] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:49.698] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:65:0) after 1m0s
[20:15:49.685] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:65:0)
[20:15:49.685] Timer tick, asking Buildbucket for the build status
[20:15:49.774] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:49.774] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:66:0) after 1m0s
[20:16:49.801] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:66:0)
[20:16:49.801] Timer tick, asking Buildbucket for the build status
[20:16:49.980] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:49.980] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:67:0) after 1m0s
[20:17:50.001] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:67:0)
[20:17:50.001] Timer tick, asking Buildbucket for the build status
[20:17:50.367] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:50.367] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:68:0) after 1m0s
[20:18:50.386] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:68:0)
[20:18:50.386] Timer tick, asking Buildbucket for the build status
[20:18:50.508] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:50.508] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:69:0) after 1m0s
[20:19:50.529] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:69:0)
[20:19:50.529] Timer tick, asking Buildbucket for the build status
[20:19:50.616] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:50.616] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:70:0) after 1m0s
[20:20:50.636] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:70:0)
[20:20:50.636] Timer tick, asking Buildbucket for the build status
[20:20:51.169] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:51.169] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:71:0) after 1m0s
[20:21:51.200] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:71:0)
[20:21:51.200] Timer tick, asking Buildbucket for the build status
[20:21:51.473] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:51.473] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:72:0) after 1m0s
[20:22:51.525] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:72:0)
[20:22:51.525] Timer tick, asking Buildbucket for the build status
[20:22:51.672] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:51.672] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:73:0) after 1m0s
[20:23:51.693] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:73:0)
[20:23:51.693] Timer tick, asking Buildbucket for the build status
[20:23:51.789] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:51.789] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:74:0) after 1m0s
[20:24:51.815] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:74:0)
[20:24:51.815] Timer tick, asking Buildbucket for the build status
[20:24:52.107] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:52.107] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:75:0) after 1m0s
[20:25:52.141] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:75:0)
[20:25:52.141] Timer tick, asking Buildbucket for the build status
[20:25:52.714] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:52.714] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:76:0) after 1m0s
[20:26:52.764] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:76:0)
[20:26:52.764] Timer tick, asking Buildbucket for the build status
[20:26:53.123] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:53.123] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:77:0) after 1m0s
[20:27:53.115] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:77:0)
[20:27:53.115] Timer tick, asking Buildbucket for the build status
[20:27:53.588] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:53.588] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:78:0) after 1m0s
[20:28:53.629] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:78:0)
[20:28:53.629] Timer tick, asking Buildbucket for the build status
[20:28:53.729] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:53.729] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:79:0) after 1m0s
[20:29:53.779] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:79:0)
[20:29:53.779] Timer tick, asking Buildbucket for the build status
[20:29:53.878] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:53.878] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:80:0) after 1m0s
[20:30:53.858] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:80:0)
[20:30:53.859] Timer tick, asking Buildbucket for the build status
[20:30:53.968] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:53.968] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:81:0) after 1m0s
[20:31:53.944] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:81:0)
[20:31:53.944] Timer tick, asking Buildbucket for the build status
[20:31:54.330] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:54.330] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:82:0) after 1m0s
[20:32:54.344] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:82:0)
[20:32:54.344] Timer tick, asking Buildbucket for the build status
[20:32:54.602] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:54.602] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:83:0) after 1m0s
[20:33:54.599] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:83:0)
[20:33:54.599] Timer tick, asking Buildbucket for the build status
[20:33:54.682] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:54.682] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:84:0) after 1m0s
[20:34:54.785] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:84:0)
[20:34:54.785] Timer tick, asking Buildbucket for the build status
[20:34:54.889] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:54.889] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:85:0) after 1m0s
[20:35:54.893] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:85:0)
[20:35:54.893] Timer tick, asking Buildbucket for the build status
[20:35:55.316] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:55.316] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:86:0) after 1m0s
[20:36:55.378] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:86:0)
[20:36:55.378] Timer tick, asking Buildbucket for the build status
[20:36:55.713] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:55.713] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:87:0) after 1m0s
[20:37:55.711] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:87:0)
[20:37:55.712] Timer tick, asking Buildbucket for the build status
[20:37:55.829] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:55.829] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:88:0) after 1m0s
[20:38:55.824] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:88:0)
[20:38:55.824] Timer tick, asking Buildbucket for the build status
[20:38:55.965] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:55.965] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:89:0) after 1m0s
[20:39:56.042] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:89:0)
[20:39:56.042] Timer tick, asking Buildbucket for the build status
[20:39:56.138] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:56.138] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:90:0) after 1m0s
[20:40:56.169] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:90:0)
[20:40:56.169] Timer tick, asking Buildbucket for the build status
[20:40:56.289] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:56.289] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:91:0) after 1m0s
[20:41:56.299] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:91:0)
[20:41:56.299] Timer tick, asking Buildbucket for the build status
[20:41:56.879] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:56.879] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:92:0) after 1m0s
[20:42:56.905] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:92:0)
[20:42:56.905] Timer tick, asking Buildbucket for the build status
[20:42:57.021] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:57.021] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:93:0) after 1m0s
[20:43:57.040] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:93:0)
[20:43:57.040] Timer tick, asking Buildbucket for the build status
[20:43:57.123] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:57.123] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:94:0) after 1m0s
[20:44:57.125] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:94:0)
[20:44:57.125] Timer tick, asking Buildbucket for the build status
[20:44:58.007] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:58.007] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:95:0) after 1m0s
[20:45:58.047] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:95:0)
[20:45:58.047] Timer tick, asking Buildbucket for the build status
[20:45:58.154] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:58.154] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:96:0) after 1m0s
[20:46:58.183] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:96:0)
[20:46:58.183] Timer tick, asking Buildbucket for the build status
[20:46:58.290] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:58.290] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:97:0) after 1m0s
[20:47:58.319] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:97:0)
[20:47:58.319] Timer tick, asking Buildbucket for the build status
[20:47:58.414] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:58.414] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:98:0) after 1m0s
[20:48:58.397] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:98:0)
[20:48:58.397] Timer tick, asking Buildbucket for the build status
[20:48:58.495] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:58.495] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:99:0) after 1m0s
[20:49:58.494] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:99:0)
[20:49:58.494] Timer tick, asking Buildbucket for the build status
[20:49:59.007] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:59.007] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:100:0) after 1m0s
[20:50:59.022] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:100:0)
[20:50:59.022] Timer tick, asking Buildbucket for the build status
[20:50:59.473] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:59.473] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:101:0) after 1m0s
[20:51:59.524] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:101:0)
[20:51:59.524] Timer tick, asking Buildbucket for the build status
[20:51:59.899] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:59.899] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:102:0) after 1m0s
[20:52:59.935] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:102:0)
[20:52:59.935] Timer tick, asking Buildbucket for the build status
[20:53:00.378] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:00.378] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:103:0) after 1m0s
[20:54:00.392] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:103:0)
[20:54:00.392] Timer tick, asking Buildbucket for the build status
[20:54:00.901] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:00.901] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:104:0) after 1m0s
[20:55:00.916] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:104:0)
[20:55:00.916] Timer tick, asking Buildbucket for the build status
[20:55:01.023] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:01.023] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:105:0) after 1m0s
[20:56:01.049] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:105:0)
[20:56:01.049] Timer tick, asking Buildbucket for the build status
[20:56:01.138] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:01.138] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:106:0) after 1m0s
[20:57:01.142] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:106:0)
[20:57:01.142] Timer tick, asking Buildbucket for the build status
[20:57:01.416] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:01.416] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:107:0) after 1m0s
[20:58:01.447] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:107:0)
[20:58:01.447] Timer tick, asking Buildbucket for the build status
[20:58:01.839] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:01.839] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:108:0) after 1m0s
[20:59:01.871] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:108:0)
[20:59:01.871] Timer tick, asking Buildbucket for the build status
[20:59:01.965] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:01.965] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:109:0) after 1m0s
[21:00:02.019] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:109:0)
[21:00:02.019] Timer tick, asking Buildbucket for the build status
[21:00:02.623] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:02.623] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:110:0) after 1m0s
[21:01:02.649] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:110:0)
[21:01:02.649] Timer tick, asking Buildbucket for the build status
[21:01:03.108] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:03.108] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:111:0) after 1m0s
[21:02:03.133] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:111:0)
[21:02:03.133] Timer tick, asking Buildbucket for the build status
[21:02:03.365] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:03.365] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:112:0) after 1m0s
[21:03:03.417] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:112:0)
[21:03:03.417] Timer tick, asking Buildbucket for the build status
[21:03:03.533] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:03.533] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:113:0) after 1m0s
[21:04:03.524] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:113:0)
[21:04:03.524] Timer tick, asking Buildbucket for the build status
[21:04:04.010] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:04.010] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:114:0) after 1m0s
[21:05:04.033] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:114:0)
[21:05:04.033] Timer tick, asking Buildbucket for the build status
[21:05:04.183] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:04.183] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:115:0) after 1m0s
[21:06:04.208] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:115:0)
[21:06:04.208] Timer tick, asking Buildbucket for the build status
[21:06:04.315] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:04.315] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:116:0) after 1m0s
[21:07:04.329] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:116:0)
[21:07:04.329] Timer tick, asking Buildbucket for the build status
[21:07:04.504] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:04.504] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:117:0) after 1m0s
[21:08:04.595] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:117:0)
[21:08:04.595] Timer tick, asking Buildbucket for the build status
[21:08:04.697] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:04.697] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:118:0) after 1m0s
[21:09:04.747] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:118:0)
[21:09:04.747] Timer tick, asking Buildbucket for the build status
[21:09:05.269] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:05.269] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:119:0) after 1m0s
[21:10:05.287] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:119:0)
[21:10:05.287] Timer tick, asking Buildbucket for the build status
[21:10:05.415] Build 8917625359442777152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:05.415] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083055425794159616:120:0) after 1m0s
[21:10:20.889] Received PubSub notification, asking Buildbucket for the build status
[21:10:21.039] Build 8917625359442777152: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[21:10:21.039] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1553893820391785",
"created_by": "project:chromium",
"created_ts": "1553886753574332",
"id": "8917625359442777152",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"d4412c97ff1cb80e0affaebfc6f0ec455a5bf6cb\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"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}, \"bot_id\": \"windows-deterministic-0-93fj\", \"branch\": \"refs/heads/master\", \"buildername\": \"Windows deterministic\", \"buildnumber\": 12625, \"got_angle_revision\": \"3115a0fc17fa8fb5af2490c58946de708dc17a17\", \"got_dawn_revision\": \"10c24684ab9cd1c747e251d7cb4590c43eecad6b\", \"got_nacl_revision\": \"de8da4841bc3d1ed020d798d5a7fd6fb3d817fa1\", \"got_revision\": \"d4412c97ff1cb80e0affaebfc6f0ec455a5bf6cb\", \"got_revision_cp\": \"refs/heads/master@{#645886}\", \"got_swarming_client_revision\": \"aa60736aded9fc32a0e21a81f5fc51f6009d01f3\", \"got_v8_revision\": \"2db1bb8dd37ef228e2d97c91c95996a94f679ef2\", \"got_v8_revision_cp\": \"refs/heads/7.5.145@{#1}\", \"got_webrtc_revision\": \"d9b62d32283e12cb6b49a03e16f9128229047d81\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#27353}\", \"mastername\": \"chromium.win\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"d4412c97ff1cb80e0affaebfc6f0ec455a5bf6cb\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"Windows deterministic\"], \"caches\": [\"builder_e8fffaf9fac16164b1ec16ebaa1f377b792d39a582f7640830518d8ff7303d0c_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"windows-deterministic-0-93fj\"], \"image\": [\"chrome-win10-1703-2018-04-20-61acaf8983f\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4226-cfe6277\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1553886757438857",
"status": "COMPLETED",
"status_changed_ts": "1553893820681635",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/12625",
"builder:Windows deterministic",
"buildset:commit/git/d4412c97ff1cb80e0affaebfc6f0ec455a5bf6cb",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d4412c97ff1cb80e0affaebfc6f0ec455a5bf6cb",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9083055425794159616",
"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/8917625359442777152/+/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:43e3afbf61cdaa10",
"user_agent:luci-scheduler"
],
"updated_ts": "1553893820684425",
"url": "https://ci.chromium.org/b/8917625359442777152",
"utcnow_ts": "1553893821028286"
}
}
[21:10:21.039] Invocation finished in 1h57m52.905753016s with status SUCCEEDED