[01:35:54.137] New invocation is queued and will start shortly
[01:35:55.193] Starting the invocation (attempt 1)
[01:35:55.239] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:35:55.239] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:35:55.240] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9085386823396025328",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"556e7283b39dcbe1d0edb22aaa9932dde20013ee\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9085386823396025328",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/556e7283b39dcbe1d0edb22aaa9932dde20013ee",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/556e7283b39dcbe1d0edb22aaa9932dde20013ee",
"gitiles_ref:refs/heads/master"
]
}
[01:35:56.562] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1551663355310087",
"id": "8919956761500756464",
"lease_expiration_ts": "1551782156011687",
"lease_key": "152851837",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"556e7283b39dcbe1d0edb22aaa9932dde20013ee\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1551663356244272",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/12288",
"builder:Windows deterministic",
"buildset:commit/git/556e7283b39dcbe1d0edb22aaa9932dde20013ee",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/556e7283b39dcbe1d0edb22aaa9932dde20013ee",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9085386823396025328",
"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/8919956761500756464/+/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:435f295fa8908610",
"user_agent:luci-scheduler"
],
"updated_ts": "1551663356244864",
"url": "https://ci.chromium.org/b/8919956761500756464",
"utcnow_ts": "1551663356550714"
}
}
[01:35:56.562] Task URL: https://ci.chromium.org/b/8919956761500756464
[01:35:56.562] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:2:0) after 1m0s
[01:35:57.806] Received PubSub notification, asking Buildbucket for the build status
[01:35:57.855] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:56.591] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:2:0)
[01:36:56.591] Timer tick, asking Buildbucket for the build status
[01:36:56.708] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:56.708] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:4:0) after 1m0s
[01:37:56.723] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:4:0)
[01:37:56.723] Timer tick, asking Buildbucket for the build status
[01:37:56.825] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:56.825] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:5:0) after 1m0s
[01:38:56.809] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:5:0)
[01:38:56.809] Timer tick, asking Buildbucket for the build status
[01:38:56.885] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:56.885] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:6:0) after 1m0s
[01:39:56.875] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:6:0)
[01:39:56.875] Timer tick, asking Buildbucket for the build status
[01:39:56.931] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:56.931] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:7:0) after 1m0s
[01:40:56.919] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:7:0)
[01:40:56.919] Timer tick, asking Buildbucket for the build status
[01:40:57.108] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:57.108] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:8:0) after 1m0s
[01:41:57.144] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:8:0)
[01:41:57.144] Timer tick, asking Buildbucket for the build status
[01:41:57.195] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:57.195] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:9:0) after 1m0s
[01:42:57.199] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:9:0)
[01:42:57.199] Timer tick, asking Buildbucket for the build status
[01:42:57.257] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:57.257] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:10:0) after 1m0s
[01:43:57.239] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:10:0)
[01:43:57.239] Timer tick, asking Buildbucket for the build status
[01:43:57.386] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:57.386] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:11:0) after 1m0s
[01:44:57.431] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:11:0)
[01:44:57.431] Timer tick, asking Buildbucket for the build status
[01:44:57.477] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:57.477] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:12:0) after 1m0s
[01:45:57.476] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:12:0)
[01:45:57.476] Timer tick, asking Buildbucket for the build status
[01:45:57.578] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:57.578] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:13:0) after 1m0s
[01:46:57.588] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:13:0)
[01:46:57.588] Timer tick, asking Buildbucket for the build status
[01:46:57.897] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:57.897] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:14:0) after 1m0s
[01:47:57.918] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:14:0)
[01:47:57.918] Timer tick, asking Buildbucket for the build status
[01:47:57.994] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:57.994] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:15:0) after 1m0s
[01:48:58.014] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:15:0)
[01:48:58.014] Timer tick, asking Buildbucket for the build status
[01:48:58.095] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:58.095] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:16:0) after 1m0s
[01:49:58.119] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:16:0)
[01:49:58.119] Timer tick, asking Buildbucket for the build status
[01:49:58.165] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:58.165] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:17:0) after 1m0s
[01:50:58.174] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:17:0)
[01:50:58.174] Timer tick, asking Buildbucket for the build status
[01:50:58.509] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:58.509] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:18:0) after 1m0s
[01:51:58.521] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:18:0)
[01:51:58.521] Timer tick, asking Buildbucket for the build status
[01:51:58.620] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:58.620] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:19:0) after 1m0s
[01:52:58.627] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:19:0)
[01:52:58.627] Timer tick, asking Buildbucket for the build status
[01:52:58.703] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:58.703] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:20:0) after 1m0s
[01:53:58.716] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:20:0)
[01:53:58.716] Timer tick, asking Buildbucket for the build status
[01:53:58.791] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:58.791] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:21:0) after 1m0s
[01:54:58.790] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:21:0)
[01:54:58.790] Timer tick, asking Buildbucket for the build status
[01:54:58.961] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:58.961] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:22:0) after 1m0s
[01:55:58.985] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:22:0)
[01:55:58.985] Timer tick, asking Buildbucket for the build status
[01:55:59.049] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:59.049] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:23:0) after 1m0s
[01:56:59.030] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:23:0)
[01:56:59.030] Timer tick, asking Buildbucket for the build status
[01:56:59.405] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:59.405] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:24:0) after 1m0s
[01:57:59.421] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:24:0)
[01:57:59.421] Timer tick, asking Buildbucket for the build status
[01:57:59.602] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:59.602] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:25:0) after 1m0s
[01:58:59.623] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:25:0)
[01:58:59.623] Timer tick, asking Buildbucket for the build status
[01:58:59.975] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:59.975] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:26:0) after 1m0s
[01:59:59.967] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:26:0)
[01:59:59.967] Timer tick, asking Buildbucket for the build status
[02:00:00.165] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:00.165] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:27:0) after 1m0s
[02:01:00.185] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:27:0)
[02:01:00.185] Timer tick, asking Buildbucket for the build status
[02:01:00.257] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:00.257] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:28:0) after 1m0s
[02:02:00.281] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:28:0)
[02:02:00.281] Timer tick, asking Buildbucket for the build status
[02:02:00.429] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:00.429] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:29:0) after 1m0s
[02:03:00.439] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:29:0)
[02:03:00.439] Timer tick, asking Buildbucket for the build status
[02:03:00.551] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:00.551] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:30:0) after 1m0s
[02:04:00.569] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:30:0)
[02:04:00.570] Timer tick, asking Buildbucket for the build status
[02:04:00.628] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:00.628] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:31:0) after 1m0s
[02:05:00.649] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:31:0)
[02:05:00.649] Timer tick, asking Buildbucket for the build status
[02:05:00.707] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:00.707] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:32:0) after 1m0s
[02:06:00.717] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:32:0)
[02:06:00.717] Timer tick, asking Buildbucket for the build status
[02:06:00.967] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:00.967] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:33:0) after 1m0s
[02:07:00.984] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:33:0)
[02:07:00.985] Timer tick, asking Buildbucket for the build status
[02:07:01.225] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:01.225] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:34:0) after 1m0s
[02:08:01.242] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:34:0)
[02:08:01.242] Timer tick, asking Buildbucket for the build status
[02:08:01.611] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:01.611] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:35:0) after 1m0s
[02:09:01.581] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:35:0)
[02:09:01.581] Timer tick, asking Buildbucket for the build status
[02:09:01.636] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:01.636] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:36:0) after 1m0s
[02:10:01.656] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:36:0)
[02:10:01.656] Timer tick, asking Buildbucket for the build status
[02:10:01.761] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:01.761] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:37:0) after 1m0s
[02:11:01.781] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:37:0)
[02:11:01.781] Timer tick, asking Buildbucket for the build status
[02:11:01.958] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:01.958] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:38:0) after 1m0s
[02:12:01.977] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:38:0)
[02:12:01.977] Timer tick, asking Buildbucket for the build status
[02:12:02.093] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:02.093] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:39:0) after 1m0s
[02:13:02.114] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:39:0)
[02:13:02.114] Timer tick, asking Buildbucket for the build status
[02:13:02.187] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:02.187] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:40:0) after 1m0s
[02:14:02.194] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:40:0)
[02:14:02.194] Timer tick, asking Buildbucket for the build status
[02:14:02.272] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:02.272] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:41:0) after 1m0s
[02:15:02.329] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:41:0)
[02:15:02.329] Timer tick, asking Buildbucket for the build status
[02:15:02.594] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:02.594] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:42:0) after 1m0s
[02:16:02.584] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:42:0)
[02:16:02.584] Timer tick, asking Buildbucket for the build status
[02:16:02.692] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:02.692] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:43:0) after 1m0s
[02:17:02.675] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:43:0)
[02:17:02.675] Timer tick, asking Buildbucket for the build status
[02:17:02.723] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:02.723] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:44:0) after 1m0s
[02:18:02.744] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:44:0)
[02:18:02.744] Timer tick, asking Buildbucket for the build status
[02:18:03.009] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:03.009] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:45:0) after 1m0s
[02:19:03.017] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:45:0)
[02:19:03.017] Timer tick, asking Buildbucket for the build status
[02:19:03.073] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:03.073] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:46:0) after 1m0s
[02:20:03.093] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:46:0)
[02:20:03.093] Timer tick, asking Buildbucket for the build status
[02:20:03.313] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:03.313] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:47:0) after 1m0s
[02:21:03.331] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:47:0)
[02:21:03.331] Timer tick, asking Buildbucket for the build status
[02:21:03.488] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:03.488] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:48:0) after 1m0s
[02:22:03.507] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:48:0)
[02:22:03.507] Timer tick, asking Buildbucket for the build status
[02:22:03.684] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:03.684] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:49:0) after 1m0s
[02:23:03.674] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:49:0)
[02:23:03.674] Timer tick, asking Buildbucket for the build status
[02:23:03.744] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:03.744] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:50:0) after 1m0s
[02:24:03.736] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:50:0)
[02:24:03.736] Timer tick, asking Buildbucket for the build status
[02:24:03.883] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:03.883] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:51:0) after 1m0s
[02:25:03.915] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:51:0)
[02:25:03.915] Timer tick, asking Buildbucket for the build status
[02:25:03.961] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:03.961] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:52:0) after 1m0s
[02:26:03.982] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:52:0)
[02:26:03.982] Timer tick, asking Buildbucket for the build status
[02:26:04.056] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:04.056] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:53:0) after 1m0s
[02:27:04.049] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:53:0)
[02:27:04.049] Timer tick, asking Buildbucket for the build status
[02:27:04.185] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:04.185] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:54:0) after 1m0s
[02:28:04.211] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:54:0)
[02:28:04.211] Timer tick, asking Buildbucket for the build status
[02:28:04.397] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:04.397] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:55:0) after 1m0s
[02:29:04.416] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:55:0)
[02:29:04.416] Timer tick, asking Buildbucket for the build status
[02:29:04.616] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:04.616] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:56:0) after 1m0s
[02:30:04.607] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:56:0)
[02:30:04.607] Timer tick, asking Buildbucket for the build status
[02:30:04.669] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:04.669] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:57:0) after 1m0s
[02:31:04.700] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:57:0)
[02:31:04.700] Timer tick, asking Buildbucket for the build status
[02:31:04.784] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:04.785] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:58:0) after 1m0s
[02:32:04.802] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:58:0)
[02:32:04.802] Timer tick, asking Buildbucket for the build status
[02:32:04.883] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:04.883] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:59:0) after 1m0s
[02:33:04.904] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:59:0)
[02:33:04.904] Timer tick, asking Buildbucket for the build status
[02:33:05.019] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:05.019] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:60:0) after 1m0s
[02:34:05.033] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:60:0)
[02:34:05.033] Timer tick, asking Buildbucket for the build status
[02:34:05.093] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:05.093] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:61:0) after 1m0s
[02:35:05.137] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:61:0)
[02:35:05.137] Timer tick, asking Buildbucket for the build status
[02:35:05.207] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:05.207] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:62:0) after 1m0s
[02:36:05.271] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:62:0)
[02:36:05.271] Timer tick, asking Buildbucket for the build status
[02:36:05.344] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:05.344] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:63:0) after 1m0s
[02:37:05.356] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:63:0)
[02:37:05.356] Timer tick, asking Buildbucket for the build status
[02:37:05.490] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:05.490] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:64:0) after 1m0s
[02:38:05.509] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:64:0)
[02:38:05.509] Timer tick, asking Buildbucket for the build status
[02:38:05.705] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:05.705] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:65:0) after 1m0s
[02:39:05.725] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:65:0)
[02:39:05.725] Timer tick, asking Buildbucket for the build status
[02:39:05.852] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:05.852] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:66:0) after 1m0s
[02:40:05.871] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:66:0)
[02:40:05.871] Timer tick, asking Buildbucket for the build status
[02:40:06.193] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:06.193] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:67:0) after 1m0s
[02:41:06.190] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:67:0)
[02:41:06.191] Timer tick, asking Buildbucket for the build status
[02:41:06.234] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:06.234] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:68:0) after 1m0s
[02:42:06.277] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:68:0)
[02:42:06.277] Timer tick, asking Buildbucket for the build status
[02:42:06.348] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:06.348] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:69:0) after 1m0s
[02:43:06.329] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:69:0)
[02:43:06.329] Timer tick, asking Buildbucket for the build status
[02:43:06.396] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:06.396] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:70:0) after 1m0s
[02:44:06.411] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:70:0)
[02:44:06.411] Timer tick, asking Buildbucket for the build status
[02:44:06.496] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:06.496] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:71:0) after 1m0s
[02:45:06.526] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:71:0)
[02:45:06.526] Timer tick, asking Buildbucket for the build status
[02:45:06.584] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:06.584] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:72:0) after 1m0s
[02:46:06.607] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:72:0)
[02:46:06.607] Timer tick, asking Buildbucket for the build status
[02:46:06.729] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:06.729] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:73:0) after 1m0s
[02:47:06.769] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:73:0)
[02:47:06.769] Timer tick, asking Buildbucket for the build status
[02:47:06.841] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:06.841] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:74:0) after 1m0s
[02:48:06.841] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:74:0)
[02:48:06.841] Timer tick, asking Buildbucket for the build status
[02:48:07.055] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:07.055] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:75:0) after 1m0s
[02:49:07.065] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:75:0)
[02:49:07.065] Timer tick, asking Buildbucket for the build status
[02:49:07.205] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:07.205] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:76:0) after 1m0s
[02:50:07.194] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:76:0)
[02:50:07.194] Timer tick, asking Buildbucket for the build status
[02:50:07.239] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:07.239] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:77:0) after 1m0s
[02:51:07.270] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:77:0)
[02:51:07.270] Timer tick, asking Buildbucket for the build status
[02:51:07.329] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:07.329] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:78:0) after 1m0s
[02:52:07.311] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:78:0)
[02:52:07.311] Timer tick, asking Buildbucket for the build status
[02:52:07.381] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:07.381] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:79:0) after 1m0s
[02:53:07.397] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:79:0)
[02:53:07.397] Timer tick, asking Buildbucket for the build status
[02:53:07.442] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:07.442] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:80:0) after 1m0s
[02:54:07.429] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:80:0)
[02:54:07.430] Timer tick, asking Buildbucket for the build status
[02:54:07.481] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:07.481] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:81:0) after 1m0s
[02:55:07.492] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:81:0)
[02:55:07.492] Timer tick, asking Buildbucket for the build status
[02:55:07.723] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:07.723] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:82:0) after 1m0s
[02:56:07.762] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:82:0)
[02:56:07.762] Timer tick, asking Buildbucket for the build status
[02:56:08.282] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:08.282] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:83:0) after 1m0s
[02:57:08.286] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:83:0)
[02:57:08.286] Timer tick, asking Buildbucket for the build status
[02:57:08.425] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:08.425] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:84:0) after 1m0s
[02:58:08.430] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:84:0)
[02:58:08.430] Timer tick, asking Buildbucket for the build status
[02:58:08.871] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:08.871] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:85:0) after 1m0s
[02:59:08.885] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:85:0)
[02:59:08.885] Timer tick, asking Buildbucket for the build status
[02:59:08.940] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:08.940] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:86:0) after 1m0s
[03:00:08.959] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:86:0)
[03:00:08.959] Timer tick, asking Buildbucket for the build status
[03:00:09.137] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:09.137] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:87:0) after 1m0s
[03:01:09.133] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:87:0)
[03:01:09.133] Timer tick, asking Buildbucket for the build status
[03:01:09.189] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:09.189] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:88:0) after 1m0s
[03:02:09.211] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:88:0)
[03:02:09.211] Timer tick, asking Buildbucket for the build status
[03:02:09.275] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:09.275] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:89:0) after 1m0s
[03:03:09.264] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:89:0)
[03:03:09.264] Timer tick, asking Buildbucket for the build status
[03:03:09.327] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:09.327] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:90:0) after 1m0s
[03:04:09.336] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:90:0)
[03:04:09.336] Timer tick, asking Buildbucket for the build status
[03:04:09.405] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:09.405] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:91:0) after 1m0s
[03:05:09.415] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:91:0)
[03:05:09.415] Timer tick, asking Buildbucket for the build status
[03:05:09.487] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:09.487] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:92:0) after 1m0s
[03:06:09.485] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:92:0)
[03:06:09.485] Timer tick, asking Buildbucket for the build status
[03:06:09.568] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:09.568] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:93:0) after 1m0s
[03:07:09.550] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:93:0)
[03:07:09.550] Timer tick, asking Buildbucket for the build status
[03:07:09.633] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:09.633] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:94:0) after 1m0s
[03:08:09.658] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:94:0)
[03:08:09.658] Timer tick, asking Buildbucket for the build status
[03:08:09.716] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:09.716] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:95:0) after 1m0s
[03:09:09.735] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:95:0)
[03:09:09.735] Timer tick, asking Buildbucket for the build status
[03:09:09.868] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:09.868] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:96:0) after 1m0s
[03:10:09.889] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:96:0)
[03:10:09.889] Timer tick, asking Buildbucket for the build status
[03:10:09.945] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:09.945] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:97:0) after 1m0s
[03:11:09.963] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:97:0)
[03:11:09.963] Timer tick, asking Buildbucket for the build status
[03:11:10.069] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:10.069] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:98:0) after 1m0s
[03:12:10.089] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:98:0)
[03:12:10.089] Timer tick, asking Buildbucket for the build status
[03:12:10.461] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:10.461] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:99:0) after 1m0s
[03:13:10.473] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:99:0)
[03:13:10.473] Timer tick, asking Buildbucket for the build status
[03:13:10.570] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:10.570] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:100:0) after 1m0s
[03:14:10.591] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:100:0)
[03:14:10.591] Timer tick, asking Buildbucket for the build status
[03:14:10.689] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:10.689] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:101:0) after 1m0s
[03:15:10.711] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:101:0)
[03:15:10.711] Timer tick, asking Buildbucket for the build status
[03:15:10.778] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:10.778] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:102:0) after 1m0s
[03:16:10.804] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:102:0)
[03:16:10.804] Timer tick, asking Buildbucket for the build status
[03:16:10.915] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:10.915] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:103:0) after 1m0s
[03:17:10.912] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:103:0)
[03:17:10.912] Timer tick, asking Buildbucket for the build status
[03:17:11.009] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:11.009] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:104:0) after 1m0s
[03:18:10.995] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:104:0)
[03:18:10.995] Timer tick, asking Buildbucket for the build status
[03:18:11.481] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:11.481] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:105:0) after 1m0s
[03:19:11.482] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:105:0)
[03:19:11.482] Timer tick, asking Buildbucket for the build status
[03:19:11.525] Build 8919956761500756464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:11.525] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9085386823396025328:106:0) after 1m0s
[03:19:15.078] Received PubSub notification, asking Buildbucket for the build status
[03:19:15.285] Build 8919956761500756464: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[03:19:15.285] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "AUTO",
"completed_ts": "1551669553759869",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1551663355310087",
"id": "8919956761500756464",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"556e7283b39dcbe1d0edb22aaa9932dde20013ee\"}}",
"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\": [\"win10-727f49a0-us-west1-c-gw0h\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4143-92e279d\"], \"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\": {\"got_nacl_revision\": \"1ed9878b8eb68c1e0d0268754dafcf83e747fa8f\", \"got_swarming_client_revision\": \"7a61cf37d6a0163f0ec02d495289a1d038e62457\", \"got_revision\": \"556e7283b39dcbe1d0edb22aaa9932dde20013ee\", \"recipe\": \"swarming/deterministic_build\", \"got_dawn_revision\": \"1c92c159adf995b450275de69407625328bb2796\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#26938}\", \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"got_revision_cp\": \"refs/heads/master@{#637193}\", \"branch\": \"refs/heads/master\", \"revision\": \"556e7283b39dcbe1d0edb22aaa9932dde20013ee\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"4a42742dc6e41142e40e8e85f6a2dfb6fb774cca\", \"mastername\": \"chromium.win\", \"got_angle_revision\": \"4d153383bd3a2359a44fb0c13a65119c0087702f\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1551663355310087, \"tags\": [\"build_address:luci.chromium.ci/Windows deterministic/12288\", \"builder:Windows deterministic\", \"buildset:commit/git/556e7283b39dcbe1d0edb22aaa9932dde20013ee\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/556e7283b39dcbe1d0edb22aaa9932dde20013ee\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9085386823396025328\", \"scheduler_job_id:chromium/Windows deterministic\", \"user_agent:luci-scheduler\"], \"bucket\": \"luci.chromium.ci\", \"created_by\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"project\": \"chromium\", \"id\": \"8919956761500756464\"}}, \"got_v8_revision\": \"c7724d93343593fbe1183b5523cd63a6ab1fb441\", \"got_v8_revision_cp\": \"refs/heads/7.4.269@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 12288, \"$recipe_engine/buildbucket\": {\"build\": {\"tags\": [{\"value\": \"Windows deterministic\", \"key\": \"builder\"}, {\"value\": \"commit/git/556e7283b39dcbe1d0edb22aaa9932dde20013ee\", \"key\": \"buildset\"}, {\"value\": \"commit/gitiles/chromium.googlesource.com/chromium/src/+/556e7283b39dcbe1d0edb22aaa9932dde20013ee\", \"key\": \"buildset\"}, {\"value\": \"9085386823396025328\", \"key\": \"scheduler_invocation_id\"}, {\"value\": \"chromium/Windows deterministic\", \"key\": \"scheduler_job_id\"}, {\"value\": \"luci-scheduler\", \"key\": \"user_agent\"}], \"builder\": {\"project\": \"chromium\", \"builder\": \"Windows deterministic\", \"bucket\": \"ci\"}, \"number\": 12288, \"infra\": {\"buildbucket\": {\"serviceConfigRevision\": \"ca0db2d762966d4b32d49d86567d9d7697438caf\", \"canary\": true}, \"recipe\": {\"name\": \"swarming/deterministic_build\", \"cipdPackage\": \"infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build\"}, \"swarming\": {\"hostname\": \"chromium-swarm.appspot.com\"}}, \"createdBy\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"id\": \"8919956761500756464\", \"input\": {\"gitilesCommit\": {\"project\": \"chromium/src\", \"host\": \"chromium.googlesource.com\", \"ref\": \"refs/heads/master\", \"id\": \"556e7283b39dcbe1d0edb22aaa9932dde20013ee\"}}, \"createTime\": \"2019-03-04T01:35:55.310087Z\"}}, \"path_config\": \"generic\", \"bot_id\": \"win10-727f49a0-us-west1-c-gw0h\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1551663357007694",
"status": "COMPLETED",
"status_changed_ts": "1551669554818988",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/12288",
"builder:Windows deterministic",
"buildset:commit/git/556e7283b39dcbe1d0edb22aaa9932dde20013ee",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/556e7283b39dcbe1d0edb22aaa9932dde20013ee",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9085386823396025328",
"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/8919956761500756464/+/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:435f295fa8908610",
"user_agent:luci-scheduler"
],
"updated_ts": "1551669554820955",
"url": "https://ci.chromium.org/b/8919956761500756464",
"utcnow_ts": "1551669555270756"
}
}
[03:19:15.285] Invocation finished in 1h43m21.165724576s with status SUCCEEDED