[12:55:36.767] New invocation is queued and will start shortly
[12:55:37.856] Starting the invocation (attempt 1)
[12:55:37.917] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:55:37.917] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:55:37.917] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9084438090043206624",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"d0d3382bed88ef4cb1ce430552ebcd5b154af42e\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9084438090043206624",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/d0d3382bed88ef4cb1ce430552ebcd5b154af42e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d0d3382bed88ef4cb1ce430552ebcd5b154af42e",
"gitiles_ref:refs/heads/master"
]
}
[12:55:39.490] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1552568138081444",
"id": "8919008028001703680",
"lease_expiration_ts": "1552686939155052",
"lease_key": "1580677933",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"d0d3382bed88ef4cb1ce430552ebcd5b154af42e\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1552568139366453",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/12425",
"builder:Windows deterministic",
"buildset:commit/git/d0d3382bed88ef4cb1ce430552ebcd5b154af42e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d0d3382bed88ef4cb1ce430552ebcd5b154af42e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9084438090043206624",
"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/8919008028001703680/+/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:4395174495b57110",
"user_agent:luci-scheduler"
],
"updated_ts": "1552568139366554",
"url": "https://ci.chromium.org/b/8919008028001703680",
"utcnow_ts": "1552568139480883"
}
}
[12:55:39.490] Task URL: https://ci.chromium.org/b/8919008028001703680
[12:55:39.490] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:2:0) after 1m0s
[12:55:42.227] Received PubSub notification, asking Buildbucket for the build status
[12:55:42.293] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:39.511] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:2:0)
[12:56:39.511] Timer tick, asking Buildbucket for the build status
[12:56:39.580] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:39.580] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:4:0) after 1m0s
[12:57:39.587] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:4:0)
[12:57:39.587] Timer tick, asking Buildbucket for the build status
[12:57:39.777] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:39.777] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:5:0) after 1m0s
[12:58:39.793] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:5:0)
[12:58:39.793] Timer tick, asking Buildbucket for the build status
[12:58:39.894] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:39.894] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:6:0) after 1m0s
[12:59:39.901] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:6:0)
[12:59:39.901] Timer tick, asking Buildbucket for the build status
[12:59:39.974] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:39.974] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:7:0) after 1m0s
[13:00:39.991] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:7:0)
[13:00:39.991] Timer tick, asking Buildbucket for the build status
[13:00:40.104] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:40.104] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:8:0) after 1m0s
[13:01:40.104] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:8:0)
[13:01:40.104] Timer tick, asking Buildbucket for the build status
[13:01:40.169] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:40.169] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:9:0) after 1m0s
[13:02:40.165] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:9:0)
[13:02:40.165] Timer tick, asking Buildbucket for the build status
[13:02:40.237] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:40.237] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:10:0) after 1m0s
[13:03:40.238] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:10:0)
[13:03:40.238] Timer tick, asking Buildbucket for the build status
[13:03:40.274] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:40.274] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:11:0) after 1m0s
[13:04:40.271] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:11:0)
[13:04:40.271] Timer tick, asking Buildbucket for the build status
[13:04:40.409] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:40.409] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:12:0) after 1m0s
[13:05:40.398] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:12:0)
[13:05:40.398] Timer tick, asking Buildbucket for the build status
[13:05:40.437] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:40.437] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:13:0) after 1m0s
[13:06:40.459] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:13:0)
[13:06:40.459] Timer tick, asking Buildbucket for the build status
[13:06:40.623] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:40.623] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:14:0) after 1m0s
[13:07:40.632] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:14:0)
[13:07:40.632] Timer tick, asking Buildbucket for the build status
[13:07:40.815] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:40.815] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:15:0) after 1m0s
[13:08:40.834] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:15:0)
[13:08:40.834] Timer tick, asking Buildbucket for the build status
[13:08:40.892] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:40.892] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:16:0) after 1m0s
[13:09:40.878] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:16:0)
[13:09:40.878] Timer tick, asking Buildbucket for the build status
[13:09:40.999] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:40.999] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:17:0) after 1m0s
[13:10:41.044] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:17:0)
[13:10:41.044] Timer tick, asking Buildbucket for the build status
[13:10:41.122] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:41.122] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:18:0) after 1m0s
[13:11:41.139] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:18:0)
[13:11:41.139] Timer tick, asking Buildbucket for the build status
[13:11:41.452] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:41.452] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:19:0) after 1m0s
[13:12:41.482] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:19:0)
[13:12:41.482] Timer tick, asking Buildbucket for the build status
[13:12:41.561] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:41.561] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:20:0) after 1m0s
[13:13:41.548] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:20:0)
[13:13:41.548] Timer tick, asking Buildbucket for the build status
[13:13:41.586] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:41.586] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:21:0) after 1m0s
[13:14:41.602] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:21:0)
[13:14:41.602] Timer tick, asking Buildbucket for the build status
[13:14:41.684] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:41.684] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:22:0) after 1m0s
[13:15:41.692] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:22:0)
[13:15:41.692] Timer tick, asking Buildbucket for the build status
[13:15:41.775] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:41.775] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:23:0) after 1m0s
[13:16:41.783] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:23:0)
[13:16:41.783] Timer tick, asking Buildbucket for the build status
[13:16:41.843] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:41.843] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:24:0) after 1m0s
[13:17:41.864] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:24:0)
[13:17:41.864] Timer tick, asking Buildbucket for the build status
[13:17:41.905] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:41.905] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:25:0) after 1m0s
[13:18:41.926] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:25:0)
[13:18:41.926] Timer tick, asking Buildbucket for the build status
[13:18:42.062] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:42.062] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:26:0) after 1m0s
[13:19:42.098] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:26:0)
[13:19:42.098] Timer tick, asking Buildbucket for the build status
[13:19:42.143] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:42.143] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:27:0) after 1m0s
[13:20:42.143] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:27:0)
[13:20:42.143] Timer tick, asking Buildbucket for the build status
[13:20:42.178] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:42.178] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:28:0) after 1m0s
[13:21:42.179] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:28:0)
[13:21:42.179] Timer tick, asking Buildbucket for the build status
[13:21:42.215] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:42.215] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:29:0) after 1m0s
[13:22:42.239] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:29:0)
[13:22:42.239] Timer tick, asking Buildbucket for the build status
[13:22:42.314] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:42.314] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:30:0) after 1m0s
[13:23:42.297] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:30:0)
[13:23:42.297] Timer tick, asking Buildbucket for the build status
[13:23:42.334] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:42.334] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:31:0) after 1m0s
[13:24:42.341] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:31:0)
[13:24:42.341] Timer tick, asking Buildbucket for the build status
[13:24:42.420] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:42.420] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:32:0) after 1m0s
[13:25:42.410] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:32:0)
[13:25:42.410] Timer tick, asking Buildbucket for the build status
[13:25:42.461] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:42.461] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:33:0) after 1m0s
[13:26:42.470] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:33:0)
[13:26:42.471] Timer tick, asking Buildbucket for the build status
[13:26:42.600] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:42.600] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:34:0) after 1m0s
[13:27:42.640] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:34:0)
[13:27:42.640] Timer tick, asking Buildbucket for the build status
[13:27:42.739] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:42.739] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:35:0) after 1m0s
[13:28:42.764] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:35:0)
[13:28:42.764] Timer tick, asking Buildbucket for the build status
[13:28:42.885] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:42.885] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:36:0) after 1m0s
[13:29:42.880] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:36:0)
[13:29:42.880] Timer tick, asking Buildbucket for the build status
[13:29:42.967] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:42.967] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:37:0) after 1m0s
[13:30:42.939] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:37:0)
[13:30:42.939] Timer tick, asking Buildbucket for the build status
[13:30:43.093] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:43.093] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:38:0) after 1m0s
[13:31:43.097] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:38:0)
[13:31:43.097] Timer tick, asking Buildbucket for the build status
[13:31:43.238] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:43.238] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:39:0) after 1m0s
[13:32:43.232] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:39:0)
[13:32:43.232] Timer tick, asking Buildbucket for the build status
[13:32:43.307] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:43.307] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:40:0) after 1m0s
[13:33:43.286] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:40:0)
[13:33:43.286] Timer tick, asking Buildbucket for the build status
[13:33:43.376] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:43.376] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:41:0) after 1m0s
[13:34:43.395] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:41:0)
[13:34:43.395] Timer tick, asking Buildbucket for the build status
[13:34:43.434] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:43.434] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:42:0) after 1m0s
[13:35:43.427] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:42:0)
[13:35:43.427] Timer tick, asking Buildbucket for the build status
[13:35:43.477] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:43.477] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:43:0) after 1m0s
[13:36:43.489] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:43:0)
[13:36:43.489] Timer tick, asking Buildbucket for the build status
[13:36:43.704] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:43.704] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:44:0) after 1m0s
[13:37:43.714] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:44:0)
[13:37:43.714] Timer tick, asking Buildbucket for the build status
[13:37:43.889] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:43.889] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:45:0) after 1m0s
[13:38:43.960] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:45:0)
[13:38:43.960] Timer tick, asking Buildbucket for the build status
[13:38:44.036] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:44.036] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:46:0) after 1m0s
[13:39:44.021] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:46:0)
[13:39:44.021] Timer tick, asking Buildbucket for the build status
[13:39:44.161] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:44.161] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:47:0) after 1m0s
[13:40:44.185] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:47:0)
[13:40:44.185] Timer tick, asking Buildbucket for the build status
[13:40:44.222] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:44.222] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:48:0) after 1m0s
[13:41:44.274] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:48:0)
[13:41:44.274] Timer tick, asking Buildbucket for the build status
[13:41:44.319] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:44.319] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:49:0) after 1m0s
[13:42:44.341] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:49:0)
[13:42:44.341] Timer tick, asking Buildbucket for the build status
[13:42:44.400] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:44.400] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:50:0) after 1m0s
[13:43:44.424] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:50:0)
[13:43:44.424] Timer tick, asking Buildbucket for the build status
[13:43:44.462] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:44.462] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:51:0) after 1m0s
[13:44:44.509] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:51:0)
[13:44:44.509] Timer tick, asking Buildbucket for the build status
[13:44:44.575] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:44.576] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:52:0) after 1m0s
[13:45:44.584] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:52:0)
[13:45:44.584] Timer tick, asking Buildbucket for the build status
[13:45:44.628] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:44.628] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:53:0) after 1m0s
[13:46:44.648] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:53:0)
[13:46:44.648] Timer tick, asking Buildbucket for the build status
[13:46:44.698] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:44.698] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:54:0) after 1m0s
[13:47:44.708] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:54:0)
[13:47:44.708] Timer tick, asking Buildbucket for the build status
[13:47:44.753] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:44.753] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:55:0) after 1m0s
[13:48:44.788] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:55:0)
[13:48:44.788] Timer tick, asking Buildbucket for the build status
[13:48:44.999] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:44.999] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:56:0) after 1m0s
[13:49:45.021] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:56:0)
[13:49:45.021] Timer tick, asking Buildbucket for the build status
[13:49:45.060] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:45.060] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:57:0) after 1m0s
[13:50:45.058] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:57:0)
[13:50:45.058] Timer tick, asking Buildbucket for the build status
[13:50:45.100] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:45.100] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:58:0) after 1m0s
[13:51:45.102] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:58:0)
[13:51:45.102] Timer tick, asking Buildbucket for the build status
[13:51:45.198] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:45.198] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:59:0) after 1m0s
[13:52:45.187] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:59:0)
[13:52:45.187] Timer tick, asking Buildbucket for the build status
[13:52:45.267] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:45.267] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:60:0) after 1m0s
[13:53:45.291] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:60:0)
[13:53:45.291] Timer tick, asking Buildbucket for the build status
[13:53:45.354] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:45.354] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:61:0) after 1m0s
[13:54:45.345] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:61:0)
[13:54:45.345] Timer tick, asking Buildbucket for the build status
[13:54:45.487] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:45.487] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:62:0) after 1m0s
[13:55:45.506] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:62:0)
[13:55:45.506] Timer tick, asking Buildbucket for the build status
[13:55:45.601] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:45.601] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:63:0) after 1m0s
[13:56:45.588] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:63:0)
[13:56:45.588] Timer tick, asking Buildbucket for the build status
[13:56:45.639] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:45.639] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:64:0) after 1m0s
[13:57:45.619] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:64:0)
[13:57:45.619] Timer tick, asking Buildbucket for the build status
[13:57:45.771] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:45.771] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:65:0) after 1m0s
[13:58:45.785] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:65:0)
[13:58:45.785] Timer tick, asking Buildbucket for the build status
[13:58:45.836] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:45.836] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:66:0) after 1m0s
[13:59:45.858] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:66:0)
[13:59:45.858] Timer tick, asking Buildbucket for the build status
[13:59:45.903] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:45.903] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:67:0) after 1m0s
[14:00:45.903] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:67:0)
[14:00:45.903] Timer tick, asking Buildbucket for the build status
[14:00:45.960] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:45.960] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:68:0) after 1m0s
[14:01:45.980] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:68:0)
[14:01:45.980] Timer tick, asking Buildbucket for the build status
[14:01:46.109] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:46.109] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:69:0) after 1m0s
[14:02:46.121] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:69:0)
[14:02:46.121] Timer tick, asking Buildbucket for the build status
[14:02:46.264] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:46.265] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:70:0) after 1m0s
[14:03:46.264] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:70:0)
[14:03:46.264] Timer tick, asking Buildbucket for the build status
[14:03:46.315] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:46.315] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:71:0) after 1m0s
[14:04:46.289] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:71:0)
[14:04:46.289] Timer tick, asking Buildbucket for the build status
[14:04:46.327] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:46.327] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:72:0) after 1m0s
[14:05:46.377] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:72:0)
[14:05:46.377] Timer tick, asking Buildbucket for the build status
[14:05:46.505] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:46.505] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:73:0) after 1m0s
[14:06:46.506] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:73:0)
[14:06:46.506] Timer tick, asking Buildbucket for the build status
[14:06:46.585] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:46.585] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:74:0) after 1m0s
[14:07:46.581] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:74:0)
[14:07:46.581] Timer tick, asking Buildbucket for the build status
[14:07:46.709] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:46.709] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:75:0) after 1m0s
[14:08:46.705] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:75:0)
[14:08:46.705] Timer tick, asking Buildbucket for the build status
[14:08:47.099] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:47.099] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:76:0) after 1m0s
[14:09:47.075] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:76:0)
[14:09:47.075] Timer tick, asking Buildbucket for the build status
[14:09:47.159] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:47.159] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:77:0) after 1m0s
[14:10:47.182] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:77:0)
[14:10:47.182] Timer tick, asking Buildbucket for the build status
[14:10:47.397] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:47.397] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:78:0) after 1m0s
[14:11:47.417] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:78:0)
[14:11:47.417] Timer tick, asking Buildbucket for the build status
[14:11:47.496] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:47.496] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:79:0) after 1m0s
[14:12:47.503] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:79:0)
[14:12:47.503] Timer tick, asking Buildbucket for the build status
[14:12:47.663] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:47.664] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:80:0) after 1m0s
[14:13:47.676] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:80:0)
[14:13:47.676] Timer tick, asking Buildbucket for the build status
[14:13:47.712] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:47.712] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:81:0) after 1m0s
[14:14:47.686] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:81:0)
[14:14:47.686] Timer tick, asking Buildbucket for the build status
[14:14:47.820] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:47.820] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:82:0) after 1m0s
[14:15:47.805] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:82:0)
[14:15:47.805] Timer tick, asking Buildbucket for the build status
[14:15:48.015] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:48.015] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:83:0) after 1m0s
[14:16:48.049] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:83:0)
[14:16:48.049] Timer tick, asking Buildbucket for the build status
[14:16:48.094] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:48.094] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:84:0) after 1m0s
[14:17:48.127] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:84:0)
[14:17:48.127] Timer tick, asking Buildbucket for the build status
[14:17:48.164] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:48.164] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:85:0) after 1m0s
[14:18:48.172] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:85:0)
[14:18:48.172] Timer tick, asking Buildbucket for the build status
[14:18:48.206] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:48.206] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:86:0) after 1m0s
[14:19:48.182] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:86:0)
[14:19:48.182] Timer tick, asking Buildbucket for the build status
[14:19:48.217] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:48.217] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:87:0) after 1m0s
[14:20:48.227] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:87:0)
[14:20:48.227] Timer tick, asking Buildbucket for the build status
[14:20:48.310] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:48.310] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:88:0) after 1m0s
[14:21:48.343] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:88:0)
[14:21:48.343] Timer tick, asking Buildbucket for the build status
[14:21:48.449] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:48.449] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:89:0) after 1m0s
[14:22:48.471] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:89:0)
[14:22:48.471] Timer tick, asking Buildbucket for the build status
[14:22:48.547] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:48.547] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:90:0) after 1m0s
[14:23:48.561] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:90:0)
[14:23:48.561] Timer tick, asking Buildbucket for the build status
[14:23:48.636] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:48.636] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:91:0) after 1m0s
[14:24:48.657] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:91:0)
[14:24:48.657] Timer tick, asking Buildbucket for the build status
[14:24:48.714] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:48.714] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:92:0) after 1m0s
[14:25:48.708] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:92:0)
[14:25:48.708] Timer tick, asking Buildbucket for the build status
[14:25:48.747] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:48.747] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:93:0) after 1m0s
[14:26:48.769] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:93:0)
[14:26:48.769] Timer tick, asking Buildbucket for the build status
[14:26:48.820] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:48.820] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:94:0) after 1m0s
[14:27:48.832] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:94:0)
[14:27:48.832] Timer tick, asking Buildbucket for the build status
[14:27:49.180] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:49.180] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:95:0) after 1m0s
[14:28:49.164] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:95:0)
[14:28:49.164] Timer tick, asking Buildbucket for the build status
[14:28:49.199] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:49.199] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:96:0) after 1m0s
[14:29:49.179] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:96:0)
[14:29:49.179] Timer tick, asking Buildbucket for the build status
[14:29:49.383] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:49.383] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:97:0) after 1m0s
[14:30:49.398] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:97:0)
[14:30:49.398] Timer tick, asking Buildbucket for the build status
[14:30:49.441] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:49.441] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:98:0) after 1m0s
[14:31:49.450] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:98:0)
[14:31:49.450] Timer tick, asking Buildbucket for the build status
[14:31:49.563] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:49.563] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:99:0) after 1m0s
[14:32:49.537] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:99:0)
[14:32:49.537] Timer tick, asking Buildbucket for the build status
[14:32:49.602] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:49.602] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:100:0) after 1m0s
[14:33:49.626] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:100:0)
[14:33:49.626] Timer tick, asking Buildbucket for the build status
[14:33:49.702] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:49.702] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:101:0) after 1m0s
[14:34:49.727] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:101:0)
[14:34:49.727] Timer tick, asking Buildbucket for the build status
[14:34:49.766] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:49.766] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:102:0) after 1m0s
[14:35:49.778] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:102:0)
[14:35:49.778] Timer tick, asking Buildbucket for the build status
[14:35:49.890] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:49.890] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:103:0) after 1m0s
[14:36:49.949] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:103:0)
[14:36:49.949] Timer tick, asking Buildbucket for the build status
[14:36:49.989] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:49.989] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:104:0) after 1m0s
[14:37:49.981] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:104:0)
[14:37:49.981] Timer tick, asking Buildbucket for the build status
[14:37:50.090] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:50.090] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:105:0) after 1m0s
[14:38:50.138] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:105:0)
[14:38:50.138] Timer tick, asking Buildbucket for the build status
[14:38:50.177] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:50.177] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:106:0) after 1m0s
[14:39:50.203] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:106:0)
[14:39:50.203] Timer tick, asking Buildbucket for the build status
[14:39:50.240] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:50.240] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:107:0) after 1m0s
[14:40:50.266] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:107:0)
[14:40:50.266] Timer tick, asking Buildbucket for the build status
[14:40:50.373] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:50.373] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:108:0) after 1m0s
[14:41:50.445] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:108:0)
[14:41:50.445] Timer tick, asking Buildbucket for the build status
[14:41:50.492] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:50.492] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:109:0) after 1m0s
[14:42:50.478] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:109:0)
[14:42:50.478] Timer tick, asking Buildbucket for the build status
[14:42:50.514] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:50.514] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:110:0) after 1m0s
[14:43:50.538] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:110:0)
[14:43:50.538] Timer tick, asking Buildbucket for the build status
[14:43:50.615] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:50.615] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:111:0) after 1m0s
[14:44:50.645] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:111:0)
[14:44:50.645] Timer tick, asking Buildbucket for the build status
[14:44:50.720] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:50.720] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:112:0) after 1m0s
[14:45:50.740] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:112:0)
[14:45:50.740] Timer tick, asking Buildbucket for the build status
[14:45:50.866] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:50.867] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:113:0) after 1m0s
[14:46:50.877] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:113:0)
[14:46:50.877] Timer tick, asking Buildbucket for the build status
[14:46:50.912] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:50.912] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:114:0) after 1m0s
[14:47:50.939] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:114:0)
[14:47:50.939] Timer tick, asking Buildbucket for the build status
[14:47:50.997] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:50.997] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:115:0) after 1m0s
[14:48:51.036] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:115:0)
[14:48:51.036] Timer tick, asking Buildbucket for the build status
[14:48:51.081] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:51.081] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:116:0) after 1m0s
[14:49:51.072] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:116:0)
[14:49:51.072] Timer tick, asking Buildbucket for the build status
[14:49:51.106] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:51.106] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:117:0) after 1m0s
[14:50:51.115] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:117:0)
[14:50:51.115] Timer tick, asking Buildbucket for the build status
[14:50:51.152] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:51.152] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:118:0) after 1m0s
[14:51:51.166] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:118:0)
[14:51:51.166] Timer tick, asking Buildbucket for the build status
[14:51:51.224] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:51.224] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:119:0) after 1m0s
[14:52:51.249] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:119:0)
[14:52:51.249] Timer tick, asking Buildbucket for the build status
[14:52:51.284] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:51.284] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:120:0) after 1m0s
[14:53:51.309] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:120:0)
[14:53:51.309] Timer tick, asking Buildbucket for the build status
[14:53:51.498] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:51.498] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:121:0) after 1m0s
[14:54:51.545] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:121:0)
[14:54:51.545] Timer tick, asking Buildbucket for the build status
[14:54:51.620] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:51.620] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:122:0) after 1m0s
[14:55:51.622] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:122:0)
[14:55:51.622] Timer tick, asking Buildbucket for the build status
[14:55:51.689] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:51.689] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:123:0) after 1m0s
[14:56:51.713] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:123:0)
[14:56:51.713] Timer tick, asking Buildbucket for the build status
[14:56:51.773] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:51.773] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:124:0) after 1m0s
[14:57:51.798] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:124:0)
[14:57:51.798] Timer tick, asking Buildbucket for the build status
[14:57:52.099] Build 8919008028001703680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:52.099] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9084438090043206624:125:0) after 1m0s
[14:58:45.186] Received PubSub notification, asking Buildbucket for the build status
[14:58:45.228] Build 8919008028001703680: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[14:58:45.228] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1552575524485578",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1552568138081444",
"id": "8919008028001703680",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"d0d3382bed88ef4cb1ce430552ebcd5b154af42e\"}}",
"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\": [\"4186-af94c3f\"], \"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\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#27121}\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"d841ea6b586a81065f78d3eae7ab82c505cda1d8\", \"got_revision\": \"d0d3382bed88ef4cb1ce430552ebcd5b154af42e\", \"got_swarming_client_revision\": \"7a61cf37d6a0163f0ec02d495289a1d038e62457\", \"recipe\": \"swarming/deterministic_build\", \"mastername\": \"chromium.win\", \"got_angle_revision\": \"37b996425a0e72b07a79fd8e86c288ba8021aa4a\", \"got_v8_revision\": \"d4096d05abfc992a150de884c25361917e06c6a9\", \"got_revision_cp\": \"refs/heads/master@{#640711}\", \"got_dawn_revision\": \"ea2c7f20f2f96e15570f7e9416fb6c826f809747\", \"branch\": \"refs/heads/master\", \"buildnumber\": 12425, \"path_config\": \"generic\", \"bot_id\": \"windows-deterministic-0-v3qq\", \"got_v8_revision_cp\": \"refs/heads/7.5.37@{#1}\", \"revision\": \"d0d3382bed88ef4cb1ce430552ebcd5b154af42e\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1552568140854652",
"status": "COMPLETED",
"status_changed_ts": "1552575524965355",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/12425",
"builder:Windows deterministic",
"buildset:commit/git/d0d3382bed88ef4cb1ce430552ebcd5b154af42e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d0d3382bed88ef4cb1ce430552ebcd5b154af42e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9084438090043206624",
"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/8919008028001703680/+/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:4395174495b57110",
"user_agent:luci-scheduler"
],
"updated_ts": "1552575524967915",
"url": "https://ci.chromium.org/b/8919008028001703680",
"utcnow_ts": "1552575525218741"
}
}
[14:58:45.228] Invocation finished in 2h3m8.477063997s with status SUCCEEDED