[20:50:34.217] New invocation is queued and will start shortly
[20:50:35.519] Starting the invocation (attempt 1)
[20:50:35.555] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:50:35.555] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:50:35.555] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9101531034952891840",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"17cf8a858dd5b0f968e31b6817f487d5ccc34eaa\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9101531034952891840",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/17cf8a858dd5b0f968e31b6817f487d5ccc34eaa",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/17cf8a858dd5b0f968e31b6817f487d5ccc34eaa",
"gitiles_ref:refs/heads/master"
]
}
[20:50:37.249] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1536267035653955",
"id": "8936100972781692592",
"lease_expiration_ts": "1536411036813654",
"lease_key": "1371546306",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"17cf8a858dd5b0f968e31b6817f487d5ccc34eaa\"}}",
"project": "chromium",
"result_details_json": "null",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1536267037118673",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/9999",
"builder:Windows deterministic",
"buildset:commit/git/17cf8a858dd5b0f968e31b6817f487d5ccc34eaa",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/17cf8a858dd5b0f968e31b6817f487d5ccc34eaa",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9101531034952891840",
"scheduler_job_id:chromium/Windows deterministic",
"swarming_dimension:builder:Windows deterministic",
"swarming_dimension:caches:builder_d795d6e07fe37a299fcfc0e872e48757ad306282f024125f803c0465bfd1eeae_v2",
"swarming_dimension:cores:8",
"swarming_dimension:cpu:x86-64",
"swarming_dimension:os:Windows-10",
"swarming_dimension:pool:luci.chromium.ci",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:build_address:luci.chromium.ci/Windows deterministic/9999",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8936100972781692592",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:ca2c7a741e04d0385e2bf62941ac932dc0521af3",
"swarming_tag:builder:Windows deterministic",
"swarming_tag:buildset:commit/git/17cf8a858dd5b0f968e31b6817f487d5ccc34eaa",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/17cf8a858dd5b0f968e31b6817f487d5ccc34eaa",
"swarming_tag:caches:builder_d795d6e07fe37a299fcfc0e872e48757ad306282f024125f803c0465bfd1eeae_v2",
"swarming_tag:cores:8",
"swarming_tag:cpu:x86-64",
"swarming_tag:gitiles_ref:refs/heads/master",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8936100972781692592/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Windows-10",
"swarming_tag:pool:luci.chromium.ci",
"swarming_tag:priority:30",
"swarming_tag:recipe_name:swarming/deterministic_build",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_tag:scheduler_invocation_id:9101531034952891840",
"swarming_tag:scheduler_job_id:chromium/Windows deterministic",
"swarming_tag:service_account:chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"swarming_tag:swarming.pool.template:skip",
"swarming_tag:swarming.pool.version:75d2c3b43d86be91c8a8b6a9bd4c64b49bc37fb1",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:3fc9782f7b04c310",
"user_agent:luci-scheduler"
],
"updated_ts": "1536267037118773",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/9999",
"utcnow_ts": "1536267037237607"
}
}
[20:50:37.249] Task URL: https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/9999
[20:50:37.249] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:2:0) after 1m0s
[20:51:37.272] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:2:0)
[20:51:37.272] Timer tick, asking Buildbucket for the build status
[20:51:37.516] Build 8936100972781692592: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:51:37.516] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:3:0) after 1m0s
[20:52:01.346] Received PubSub notification, asking Buildbucket for the build status
[20:52:01.491] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:37.541] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:3:0)
[20:52:37.541] Timer tick, asking Buildbucket for the build status
[20:52:37.585] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:37.585] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:5:0) after 1m0s
[20:53:37.604] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:5:0)
[20:53:37.604] Timer tick, asking Buildbucket for the build status
[20:53:37.784] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:37.784] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:6:0) after 1m0s
[20:54:37.803] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:6:0)
[20:54:37.803] Timer tick, asking Buildbucket for the build status
[20:54:37.997] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:37.997] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:7:0) after 1m0s
[20:55:38.023] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:7:0)
[20:55:38.023] Timer tick, asking Buildbucket for the build status
[20:55:38.116] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:38.116] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:8:0) after 1m0s
[20:56:38.164] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:8:0)
[20:56:38.164] Timer tick, asking Buildbucket for the build status
[20:56:38.249] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:38.249] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:9:0) after 1m0s
[20:57:38.276] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:9:0)
[20:57:38.276] Timer tick, asking Buildbucket for the build status
[20:57:38.452] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:38.452] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:10:0) after 1m0s
[20:58:38.474] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:10:0)
[20:58:38.474] Timer tick, asking Buildbucket for the build status
[20:58:38.516] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:38.516] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:11:0) after 1m0s
[20:59:38.558] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:11:0)
[20:59:38.558] Timer tick, asking Buildbucket for the build status
[20:59:38.615] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:38.615] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:12:0) after 1m0s
[21:00:38.638] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:12:0)
[21:00:38.638] Timer tick, asking Buildbucket for the build status
[21:00:38.698] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:38.698] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:13:0) after 1m0s
[21:01:38.725] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:13:0)
[21:01:38.725] Timer tick, asking Buildbucket for the build status
[21:01:38.767] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:38.767] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:14:0) after 1m0s
[21:02:38.796] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:14:0)
[21:02:38.796] Timer tick, asking Buildbucket for the build status
[21:02:38.953] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:38.953] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:15:0) after 1m0s
[21:03:38.973] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:15:0)
[21:03:38.973] Timer tick, asking Buildbucket for the build status
[21:03:39.018] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:39.018] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:16:0) after 1m0s
[21:04:39.037] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:16:0)
[21:04:39.037] Timer tick, asking Buildbucket for the build status
[21:04:39.093] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:39.093] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:17:0) after 1m0s
[21:05:39.117] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:17:0)
[21:05:39.117] Timer tick, asking Buildbucket for the build status
[21:05:39.153] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:39.153] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:18:0) after 1m0s
[21:06:39.178] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:18:0)
[21:06:39.178] Timer tick, asking Buildbucket for the build status
[21:06:39.290] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:39.290] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:19:0) after 1m0s
[21:07:39.312] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:19:0)
[21:07:39.312] Timer tick, asking Buildbucket for the build status
[21:07:39.381] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:39.381] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:20:0) after 1m0s
[21:08:39.403] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:20:0)
[21:08:39.403] Timer tick, asking Buildbucket for the build status
[21:08:39.496] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:39.496] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:21:0) after 1m0s
[21:09:39.535] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:21:0)
[21:09:39.535] Timer tick, asking Buildbucket for the build status
[21:09:39.568] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:39.568] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:22:0) after 1m0s
[21:10:39.603] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:22:0)
[21:10:39.603] Timer tick, asking Buildbucket for the build status
[21:10:40.240] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:40.240] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:23:0) after 1m0s
[21:11:40.263] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:23:0)
[21:11:40.263] Timer tick, asking Buildbucket for the build status
[21:11:40.618] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:40.618] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:24:0) after 1m0s
[21:12:40.641] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:24:0)
[21:12:40.641] Timer tick, asking Buildbucket for the build status
[21:12:40.727] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:40.727] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:25:0) after 1m0s
[21:13:40.748] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:25:0)
[21:13:40.748] Timer tick, asking Buildbucket for the build status
[21:13:40.789] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:40.789] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:26:0) after 1m0s
[21:14:40.816] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:26:0)
[21:14:40.816] Timer tick, asking Buildbucket for the build status
[21:14:40.907] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:40.907] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:27:0) after 1m0s
[21:15:40.963] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:27:0)
[21:15:40.963] Timer tick, asking Buildbucket for the build status
[21:15:41.124] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:41.124] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:28:0) after 1m0s
[21:16:41.160] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:28:0)
[21:16:41.160] Timer tick, asking Buildbucket for the build status
[21:16:41.207] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:41.207] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:29:0) after 1m0s
[21:17:41.243] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:29:0)
[21:17:41.243] Timer tick, asking Buildbucket for the build status
[21:17:41.335] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:41.335] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:30:0) after 1m0s
[21:18:41.357] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:30:0)
[21:18:41.357] Timer tick, asking Buildbucket for the build status
[21:18:41.494] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:41.494] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:31:0) after 1m0s
[21:19:41.556] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:31:0)
[21:19:41.556] Timer tick, asking Buildbucket for the build status
[21:19:41.843] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:41.843] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:32:0) after 1m0s
[21:20:41.869] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:32:0)
[21:20:41.869] Timer tick, asking Buildbucket for the build status
[21:20:42.123] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:42.123] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:33:0) after 1m0s
[21:21:42.161] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:33:0)
[21:21:42.161] Timer tick, asking Buildbucket for the build status
[21:21:42.264] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:42.265] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:34:0) after 1m0s
[21:22:42.286] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:34:0)
[21:22:42.286] Timer tick, asking Buildbucket for the build status
[21:22:42.329] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:42.329] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:35:0) after 1m0s
[21:23:42.350] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:35:0)
[21:23:42.350] Timer tick, asking Buildbucket for the build status
[21:23:42.475] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:42.475] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:36:0) after 1m0s
[21:24:42.528] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:36:0)
[21:24:42.528] Timer tick, asking Buildbucket for the build status
[21:24:42.576] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:42.576] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:37:0) after 1m0s
[21:25:42.700] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:37:0)
[21:25:42.700] Timer tick, asking Buildbucket for the build status
[21:25:42.745] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:42.745] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:38:0) after 1m0s
[21:26:42.899] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:38:0)
[21:26:42.899] Timer tick, asking Buildbucket for the build status
[21:26:42.991] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:42.991] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:39:0) after 1m0s
[21:27:43.027] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:39:0)
[21:27:43.027] Timer tick, asking Buildbucket for the build status
[21:27:43.065] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:43.065] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:40:0) after 1m0s
[21:28:43.086] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:40:0)
[21:28:43.086] Timer tick, asking Buildbucket for the build status
[21:28:43.401] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:43.401] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:41:0) after 1m0s
[21:29:43.425] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:41:0)
[21:29:43.426] Timer tick, asking Buildbucket for the build status
[21:29:43.475] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:43.475] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:42:0) after 1m0s
[21:30:43.495] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:42:0)
[21:30:43.495] Timer tick, asking Buildbucket for the build status
[21:30:43.616] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:43.616] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:43:0) after 1m0s
[21:31:43.638] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:43:0)
[21:31:43.638] Timer tick, asking Buildbucket for the build status
[21:31:43.954] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:43.954] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:44:0) after 1m0s
[21:32:43.978] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:44:0)
[21:32:43.978] Timer tick, asking Buildbucket for the build status
[21:32:44.091] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:44.091] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:45:0) after 1m0s
[21:33:44.114] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:45:0)
[21:33:44.114] Timer tick, asking Buildbucket for the build status
[21:33:44.180] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:44.180] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:46:0) after 1m0s
[21:34:44.201] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:46:0)
[21:34:44.201] Timer tick, asking Buildbucket for the build status
[21:34:44.243] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:44.243] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:47:0) after 1m0s
[21:35:44.265] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:47:0)
[21:35:44.265] Timer tick, asking Buildbucket for the build status
[21:35:44.440] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:44.440] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:48:0) after 1m0s
[21:36:44.480] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:48:0)
[21:36:44.480] Timer tick, asking Buildbucket for the build status
[21:36:44.561] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:44.561] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:49:0) after 1m0s
[21:37:44.665] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:49:0)
[21:37:44.665] Timer tick, asking Buildbucket for the build status
[21:37:44.743] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:44.743] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:50:0) after 1m0s
[21:38:44.769] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:50:0)
[21:38:44.769] Timer tick, asking Buildbucket for the build status
[21:38:44.807] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:44.807] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:51:0) after 1m0s
[21:39:44.830] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:51:0)
[21:39:44.830] Timer tick, asking Buildbucket for the build status
[21:39:44.908] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:44.908] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:52:0) after 1m0s
[21:40:44.932] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:52:0)
[21:40:44.932] Timer tick, asking Buildbucket for the build status
[21:40:44.991] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:44.991] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:53:0) after 1m0s
[21:41:45.019] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:53:0)
[21:41:45.019] Timer tick, asking Buildbucket for the build status
[21:41:45.079] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:45.079] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:54:0) after 1m0s
[21:42:45.103] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:54:0)
[21:42:45.103] Timer tick, asking Buildbucket for the build status
[21:42:45.175] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:45.175] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:55:0) after 1m0s
[21:43:45.196] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:55:0)
[21:43:45.196] Timer tick, asking Buildbucket for the build status
[21:43:45.273] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:45.273] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:56:0) after 1m0s
[21:44:45.313] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:56:0)
[21:44:45.313] Timer tick, asking Buildbucket for the build status
[21:44:45.359] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:45.359] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:57:0) after 1m0s
[21:45:45.386] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:57:0)
[21:45:45.386] Timer tick, asking Buildbucket for the build status
[21:45:45.430] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:45.430] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:58:0) after 1m0s
[21:46:45.457] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:58:0)
[21:46:45.457] Timer tick, asking Buildbucket for the build status
[21:46:45.546] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:45.546] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:59:0) after 1m0s
[21:47:45.566] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:59:0)
[21:47:45.567] Timer tick, asking Buildbucket for the build status
[21:47:45.614] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:45.614] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:60:0) after 1m0s
[21:48:45.667] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:60:0)
[21:48:45.667] Timer tick, asking Buildbucket for the build status
[21:48:45.763] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:45.763] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:61:0) after 1m0s
[21:49:45.791] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:61:0)
[21:49:45.791] Timer tick, asking Buildbucket for the build status
[21:49:45.860] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:45.860] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:62:0) after 1m0s
[21:50:45.885] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:62:0)
[21:50:45.885] Timer tick, asking Buildbucket for the build status
[21:50:45.983] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:45.983] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:63:0) after 1m0s
[21:51:46.008] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:63:0)
[21:51:46.008] Timer tick, asking Buildbucket for the build status
[21:51:46.069] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:46.069] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:64:0) after 1m0s
[21:52:46.090] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:64:0)
[21:52:46.090] Timer tick, asking Buildbucket for the build status
[21:52:46.216] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:46.216] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:65:0) after 1m0s
[21:53:46.250] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:65:0)
[21:53:46.250] Timer tick, asking Buildbucket for the build status
[21:53:46.299] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:46.299] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:66:0) after 1m0s
[21:54:46.360] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:66:0)
[21:54:46.360] Timer tick, asking Buildbucket for the build status
[21:54:46.418] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:46.418] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:67:0) after 1m0s
[21:55:46.472] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:67:0)
[21:55:46.472] Timer tick, asking Buildbucket for the build status
[21:55:46.636] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:46.636] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:68:0) after 1m0s
[21:56:46.664] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:68:0)
[21:56:46.664] Timer tick, asking Buildbucket for the build status
[21:56:46.804] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:46.804] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:69:0) after 1m0s
[21:57:46.829] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:69:0)
[21:57:46.829] Timer tick, asking Buildbucket for the build status
[21:57:46.928] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:46.928] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:70:0) after 1m0s
[21:58:46.965] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:70:0)
[21:58:46.965] Timer tick, asking Buildbucket for the build status
[21:58:47.059] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:47.059] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:71:0) after 1m0s
[21:59:47.084] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:71:0)
[21:59:47.084] Timer tick, asking Buildbucket for the build status
[21:59:47.129] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:47.129] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:72:0) after 1m0s
[22:00:47.154] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:72:0)
[22:00:47.154] Timer tick, asking Buildbucket for the build status
[22:00:47.220] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:47.220] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:73:0) after 1m0s
[22:01:47.258] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:73:0)
[22:01:47.258] Timer tick, asking Buildbucket for the build status
[22:01:47.327] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:47.327] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:74:0) after 1m0s
[22:02:47.360] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:74:0)
[22:02:47.360] Timer tick, asking Buildbucket for the build status
[22:02:47.465] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:47.465] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:75:0) after 1m0s
[22:03:47.499] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:75:0)
[22:03:47.499] Timer tick, asking Buildbucket for the build status
[22:03:47.549] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:47.549] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:76:0) after 1m0s
[22:04:47.573] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:76:0)
[22:04:47.573] Timer tick, asking Buildbucket for the build status
[22:04:47.617] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:47.617] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:77:0) after 1m0s
[22:05:47.708] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:77:0)
[22:05:47.708] Timer tick, asking Buildbucket for the build status
[22:05:47.749] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:47.749] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:78:0) after 1m0s
[22:06:47.805] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:78:0)
[22:06:47.805] Timer tick, asking Buildbucket for the build status
[22:06:47.946] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:47.946] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:79:0) after 1m0s
[22:07:48.079] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:79:0)
[22:07:48.079] Timer tick, asking Buildbucket for the build status
[22:07:48.124] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:48.124] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:80:0) after 1m0s
[22:08:48.183] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:80:0)
[22:08:48.183] Timer tick, asking Buildbucket for the build status
[22:08:48.281] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:48.281] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:81:0) after 1m0s
[22:09:48.306] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:81:0)
[22:09:48.306] Timer tick, asking Buildbucket for the build status
[22:09:48.554] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:48.554] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:82:0) after 1m0s
[22:10:48.578] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:82:0)
[22:10:48.578] Timer tick, asking Buildbucket for the build status
[22:10:48.637] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:48.637] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:83:0) after 1m0s
[22:11:48.665] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:83:0)
[22:11:48.665] Timer tick, asking Buildbucket for the build status
[22:11:48.780] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:48.780] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:84:0) after 1m0s
[22:12:48.803] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:84:0)
[22:12:48.803] Timer tick, asking Buildbucket for the build status
[22:12:48.973] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:48.973] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:85:0) after 1m0s
[22:13:49.017] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:85:0)
[22:13:49.017] Timer tick, asking Buildbucket for the build status
[22:13:49.056] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:49.056] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:86:0) after 1m0s
[22:14:49.100] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:86:0)
[22:14:49.100] Timer tick, asking Buildbucket for the build status
[22:14:49.273] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:49.273] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:87:0) after 1m0s
[22:15:49.297] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:87:0)
[22:15:49.297] Timer tick, asking Buildbucket for the build status
[22:15:49.480] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:49.480] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:88:0) after 1m0s
[22:16:49.509] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:88:0)
[22:16:49.509] Timer tick, asking Buildbucket for the build status
[22:16:49.591] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:49.591] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:89:0) after 1m0s
[22:17:49.693] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:89:0)
[22:17:49.693] Timer tick, asking Buildbucket for the build status
[22:17:49.840] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:49.840] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:90:0) after 1m0s
[22:18:49.930] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:90:0)
[22:18:49.930] Timer tick, asking Buildbucket for the build status
[22:18:49.963] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:49.963] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:91:0) after 1m0s
[22:19:49.990] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:91:0)
[22:19:49.990] Timer tick, asking Buildbucket for the build status
[22:19:50.236] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:50.236] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:92:0) after 1m0s
[22:20:50.259] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:92:0)
[22:20:50.259] Timer tick, asking Buildbucket for the build status
[22:20:50.295] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:50.295] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:93:0) after 1m0s
[22:21:50.335] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:93:0)
[22:21:50.335] Timer tick, asking Buildbucket for the build status
[22:21:50.376] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:50.376] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:94:0) after 1m0s
[22:22:50.415] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:94:0)
[22:22:50.415] Timer tick, asking Buildbucket for the build status
[22:22:50.513] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:50.513] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:95:0) after 1m0s
[22:23:50.582] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:95:0)
[22:23:50.582] Timer tick, asking Buildbucket for the build status
[22:23:50.645] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:50.645] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:96:0) after 1m0s
[22:24:50.671] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:96:0)
[22:24:50.671] Timer tick, asking Buildbucket for the build status
[22:24:50.709] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:50.709] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:97:0) after 1m0s
[22:25:50.735] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:97:0)
[22:25:50.735] Timer tick, asking Buildbucket for the build status
[22:25:50.942] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:50.942] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:98:0) after 1m0s
[22:26:50.965] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:98:0)
[22:26:50.965] Timer tick, asking Buildbucket for the build status
[22:26:51.018] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:51.018] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:99:0) after 1m0s
[22:27:51.066] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:99:0)
[22:27:51.066] Timer tick, asking Buildbucket for the build status
[22:27:51.151] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:51.151] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:100:0) after 1m0s
[22:28:51.176] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:100:0)
[22:28:51.176] Timer tick, asking Buildbucket for the build status
[22:28:51.221] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:51.221] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:101:0) after 1m0s
[22:29:51.337] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:101:0)
[22:29:51.337] Timer tick, asking Buildbucket for the build status
[22:29:52.090] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:52.090] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:102:0) after 1m0s
[22:30:52.123] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:102:0)
[22:30:52.123] Timer tick, asking Buildbucket for the build status
[22:30:52.481] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:52.481] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:103:0) after 1m0s
[22:31:52.504] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:103:0)
[22:31:52.504] Timer tick, asking Buildbucket for the build status
[22:31:52.651] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:52.651] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:104:0) after 1m0s
[22:32:52.679] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:104:0)
[22:32:52.679] Timer tick, asking Buildbucket for the build status
[22:32:52.763] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:52.763] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:105:0) after 1m0s
[22:33:52.828] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:105:0)
[22:33:52.829] Timer tick, asking Buildbucket for the build status
[22:33:52.896] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:52.896] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:106:0) after 1m0s
[22:34:52.920] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:106:0)
[22:34:52.920] Timer tick, asking Buildbucket for the build status
[22:34:52.980] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:52.980] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:107:0) after 1m0s
[22:35:53.004] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:107:0)
[22:35:53.004] Timer tick, asking Buildbucket for the build status
[22:35:53.169] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:53.170] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:108:0) after 1m0s
[22:36:53.229] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:108:0)
[22:36:53.229] Timer tick, asking Buildbucket for the build status
[22:36:53.273] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:53.273] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:109:0) after 1m0s
[22:37:53.296] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:109:0)
[22:37:53.296] Timer tick, asking Buildbucket for the build status
[22:37:53.455] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:53.455] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:110:0) after 1m0s
[22:38:53.479] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:110:0)
[22:38:53.479] Timer tick, asking Buildbucket for the build status
[22:38:53.525] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:53.525] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:111:0) after 1m0s
[22:39:53.555] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:111:0)
[22:39:53.555] Timer tick, asking Buildbucket for the build status
[22:39:53.610] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:53.610] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:112:0) after 1m0s
[22:40:53.682] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:112:0)
[22:40:53.682] Timer tick, asking Buildbucket for the build status
[22:40:53.774] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:53.774] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:113:0) after 1m0s
[22:41:53.804] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:113:0)
[22:41:53.804] Timer tick, asking Buildbucket for the build status
[22:41:53.911] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:53.911] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:114:0) after 1m0s
[22:42:53.946] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:114:0)
[22:42:53.946] Timer tick, asking Buildbucket for the build status
[22:42:54.311] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:54.311] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:115:0) after 1m0s
[22:43:54.361] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:115:0)
[22:43:54.361] Timer tick, asking Buildbucket for the build status
[22:43:54.423] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:54.423] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:116:0) after 1m0s
[22:44:54.481] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:116:0)
[22:44:54.481] Timer tick, asking Buildbucket for the build status
[22:44:54.520] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:54.520] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:117:0) after 1m0s
[22:45:54.585] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:117:0)
[22:45:54.585] Timer tick, asking Buildbucket for the build status
[22:45:54.649] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:54.649] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:118:0) after 1m0s
[22:46:54.674] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:118:0)
[22:46:54.674] Timer tick, asking Buildbucket for the build status
[22:46:54.715] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:54.715] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:119:0) after 1m0s
[22:47:54.747] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:119:0)
[22:47:54.747] Timer tick, asking Buildbucket for the build status
[22:47:54.815] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:54.815] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:120:0) after 1m0s
[22:48:54.852] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:120:0)
[22:48:54.852] Timer tick, asking Buildbucket for the build status
[22:48:54.893] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:54.893] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:121:0) after 1m0s
[22:49:54.922] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:121:0)
[22:49:54.922] Timer tick, asking Buildbucket for the build status
[22:49:54.999] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:54.999] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:122:0) after 1m0s
[22:50:55.049] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:122:0)
[22:50:55.049] Timer tick, asking Buildbucket for the build status
[22:50:55.113] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:55.113] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:123:0) after 1m0s
[22:51:55.137] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:123:0)
[22:51:55.137] Timer tick, asking Buildbucket for the build status
[22:51:55.229] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:55.229] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:124:0) after 1m0s
[22:52:55.268] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:124:0)
[22:52:55.268] Timer tick, asking Buildbucket for the build status
[22:52:55.313] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:55.313] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:125:0) after 1m0s
[22:53:55.383] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:125:0)
[22:53:55.383] Timer tick, asking Buildbucket for the build status
[22:53:55.441] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:55.441] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:126:0) after 1m0s
[22:54:55.473] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:126:0)
[22:54:55.473] Timer tick, asking Buildbucket for the build status
[22:54:55.614] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:55.614] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:127:0) after 1m0s
[22:55:55.654] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:127:0)
[22:55:55.654] Timer tick, asking Buildbucket for the build status
[22:55:55.701] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:55.701] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:128:0) after 1m0s
[22:56:55.738] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:128:0)
[22:56:55.738] Timer tick, asking Buildbucket for the build status
[22:56:55.788] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:55.788] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:129:0) after 1m0s
[22:57:55.815] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:129:0)
[22:57:55.815] Timer tick, asking Buildbucket for the build status
[22:57:55.955] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:55.955] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:130:0) after 1m0s
[22:58:55.991] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:130:0)
[22:58:55.991] Timer tick, asking Buildbucket for the build status
[22:58:56.029] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:56.029] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:131:0) after 1m0s
[22:59:56.057] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:131:0)
[22:59:56.057] Timer tick, asking Buildbucket for the build status
[22:59:56.229] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:56.229] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:132:0) after 1m0s
[23:00:56.255] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:132:0)
[23:00:56.255] Timer tick, asking Buildbucket for the build status
[23:00:56.332] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:56.332] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:133:0) after 1m0s
[23:01:56.358] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:133:0)
[23:01:56.358] Timer tick, asking Buildbucket for the build status
[23:01:56.394] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:56.394] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:134:0) after 1m0s
[23:02:56.421] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:134:0)
[23:02:56.421] Timer tick, asking Buildbucket for the build status
[23:02:56.698] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:56.698] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:135:0) after 1m0s
[23:03:56.791] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:135:0)
[23:03:56.791] Timer tick, asking Buildbucket for the build status
[23:03:56.887] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:56.887] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:136:0) after 1m0s
[23:04:56.915] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:136:0)
[23:04:56.915] Timer tick, asking Buildbucket for the build status
[23:04:57.093] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:57.093] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:137:0) after 1m0s
[23:05:57.116] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:137:0)
[23:05:57.116] Timer tick, asking Buildbucket for the build status
[23:05:57.285] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:57.285] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:138:0) after 1m0s
[23:06:57.315] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:138:0)
[23:06:57.315] Timer tick, asking Buildbucket for the build status
[23:06:57.491] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:57.491] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:139:0) after 1m0s
[23:07:57.535] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:139:0)
[23:07:57.535] Timer tick, asking Buildbucket for the build status
[23:07:57.603] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:57.603] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:140:0) after 1m0s
[23:08:57.652] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:140:0)
[23:08:57.652] Timer tick, asking Buildbucket for the build status
[23:08:57.891] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:57.891] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:141:0) after 1m0s
[23:09:57.920] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:141:0)
[23:09:57.920] Timer tick, asking Buildbucket for the build status
[23:09:57.960] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:57.960] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:142:0) after 1m0s
[23:10:57.988] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:142:0)
[23:10:57.988] Timer tick, asking Buildbucket for the build status
[23:10:58.049] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:58.050] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:143:0) after 1m0s
[23:11:58.098] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:143:0)
[23:11:58.098] Timer tick, asking Buildbucket for the build status
[23:11:58.165] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:58.165] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:144:0) after 1m0s
[23:12:58.263] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:144:0)
[23:12:58.263] Timer tick, asking Buildbucket for the build status
[23:12:58.385] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:58.385] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:145:0) after 1m0s
[23:13:58.413] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:145:0)
[23:13:58.413] Timer tick, asking Buildbucket for the build status
[23:13:58.578] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:58.578] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:146:0) after 1m0s
[23:14:58.602] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:146:0)
[23:14:58.602] Timer tick, asking Buildbucket for the build status
[23:14:58.768] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:58.768] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:147:0) after 1m0s
[23:15:58.862] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:147:0)
[23:15:58.862] Timer tick, asking Buildbucket for the build status
[23:15:58.942] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:58.942] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:148:0) after 1m0s
[23:16:58.972] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:148:0)
[23:16:58.972] Timer tick, asking Buildbucket for the build status
[23:16:59.019] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:59.019] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:149:0) after 1m0s
[23:17:59.047] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:149:0)
[23:17:59.047] Timer tick, asking Buildbucket for the build status
[23:17:59.111] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:59.111] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:150:0) after 1m0s
[23:18:59.158] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:150:0)
[23:18:59.158] Timer tick, asking Buildbucket for the build status
[23:18:59.224] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:59.224] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:151:0) after 1m0s
[23:19:59.254] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:151:0)
[23:19:59.254] Timer tick, asking Buildbucket for the build status
[23:19:59.415] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:59.415] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:152:0) after 1m0s
[23:20:59.442] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:152:0)
[23:20:59.442] Timer tick, asking Buildbucket for the build status
[23:20:59.567] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:59.567] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:153:0) after 1m0s
[23:21:59.598] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:153:0)
[23:21:59.598] Timer tick, asking Buildbucket for the build status
[23:21:59.664] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:59.664] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:154:0) after 1m0s
[23:22:59.690] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:154:0)
[23:22:59.690] Timer tick, asking Buildbucket for the build status
[23:22:59.905] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:59.905] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:155:0) after 1m0s
[23:23:59.953] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:155:0)
[23:23:59.953] Timer tick, asking Buildbucket for the build status
[23:24:00.025] Build 8936100972781692592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:00.025] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101531034952891840:156:0) after 1m0s
[23:24:52.536] Received PubSub notification, asking Buildbucket for the build status
[23:24:52.585] Build 8936100972781692592: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[23:24:52.585] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1536276291316108",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1536267035653955",
"failure_reason": "BUILD_FAILURE",
"id": "8936100972781692592",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"17cf8a858dd5b0f968e31b6817f487d5ccc34eaa\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"ui\": {\"info\": \"Failure Second build\"}, \"build_run_result\": {\"infraFailure\": null, \"recipeResult\": {\"failure\": {\"failure\": {\"step\": \"Second build\"}, \"humanReason\": \"Step('Second build') failed with return_code 1\"}}, \"recipeExitCode\": {\"value\": 1}, \"recipe\": {\"name\": \"swarming/deterministic_build\", \"repository\": \"\", \"revision\": \"\"}, \"annotationUrl\": \"logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8936100972781692592/+/annotations\"}, \"swarming\": {\"bot_dimensions\": {\"machine_type\": [\"n1-standard-8\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"], \"locale\": [\"en_US.cp1252\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"builder\": [\"Windows deterministic\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\"], \"python\": [\"2.7.6\"], \"server_version\": [\"3768-9a74523\"], \"image\": [\"chrome-win10-1703-2018-04-20-61acaf8983f\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"integrity\": [\"high\"], \"id\": [\"swarm2173-c4\"], \"pool\": [\"luci.chromium.ci\"], \"caches\": [\"git\", \"goma_v2\", \"vpython\"]}}, \"properties\": {\"got_nacl_revision\": \"cb6ec4b1f91af259cbdfd1bd22bb98ed5551955e\", \"got_swarming_client_revision\": \"486c9b53c4d54dd4b95bb6ce0e31160e600dfc11\", \"got_revision\": \"17cf8a858dd5b0f968e31b6817f487d5ccc34eaa\", \"recipe\": \"swarming/deterministic_build\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#24595}\", \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\swarming\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\swarming\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"got_revision_cp\": \"refs/heads/master@{#589286}\", \"branch\": \"refs/heads/master\", \"revision\": \"17cf8a858dd5b0f968e31b6817f487d5ccc34eaa\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"bb081a62dc30a49fa77cfdbb4ae470c5137fa547\", \"mastername\": \"chromium.fyi\", \"got_angle_revision\": \"4d42ef39658b9808f4e18c077b10202a7b50b33b\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1536267035653955, \"tags\": [\"builder:Windows deterministic\", \"buildset:commit/git/17cf8a858dd5b0f968e31b6817f487d5ccc34eaa\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/17cf8a858dd5b0f968e31b6817f487d5ccc34eaa\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9101531034952891840\", \"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\": \"8936100972781692592\"}}, \"got_v8_revision\": \"41db184149979896ef2266e1c6173a8d5b594242\", \"got_v8_revision_cp\": \"refs/heads/7.1.47@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 9999, \"swarm_hashes\": {\"shell_dialogs_unittests\": \"6914ea53f604f029c875efdc8ba08d4f3ad9d2f9\", \"notification_helper_unittests\": \"2575d24e40050ef9df9fe6925a1213940b868c15\", \"gpu_perftests\": \"ae189ff918fdc17a0d8a314f95be3d79c68d2b3e\", \"vr_common_unittests\": \"c44df826bccbb011ae4e706587570ade69671a89\", \"telemetry_unittests\": \"17e272b3faa822e325c7bf79112f55467fb06a51\", \"load_library_perf_tests\": \"f188c4940c9966f135a46fa02eaff84370019d3e\", \"devtools_closure_compile\": \"3c92b1019fd55fb6fccee350cebd46656910d3ed\", \"chromedriver_py_tests\": \"4bb151398e6a9184f83a180762cff1598fe5a80a\", \"mojo_core_unittests\": \"1223607bd8183d91e9434e55f16dbdb000e6ad4e\", \"install_static_unittests\": \"57959fab9b6104039622f7577703f9e1ae354f37\", \"leveldb_service_unittests\": \"f76bfcb3e3c5d1e6a2a499e17b59580144131307\", \"mini_installer_tests\": \"bfb0add5f17931cb1306d5a3b7f16a670a62b6fa\", \"jingle_unittests\": \"a7f5171ca2fc17448ced574e11c23862627459d1\", \"sbox_integration_tests\": \"270838ddf3033304a95c8a639ed866729958ed81\", \"angle_unittests\": \"940ed8d3a5dfe044d24c12cd20eb2416bae0208c\", \"cast_unittests\": \"257a28ebc09f8bbf70e5c9fd692d08157012133b\", \"cc_unittests\": \"594ee0e0381d0c79ebdb1198d55240db54cffe9c\", \"blink_common_unittests\": \"6b76a984a886259f7bd053a60708a4bb3d685690\", \"crashpad_tests\": \"e1a5a7d9675118e571642917bafde266b3784321\", \"telemetry_perf_tests_without_chrome\": \"4509c0f34545f39d535e615f83e9d932e7f161d2\", \"metrics_python_tests\": \"a54ccbdcb57a0d4e104d4d9469fa880fb724183b\", \"message_center_unittests\": \"8126a1a7eec568107abdd87b719595167164848a\", \"ppapi_unittests\": \"1242f9f975fd2a4db5c55acab3355cf0fede7166\", \"extensions_unittests\": \"3b84fb0e9177e76aa375265b22f0eca2fbf880a8\", \"gl_unittests_ozone\": \"5b0667dc5a2159da0784cd33b52e3c5ce35bb7e5\", \"app_shell_unittests\": \"fff7a5175d3df698940455730926d967312cda3d\", \"chrome_app_unittests\": \"3889eef03e488f641fec69ce44e6597eed3b198f\", \"gl_unittests\": \"5b0667dc5a2159da0784cd33b52e3c5ce35bb7e5\", \"gcm_unit_tests\": \"174aafbf255d52e2ceac38eb2c8c7a4c4e04722a\", \"views_unittests\": \"75ccb5f7197f61a79c4fa69b7872b4ac2af00f1b\", \"content_shell_crash_test\": \"67c8e426484a6ec353788cc8fd1f68fa98bb0d73\", \"ui_base_unittests\": \"5067253a62b2b9afd932e48867b8aa53d4a2225f\", \"gles2_conform_test\": \"46f95d6726fa69a7fd69fb19afd0186a483e9cf8\", \"cronet_unittests\": \"9b72b31dec8f25b3158b3a500c50b6d0fa5e6f63\", \"performance_test_suite\": \"ac8529c2c2cde2ee51e9e41827e4ab19359c103d\", \"wm_unittests\": \"3ecccc6d6e43ad3887470d7a64c269b3a6b337b8\", \"boringssl_crypto_tests\": \"983a1f90354cdefc56d3b7842120a590f741091d\", \"gin_unittests\": \"26eec4f552f5c21e682dd0bf900625d1c6d22e42\", \"performance_browser_tests\": \"93cfc0d842a4f6a8d414ea34a70cc5c0a4c0896d\", \"extensions_browsertests\": \"7ae312df372eb1080bbde6b14df7355f981ecd18\", \"webkit_python_tests\": \"779c14a6654809f0643d1dc2ce55debd3f6eb6ad\", \"headless_unittests\": \"98e6663fc747bdd1960054d4d53f328646f03477\", \"webkit_layout_tests\": \"31ac4d8dd1972c86f0ffb239cbbcc8ee71c1e427\", \"vr_pixeltests\": \"69c4260b4627ddef49751189ca5b7bd57646f266\", \"setup_unittests\": \"67ba00b63217f0bd2c183e9cc915e7935af0c79c\", \"webkit_unit_tests\": \"8beb3de7ae8c99cbb8b613956a56102e0e3d9fa2\", \"content_unittests\": \"716d33c9ee96bbb78b9efa876c36515cca3d109d\", \"performance_webview_test_suite\": \"3145cadd84f961486299303972e797e6366ce7f2\", \"wtf_unittests\": \"e4dee62249e45c09bd388d08afa22c8ba744b869\", \"webkit_layout_tests_exparchive\": \"39b94c93c21b2b6b6328114f2b10b5ac1ba7b7b7\", \"chrome_cleaner_unittests\": \"3b7e0481538528233453ace5d4220d735deaeb9a\", \"traffic_annotation_auditor_unittests\": \"649659d08f021cef958529149d9635dc919e5123\", \"libjingle_xmpp_unittests\": \"658f2262c184632781df201b594842d8da152a05\", \"remoting_unittests\": \"42c07d7a1cef80a0efd240543f393e8ef7106a87\", \"vr_common_perftests\": \"bf16d34592153ed770df583b31dd37cd2cb6b21e\", \"filesystem_service_unittests\": \"8ccbdb5db0b19485c8211957e69a6236892c61b0\", \"chrome_elf_import_unittests\": \"54f38175b2221a035eefc97b7cbcb912b333ccf9\", \"installer_util_unittests\": \"312470abe2143ee0a974ce70ab4d212d2e3e7b8d\", \"gfx_unittests\": \"1e8478508ede2fc3af6a54c5652517973d1ff73e\", \"headless_browsertests\": \"40f54a66ff81433cb8fc2d5447ec1f1a00ecb29e\", \"capture_unittests\": \"7b774d2d50f7e0c8c6f2816906ddad9dc8b9d73c\", \"chrome_elf_unittests\": \"edd3ebd55854d1ce02cfa5ad9a19c21f045eed5a\", \"telemetry_gpu_unittests\": \"b7057bc691fd9095b1962f28c73e82bb1ac657db\", \"command_buffer_perftests\": \"9c8ec2b931b15f47c6c4bb4c135e4cb7e410caba\", \"media_unittests\": \"b7d2ea8910f4c030983ee73c00fd1749f82a5bcd\", \"chromedriver_unittests\": \"3bdbe386c7d3678bc00917a934fb5c4e5cdff5b7\", \"views_mus_interactive_ui_tests\": \"333ee036186c3bb06e6261c9d5d0ede86b6f8266\", \"storage_unittests\": \"81ea4213ff6457d77cca04ba37a9aeedb5ccca85\", \"media_service_unittests\": \"0ffdb0bc710651d10f7c8285847c000360f3dc96\", \"compositor_unittests\": \"5b62db484a298430a4b884f9d0913a2bc96da09f\", \"latency_unittests\": \"0e0de7f05fcdb5b47abe5a1fa9f67538d65f66d9\", \"video_decode_accelerator_unittest\": \"75d69c56b5a2b552ba7c4980b5994c75f4f1ca5a\", \"swiftshader_unittests\": \"20400645bc19f5190136e578ec92c888e7f60215\", \"telemetry_perf_unittests\": \"98c53458d1ed7b6f3c8cdc03d7d3e3a86f57b188\", \"media_blink_unittests\": \"f46da14fb2b64169db1d55c92d211784271259ff\", \"views_mus_unittests\": \"3ef53877e9e8d6f252acaa67d44aec4f623a98f2\", \"components_unittests\": \"6ff59a8e940a476c29a4ab2e122bfe557ed63338\", \"snapshot_unittests\": \"03ab03f5604db62df32d694d8924ed50cdde4426\", \"accessibility_unittests\": \"293c2ba1ee824131bed82f43da29f7478caf88ae\", \"net_perftests\": \"21e90458f31b956ea8c9e5135ea209d93556cfb4\", \"aura_unittests\": \"13e629eb982d8d4b50d5c31fb6ca8b56448f793f\", \"services_unittests\": \"f1b58018a990828fb13db91872d8ac1db89978b7\", \"components_perftests\": \"9833b45f543d0b148b17923d8287088c4271ccb6\", \"events_unittests\": \"0194c7574398994bcc587815451a4f7d5e417074\", \"tracing_perftests\": \"d6838e77e8ed679885127c564aa5ab6919ab2750\", \"chromedriver_replay_unittests\": \"e2ec5b5deadfbf1a0e00246e86e8f3e11e9cace0\", \"ui_touch_selection_unittests\": \"e985b58dd16005eb76e34c40ac97a4e718680af6\", \"cacheinvalidation_unittests\": \"094abaa38d2a6bc51e837a3b9303730793b956cc\", \"viz_unittests\": \"dc80e642179c552b0b2c88ccdbad84661e2fec39\", \"browser_tests\": \"90503938eae09e4b3c3bea2973a984ca4593f904\", \"net_unittests\": \"1e56ef88f60105cb2e19f9a2662b035d6e8a1673\", \"midi_unittests\": \"a4de8dee319762bd19c5e99ba72ec164039b4e63\", \"boringssl_ssl_tests\": \"59e7e23a2d117caba6f6100bcb6ac481d9345d7c\", \"sbox_validation_tests\": \"04caf537dff1cb3d156ffc78a14315692928ae72\", \"elevation_service_unittests\": \"900a3cd2200b784be5143a274ee387ec86040010\", \"gpu_unittests\": \"28c21d9cea650c3001737fbb0a0f19d4a9acc8e2\", \"native_theme_unittests\": \"c143787f9c23bb79dfaa61d8e4294b12c58ba822\", \"interactive_ui_tests\": \"de0c50e234b1f8a8ed7f6bb3ae1e6d9466428161\", \"mojo_unittests\": \"8fdee7218210fd33d8c9ccd3c4bede9b8fdaadc1\", \"url_unittests\": \"c4b384d3ca8e710a7e6bd16e995ae0c11bf0a628\", \"media_perftests\": \"4c26e50a88a00521c95b1a9eaf4a16d888ec3a10\", \"cronet_tests\": \"2ae4e01e38195dd3ca823d4de479678265fac2b5\", \"google_apis_unittests\": \"a9f78b777a74ab7f9f02fc26f8c92552489e8827\", \"telemetry_gpu_integration_test\": \"3428024c7131d4d75bb5e9b934a0ce71b39bceb4\", \"blink_heap_unittests\": \"cb2431ff8176206b054d7f32a1ac9c3e1e0b5c55\", \"base_unittests\": \"02614515ce30c841a0921c5c9168c7986d92e471\", \"sync_integration_tests\": \"dcfa6cb09f56a3e256df3b837782989c4565ea6b\", \"sql_unittests\": \"b85084822990c504f5745982678f8551d04d705e\", \"courgette_unittests\": \"2c4d0c00a58d163b607a175e231a9d5491df390d\", \"blink_fuzzer_unittests\": \"5f7e283ba529e2deb8d30a0541e0f8a6d11af4e1\", \"gl_tests\": \"1ab8ac34f47c46a535c3f018afb81ab0ee0f2fe5\", \"zucchini_unittests\": \"fce40f91faa80c75de0e8e1db60c903123c7431e\", \"ipc_tests\": \"a4b90ce6e356a666ed430bea9ff14e2fa8bebb30\", \"angle_end2end_tests\": \"5030f6f22b84bdeca6feab56b181050905c9213d\", \"blink_platform_unittests\": \"14f942107bb5c8ac0fe4a9237329b3a921789681\", \"components_browsertests\": \"8ea6da87672e10e49d32ef635755a1972d240f61\", \"views_perftests\": \"5bf97a1cda3e2a40418f1859b228680e00826cef\", \"angle_perftests\": \"13d159c2a14075ab08466260ce43970754f2a9d5\", \"display_unittests\": \"ef231f851c86237fa6bb37f7279a290008f09823\", \"sbox_unittests\": \"dcacdda34d26f055bfb317d48d98b8ee2d01f372\", \"service_manager_unittests\": \"036efdd1e0911048ac70a7a89c8d6b492fa5652e\", \"pdf_unittests\": \"86f9e8f2960e19c2c6420ea8733a2befbde270cb\", \"skia_unittests\": \"6c60c9d3ac0240f6a48fc0f3b310ed64211aa77f\", \"angle_white_box_tests\": \"dcb9e97f671b5406772009f041798a304bff4fed\", \"crypto_unittests\": \"0ac0d5ac09d2ee1e41c46d17c349458dfa99f977\", \"nacl_loader_unittests\": \"7726504e9b7f897cb2c84707070be36c3eb36dc7\", \"unit_tests\": \"0369e7c8224861e00812071a42df192a8566e92b\", \"content_browsertests\": \"1e222a2ea8684ff4e7dd173a4bc4f2bd1b7d62d9\", \"printing_unittests\": \"62d8e911353a0de2aeefd9dea1317284ad32e726\", \"device_unittests\": \"cbca4942cb366747bf57347249ebff5b536533f1\"}, \"path_config\": \"generic\", \"bot_id\": \"swarm2173-c4\", \"got_buildtools_revision\": \"2dff9c9c74e9d732e6fe57c84ef7fd044cc45d96\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1536267120210294",
"status": "COMPLETED",
"status_changed_ts": "1536276291995996",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/9999",
"builder:Windows deterministic",
"buildset:commit/git/17cf8a858dd5b0f968e31b6817f487d5ccc34eaa",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/17cf8a858dd5b0f968e31b6817f487d5ccc34eaa",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9101531034952891840",
"scheduler_job_id:chromium/Windows deterministic",
"swarming_dimension:builder:Windows deterministic",
"swarming_dimension:caches:builder_d795d6e07fe37a299fcfc0e872e48757ad306282f024125f803c0465bfd1eeae_v2",
"swarming_dimension:cores:8",
"swarming_dimension:cpu:x86-64",
"swarming_dimension:os:Windows-10",
"swarming_dimension:pool:luci.chromium.ci",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:build_address:luci.chromium.ci/Windows deterministic/9999",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8936100972781692592",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:ca2c7a741e04d0385e2bf62941ac932dc0521af3",
"swarming_tag:builder:Windows deterministic",
"swarming_tag:buildset:commit/git/17cf8a858dd5b0f968e31b6817f487d5ccc34eaa",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/17cf8a858dd5b0f968e31b6817f487d5ccc34eaa",
"swarming_tag:caches:builder_d795d6e07fe37a299fcfc0e872e48757ad306282f024125f803c0465bfd1eeae_v2",
"swarming_tag:cores:8",
"swarming_tag:cpu:x86-64",
"swarming_tag:gitiles_ref:refs/heads/master",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8936100972781692592/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Windows-10",
"swarming_tag:pool:luci.chromium.ci",
"swarming_tag:priority:30",
"swarming_tag:recipe_name:swarming/deterministic_build",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_tag:scheduler_invocation_id:9101531034952891840",
"swarming_tag:scheduler_job_id:chromium/Windows deterministic",
"swarming_tag:service_account:chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"swarming_tag:swarming.pool.template:skip",
"swarming_tag:swarming.pool.version:75d2c3b43d86be91c8a8b6a9bd4c64b49bc37fb1",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:3fc9782f7b04c310",
"user_agent:luci-scheduler"
],
"updated_ts": "1536276291997796",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/9999",
"utcnow_ts": "1536276292571124"
}
}
[23:24:52.585] Invocation finished in 2h34m18.38602692s with status FAILED