[02:53:46.322] New invocation is queued and will start shortly
[02:53:47.586] Starting the invocation (attempt 1)
[02:53:47.623] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:53:47.623] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:53:47.623] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9098337290500032464",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"b9d0776816b987172184af69d6de4a09def7dca6\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9098337290500032464",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/b9d0776816b987172184af69d6de4a09def7dca6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b9d0776816b987172184af69d6de4a09def7dca6",
"gitiles_ref:refs/heads/master"
]
}
[02:53:50.091] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1539312827746383",
"id": "8932907228291232064",
"lease_expiration_ts": "1539456828901864",
"lease_key": "8351461",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"b9d0776816b987172184af69d6de4a09def7dca6\"}}",
"project": "chromium",
"result_details_json": "null",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1539312829893581",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/10377",
"builder:Windows deterministic",
"buildset:commit/git/b9d0776816b987172184af69d6de4a09def7dca6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b9d0776816b987172184af69d6de4a09def7dca6",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9098337290500032464",
"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/10377",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8932907228291232064",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:4c790cbde9342069ed9b59b6709c8807b14f2913",
"swarming_tag:builder:Windows deterministic",
"swarming_tag:buildset:commit/git/b9d0776816b987172184af69d6de4a09def7dca6",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b9d0776816b987172184af69d6de4a09def7dca6",
"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/8932907228291232064/+/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:9098337290500032464",
"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:9d4e55f09f057cb6c6ed423e82fe5a8bae98ab2b",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:407f0348b604f110",
"user_agent:luci-scheduler"
],
"updated_ts": "1539312829893688",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/10377",
"utcnow_ts": "1539312830075974"
}
}
[02:53:50.091] Task URL: https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/10377
[02:53:50.091] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:2:0) after 1m0s
[02:54:01.121] Received PubSub notification, asking Buildbucket for the build status
[02:54:01.268] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:50.207] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:2:0)
[02:54:50.207] Timer tick, asking Buildbucket for the build status
[02:54:50.313] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:50.313] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:4:0) after 1m0s
[02:55:50.352] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:4:0)
[02:55:50.352] Timer tick, asking Buildbucket for the build status
[02:55:50.434] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:50.434] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:5:0) after 1m0s
[02:56:50.451] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:5:0)
[02:56:50.451] Timer tick, asking Buildbucket for the build status
[02:56:50.567] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:50.567] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:6:0) after 1m0s
[02:57:50.584] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:6:0)
[02:57:50.584] Timer tick, asking Buildbucket for the build status
[02:57:50.641] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:50.642] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:7:0) after 1m0s
[02:58:50.695] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:7:0)
[02:58:50.695] Timer tick, asking Buildbucket for the build status
[02:58:50.769] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:50.769] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:8:0) after 1m0s
[02:59:50.786] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:8:0)
[02:59:50.786] Timer tick, asking Buildbucket for the build status
[02:59:50.906] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:50.906] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:9:0) after 1m0s
[03:00:50.930] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:9:0)
[03:00:50.930] Timer tick, asking Buildbucket for the build status
[03:00:51.037] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:51.037] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:10:0) after 1m0s
[03:01:51.056] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:10:0)
[03:01:51.056] Timer tick, asking Buildbucket for the build status
[03:01:51.116] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:51.116] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:11:0) after 1m0s
[03:02:51.148] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:11:0)
[03:02:51.148] Timer tick, asking Buildbucket for the build status
[03:02:51.257] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:51.257] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:12:0) after 1m0s
[03:03:51.331] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:12:0)
[03:03:51.331] Timer tick, asking Buildbucket for the build status
[03:03:51.442] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:51.442] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:13:0) after 1m0s
[03:04:51.477] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:13:0)
[03:04:51.477] Timer tick, asking Buildbucket for the build status
[03:04:51.511] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:51.511] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:14:0) after 1m0s
[03:05:51.534] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:14:0)
[03:05:51.534] Timer tick, asking Buildbucket for the build status
[03:05:51.608] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:51.608] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:15:0) after 1m0s
[03:06:51.636] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:15:0)
[03:06:51.636] Timer tick, asking Buildbucket for the build status
[03:06:51.706] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:51.706] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:16:0) after 1m0s
[03:07:51.730] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:16:0)
[03:07:51.730] Timer tick, asking Buildbucket for the build status
[03:07:51.770] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:51.770] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:17:0) after 1m0s
[03:08:51.796] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:17:0)
[03:08:51.796] Timer tick, asking Buildbucket for the build status
[03:08:51.877] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:51.877] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:18:0) after 1m0s
[03:09:51.903] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:18:0)
[03:09:51.903] Timer tick, asking Buildbucket for the build status
[03:09:51.983] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:51.983] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:19:0) after 1m0s
[03:10:52.011] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:19:0)
[03:10:52.011] Timer tick, asking Buildbucket for the build status
[03:10:52.226] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:52.226] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:20:0) after 1m0s
[03:11:52.247] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:20:0)
[03:11:52.247] Timer tick, asking Buildbucket for the build status
[03:11:52.337] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:52.337] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:21:0) after 1m0s
[03:12:52.356] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:21:0)
[03:12:52.356] Timer tick, asking Buildbucket for the build status
[03:12:52.412] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:52.412] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:22:0) after 1m0s
[03:13:52.433] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:22:0)
[03:13:52.433] Timer tick, asking Buildbucket for the build status
[03:13:52.502] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:52.502] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:23:0) after 1m0s
[03:14:52.579] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:23:0)
[03:14:52.579] Timer tick, asking Buildbucket for the build status
[03:14:52.831] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:52.831] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:24:0) after 1m0s
[03:15:52.937] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:24:0)
[03:15:52.937] Timer tick, asking Buildbucket for the build status
[03:15:53.062] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:53.062] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:25:0) after 1m0s
[03:16:53.131] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:25:0)
[03:16:53.131] Timer tick, asking Buildbucket for the build status
[03:16:53.222] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:53.222] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:26:0) after 1m0s
[03:17:53.248] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:26:0)
[03:17:53.248] Timer tick, asking Buildbucket for the build status
[03:17:53.332] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:53.332] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:27:0) after 1m0s
[03:18:53.419] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:27:0)
[03:18:53.419] Timer tick, asking Buildbucket for the build status
[03:18:53.481] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:53.481] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:28:0) after 1m0s
[03:19:53.524] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:28:0)
[03:19:53.524] Timer tick, asking Buildbucket for the build status
[03:19:53.603] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:53.603] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:29:0) after 1m0s
[03:20:53.623] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:29:0)
[03:20:53.623] Timer tick, asking Buildbucket for the build status
[03:20:53.738] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:53.738] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:30:0) after 1m0s
[03:21:53.853] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:30:0)
[03:21:53.853] Timer tick, asking Buildbucket for the build status
[03:21:53.932] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:53.932] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:31:0) after 1m0s
[03:22:53.958] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:31:0)
[03:22:53.958] Timer tick, asking Buildbucket for the build status
[03:22:54.199] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:54.199] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:32:0) after 1m0s
[03:23:54.233] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:32:0)
[03:23:54.233] Timer tick, asking Buildbucket for the build status
[03:23:54.434] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:54.434] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:33:0) after 1m0s
[03:24:54.454] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:33:0)
[03:24:54.454] Timer tick, asking Buildbucket for the build status
[03:24:54.610] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:54.610] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:34:0) after 1m0s
[03:25:54.633] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:34:0)
[03:25:54.633] Timer tick, asking Buildbucket for the build status
[03:25:54.706] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:54.706] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:35:0) after 1m0s
[03:26:54.753] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:35:0)
[03:26:54.753] Timer tick, asking Buildbucket for the build status
[03:26:54.818] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:54.818] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:36:0) after 1m0s
[03:27:54.843] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:36:0)
[03:27:54.843] Timer tick, asking Buildbucket for the build status
[03:27:54.899] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:54.899] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:37:0) after 1m0s
[03:28:54.926] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:37:0)
[03:28:54.926] Timer tick, asking Buildbucket for the build status
[03:28:54.971] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:54.971] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:38:0) after 1m0s
[03:29:54.991] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:38:0)
[03:29:54.991] Timer tick, asking Buildbucket for the build status
[03:29:55.049] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:55.049] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:39:0) after 1m0s
[03:30:55.126] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:39:0)
[03:30:55.126] Timer tick, asking Buildbucket for the build status
[03:30:55.223] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:55.223] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:40:0) after 1m0s
[03:31:55.245] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:40:0)
[03:31:55.245] Timer tick, asking Buildbucket for the build status
[03:31:55.359] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:55.359] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:41:0) after 1m0s
[03:32:55.393] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:41:0)
[03:32:55.393] Timer tick, asking Buildbucket for the build status
[03:32:55.615] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:55.615] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:42:0) after 1m0s
[03:33:55.645] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:42:0)
[03:33:55.645] Timer tick, asking Buildbucket for the build status
[03:33:55.713] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:55.713] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:43:0) after 1m0s
[03:34:55.736] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:43:0)
[03:34:55.736] Timer tick, asking Buildbucket for the build status
[03:34:55.781] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:55.781] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:44:0) after 1m0s
[03:35:55.809] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:44:0)
[03:35:55.809] Timer tick, asking Buildbucket for the build status
[03:35:55.899] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:55.899] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:45:0) after 1m0s
[03:36:55.972] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:45:0)
[03:36:55.972] Timer tick, asking Buildbucket for the build status
[03:36:56.017] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:56.017] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:46:0) after 1m0s
[03:37:56.065] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:46:0)
[03:37:56.065] Timer tick, asking Buildbucket for the build status
[03:37:56.106] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:56.106] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:47:0) after 1m0s
[03:38:56.129] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:47:0)
[03:38:56.129] Timer tick, asking Buildbucket for the build status
[03:38:56.184] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:56.184] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:48:0) after 1m0s
[03:39:56.207] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:48:0)
[03:39:56.207] Timer tick, asking Buildbucket for the build status
[03:39:56.287] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:56.287] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:49:0) after 1m0s
[03:40:56.341] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:49:0)
[03:40:56.341] Timer tick, asking Buildbucket for the build status
[03:40:56.422] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:56.422] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:50:0) after 1m0s
[03:41:56.444] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:50:0)
[03:41:56.444] Timer tick, asking Buildbucket for the build status
[03:41:56.504] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:56.504] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:51:0) after 1m0s
[03:42:56.528] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:51:0)
[03:42:56.528] Timer tick, asking Buildbucket for the build status
[03:42:56.640] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:56.640] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:52:0) after 1m0s
[03:43:56.661] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:52:0)
[03:43:56.661] Timer tick, asking Buildbucket for the build status
[03:43:58.096] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:58.096] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:53:0) after 1m0s
[03:44:58.154] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:53:0)
[03:44:58.154] Timer tick, asking Buildbucket for the build status
[03:44:58.228] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:58.228] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:54:0) after 1m0s
[03:45:58.261] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:54:0)
[03:45:58.261] Timer tick, asking Buildbucket for the build status
[03:45:58.341] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:58.341] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:55:0) after 1m0s
[03:46:58.455] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:55:0)
[03:46:58.456] Timer tick, asking Buildbucket for the build status
[03:46:58.509] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:58.509] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:56:0) after 1m0s
[03:47:58.533] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:56:0)
[03:47:58.533] Timer tick, asking Buildbucket for the build status
[03:47:58.611] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:58.611] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:57:0) after 1m0s
[03:48:58.637] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:57:0)
[03:48:58.637] Timer tick, asking Buildbucket for the build status
[03:48:58.701] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:58.701] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:58:0) after 1m0s
[03:49:58.727] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:58:0)
[03:49:58.727] Timer tick, asking Buildbucket for the build status
[03:49:58.884] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:58.884] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:59:0) after 1m0s
[03:50:58.914] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:59:0)
[03:50:58.914] Timer tick, asking Buildbucket for the build status
[03:50:58.994] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:58.994] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:60:0) after 1m0s
[03:51:59.019] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:60:0)
[03:51:59.019] Timer tick, asking Buildbucket for the build status
[03:51:59.178] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:59.178] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:61:0) after 1m0s
[03:52:59.215] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:61:0)
[03:52:59.215] Timer tick, asking Buildbucket for the build status
[03:52:59.310] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:59.310] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:62:0) after 1m0s
[03:53:59.331] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:62:0)
[03:53:59.331] Timer tick, asking Buildbucket for the build status
[03:53:59.522] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:59.522] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:63:0) after 1m0s
[03:54:59.548] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:63:0)
[03:54:59.548] Timer tick, asking Buildbucket for the build status
[03:54:59.761] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:59.761] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:64:0) after 1m0s
[03:55:59.804] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:64:0)
[03:55:59.804] Timer tick, asking Buildbucket for the build status
[03:55:59.878] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:59.878] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:65:0) after 1m0s
[03:56:59.901] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:65:0)
[03:56:59.901] Timer tick, asking Buildbucket for the build status
[03:57:00.014] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:00.014] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:66:0) after 1m0s
[03:58:00.168] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:66:0)
[03:58:00.168] Timer tick, asking Buildbucket for the build status
[03:58:00.268] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:00.268] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:67:0) after 1m0s
[03:59:00.293] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:67:0)
[03:59:00.293] Timer tick, asking Buildbucket for the build status
[03:59:00.365] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:00.365] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:68:0) after 1m0s
[04:00:00.434] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:68:0)
[04:00:00.434] Timer tick, asking Buildbucket for the build status
[04:00:00.493] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:00.493] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:69:0) after 1m0s
[04:01:00.561] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:69:0)
[04:01:00.561] Timer tick, asking Buildbucket for the build status
[04:01:00.652] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:00.652] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:70:0) after 1m0s
[04:02:00.681] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:70:0)
[04:02:00.681] Timer tick, asking Buildbucket for the build status
[04:02:00.779] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:00.779] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:71:0) after 1m0s
[04:03:00.802] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:71:0)
[04:03:00.802] Timer tick, asking Buildbucket for the build status
[04:03:00.875] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:00.875] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:72:0) after 1m0s
[04:04:00.905] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:72:0)
[04:04:00.905] Timer tick, asking Buildbucket for the build status
[04:04:00.952] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:00.952] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:73:0) after 1m0s
[04:05:00.990] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:73:0)
[04:05:00.990] Timer tick, asking Buildbucket for the build status
[04:05:01.037] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:01.037] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:74:0) after 1m0s
[04:06:01.062] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:74:0)
[04:06:01.062] Timer tick, asking Buildbucket for the build status
[04:06:01.108] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:01.108] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:75:0) after 1m0s
[04:07:01.132] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:75:0)
[04:07:01.132] Timer tick, asking Buildbucket for the build status
[04:07:02.616] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:02.616] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:76:0) after 1m0s
[04:08:02.690] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:76:0)
[04:08:02.690] Timer tick, asking Buildbucket for the build status
[04:08:02.873] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:02.873] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:77:0) after 1m0s
[04:09:02.993] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:77:0)
[04:09:02.993] Timer tick, asking Buildbucket for the build status
[04:09:03.169] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:03.169] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:78:0) after 1m0s
[04:10:03.191] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:78:0)
[04:10:03.191] Timer tick, asking Buildbucket for the build status
[04:10:03.474] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:03.474] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:79:0) after 1m0s
[04:11:03.494] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:79:0)
[04:11:03.494] Timer tick, asking Buildbucket for the build status
[04:11:03.532] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:03.532] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:80:0) after 1m0s
[04:12:03.554] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:80:0)
[04:12:03.554] Timer tick, asking Buildbucket for the build status
[04:12:03.606] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:03.606] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:81:0) after 1m0s
[04:13:03.693] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:81:0)
[04:13:03.693] Timer tick, asking Buildbucket for the build status
[04:13:03.866] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:03.866] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:82:0) after 1m0s
[04:14:03.888] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:82:0)
[04:14:03.888] Timer tick, asking Buildbucket for the build status
[04:14:03.958] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:03.958] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:83:0) after 1m0s
[04:15:04.087] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:83:0)
[04:15:04.087] Timer tick, asking Buildbucket for the build status
[04:15:04.221] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:04.221] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:84:0) after 1m0s
[04:16:04.246] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:84:0)
[04:16:04.246] Timer tick, asking Buildbucket for the build status
[04:16:04.327] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:04.327] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:85:0) after 1m0s
[04:17:04.438] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:85:0)
[04:17:04.438] Timer tick, asking Buildbucket for the build status
[04:17:04.492] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:04.492] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:86:0) after 1m0s
[04:18:04.552] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:86:0)
[04:18:04.552] Timer tick, asking Buildbucket for the build status
[04:18:04.732] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:04.732] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:87:0) after 1m0s
[04:19:04.758] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:87:0)
[04:19:04.758] Timer tick, asking Buildbucket for the build status
[04:19:04.889] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:04.889] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:88:0) after 1m0s
[04:20:05.015] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:88:0)
[04:20:05.015] Timer tick, asking Buildbucket for the build status
[04:20:05.059] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:05.059] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:89:0) after 1m0s
[04:21:05.120] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:89:0)
[04:21:05.120] Timer tick, asking Buildbucket for the build status
[04:21:05.167] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:05.167] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:90:0) after 1m0s
[04:22:05.195] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:90:0)
[04:22:05.195] Timer tick, asking Buildbucket for the build status
[04:22:05.262] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:05.262] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:91:0) after 1m0s
[04:23:05.289] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:91:0)
[04:23:05.289] Timer tick, asking Buildbucket for the build status
[04:23:05.345] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:05.345] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:92:0) after 1m0s
[04:24:05.382] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:92:0)
[04:24:05.382] Timer tick, asking Buildbucket for the build status
[04:24:05.462] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:05.462] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:93:0) after 1m0s
[04:25:05.513] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:93:0)
[04:25:05.513] Timer tick, asking Buildbucket for the build status
[04:25:05.605] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:05.605] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:94:0) after 1m0s
[04:26:05.633] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:94:0)
[04:26:05.634] Timer tick, asking Buildbucket for the build status
[04:26:05.711] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:05.711] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:95:0) after 1m0s
[04:27:05.733] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:95:0)
[04:27:05.733] Timer tick, asking Buildbucket for the build status
[04:27:06.056] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:06.056] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:96:0) after 1m0s
[04:28:06.121] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:96:0)
[04:28:06.121] Timer tick, asking Buildbucket for the build status
[04:28:06.260] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:06.260] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:97:0) after 1m0s
[04:29:06.331] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:97:0)
[04:29:06.331] Timer tick, asking Buildbucket for the build status
[04:29:06.621] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:06.622] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:98:0) after 1m0s
[04:30:06.678] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:98:0)
[04:30:06.678] Timer tick, asking Buildbucket for the build status
[04:30:06.742] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:06.742] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:99:0) after 1m0s
[04:31:06.796] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:99:0)
[04:31:06.796] Timer tick, asking Buildbucket for the build status
[04:31:06.966] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:06.966] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:100:0) after 1m0s
[04:32:07.018] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:100:0)
[04:32:07.018] Timer tick, asking Buildbucket for the build status
[04:32:07.074] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:07.074] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:101:0) after 1m0s
[04:33:07.101] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:101:0)
[04:33:07.101] Timer tick, asking Buildbucket for the build status
[04:33:07.137] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:07.137] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:102:0) after 1m0s
[04:34:07.165] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:102:0)
[04:34:07.165] Timer tick, asking Buildbucket for the build status
[04:34:07.258] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:07.258] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:103:0) after 1m0s
[04:35:07.279] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:103:0)
[04:35:07.279] Timer tick, asking Buildbucket for the build status
[04:35:07.325] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:07.325] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:104:0) after 1m0s
[04:36:07.360] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:104:0)
[04:36:07.360] Timer tick, asking Buildbucket for the build status
[04:36:07.487] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:07.487] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:105:0) after 1m0s
[04:37:07.540] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:105:0)
[04:37:07.540] Timer tick, asking Buildbucket for the build status
[04:37:07.613] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:07.613] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:106:0) after 1m0s
[04:38:07.652] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:106:0)
[04:38:07.652] Timer tick, asking Buildbucket for the build status
[04:38:07.709] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:07.709] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:107:0) after 1m0s
[04:39:07.728] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:107:0)
[04:39:07.728] Timer tick, asking Buildbucket for the build status
[04:39:07.841] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:07.841] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:108:0) after 1m0s
[04:40:07.865] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:108:0)
[04:40:07.865] Timer tick, asking Buildbucket for the build status
[04:40:07.968] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:07.968] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:109:0) after 1m0s
[04:41:07.991] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:109:0)
[04:41:07.991] Timer tick, asking Buildbucket for the build status
[04:41:08.108] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:08.108] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:110:0) after 1m0s
[04:42:08.141] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:110:0)
[04:42:08.141] Timer tick, asking Buildbucket for the build status
[04:42:08.193] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:08.193] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:111:0) after 1m0s
[04:43:08.246] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:111:0)
[04:43:08.246] Timer tick, asking Buildbucket for the build status
[04:43:08.310] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:08.311] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:112:0) after 1m0s
[04:44:08.332] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:112:0)
[04:44:08.332] Timer tick, asking Buildbucket for the build status
[04:44:08.492] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:08.492] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:113:0) after 1m0s
[04:45:08.626] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:113:0)
[04:45:08.626] Timer tick, asking Buildbucket for the build status
[04:45:08.802] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:08.802] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:114:0) after 1m0s
[04:46:08.934] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:114:0)
[04:46:08.934] Timer tick, asking Buildbucket for the build status
[04:46:09.026] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:09.026] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:115:0) after 1m0s
[04:47:09.050] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:115:0)
[04:47:09.050] Timer tick, asking Buildbucket for the build status
[04:47:09.114] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:09.114] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:116:0) after 1m0s
[04:48:09.146] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:116:0)
[04:48:09.146] Timer tick, asking Buildbucket for the build status
[04:48:09.231] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:09.231] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:117:0) after 1m0s
[04:49:09.277] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:117:0)
[04:49:09.277] Timer tick, asking Buildbucket for the build status
[04:49:09.444] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:09.444] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:118:0) after 1m0s
[04:50:09.479] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:118:0)
[04:50:09.479] Timer tick, asking Buildbucket for the build status
[04:50:09.568] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:09.568] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:119:0) after 1m0s
[04:51:09.648] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:119:0)
[04:51:09.648] Timer tick, asking Buildbucket for the build status
[04:51:09.692] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:09.692] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:120:0) after 1m0s
[04:52:09.742] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:120:0)
[04:52:09.742] Timer tick, asking Buildbucket for the build status
[04:52:09.799] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:09.799] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:121:0) after 1m0s
[04:53:09.831] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:121:0)
[04:53:09.831] Timer tick, asking Buildbucket for the build status
[04:53:09.918] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:09.918] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:122:0) after 1m0s
[04:54:09.943] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:122:0)
[04:54:09.943] Timer tick, asking Buildbucket for the build status
[04:54:10.014] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:10.014] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:123:0) after 1m0s
[04:55:10.043] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:123:0)
[04:55:10.044] Timer tick, asking Buildbucket for the build status
[04:55:10.123] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:10.123] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:124:0) after 1m0s
[04:56:10.203] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:124:0)
[04:56:10.203] Timer tick, asking Buildbucket for the build status
[04:56:10.298] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:10.298] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:125:0) after 1m0s
[04:57:10.324] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:125:0)
[04:57:10.324] Timer tick, asking Buildbucket for the build status
[04:57:10.362] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:10.363] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:126:0) after 1m0s
[04:58:10.393] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:126:0)
[04:58:10.393] Timer tick, asking Buildbucket for the build status
[04:58:10.499] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:10.499] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:127:0) after 1m0s
[04:59:10.622] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:127:0)
[04:59:10.622] Timer tick, asking Buildbucket for the build status
[04:59:10.669] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:10.669] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:128:0) after 1m0s
[05:00:10.692] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:128:0)
[05:00:10.692] Timer tick, asking Buildbucket for the build status
[05:00:10.817] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:10.817] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:129:0) after 1m0s
[05:01:10.899] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:129:0)
[05:01:10.899] Timer tick, asking Buildbucket for the build status
[05:01:10.935] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:10.935] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:130:0) after 1m0s
[05:02:10.961] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:130:0)
[05:02:10.961] Timer tick, asking Buildbucket for the build status
[05:02:11.024] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:11.024] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:131:0) after 1m0s
[05:03:11.047] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:131:0)
[05:03:11.047] Timer tick, asking Buildbucket for the build status
[05:03:11.161] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:11.161] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:132:0) after 1m0s
[05:04:11.193] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:132:0)
[05:04:11.193] Timer tick, asking Buildbucket for the build status
[05:04:11.249] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:11.249] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:133:0) after 1m0s
[05:05:11.281] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:133:0)
[05:05:11.281] Timer tick, asking Buildbucket for the build status
[05:05:11.340] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:11.340] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:134:0) after 1m0s
[05:06:11.369] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:134:0)
[05:06:11.369] Timer tick, asking Buildbucket for the build status
[05:06:11.739] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:11.739] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:135:0) after 1m0s
[05:07:11.771] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:135:0)
[05:07:11.771] Timer tick, asking Buildbucket for the build status
[05:07:11.882] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:11.882] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:136:0) after 1m0s
[05:08:11.923] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:136:0)
[05:08:11.923] Timer tick, asking Buildbucket for the build status
[05:08:12.024] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:12.024] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:137:0) after 1m0s
[05:09:12.054] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:137:0)
[05:09:12.054] Timer tick, asking Buildbucket for the build status
[05:09:12.198] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:12.198] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:138:0) after 1m0s
[05:10:12.247] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:138:0)
[05:10:12.247] Timer tick, asking Buildbucket for the build status
[05:10:12.324] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:12.324] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:139:0) after 1m0s
[05:11:12.355] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:139:0)
[05:11:12.355] Timer tick, asking Buildbucket for the build status
[05:11:12.414] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:12.414] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:140:0) after 1m0s
[05:12:12.443] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:140:0)
[05:12:12.443] Timer tick, asking Buildbucket for the build status
[05:12:12.502] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:12.502] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:141:0) after 1m0s
[05:13:12.541] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:141:0)
[05:13:12.541] Timer tick, asking Buildbucket for the build status
[05:13:12.605] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:12.605] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:142:0) after 1m0s
[05:14:12.646] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:142:0)
[05:14:12.646] Timer tick, asking Buildbucket for the build status
[05:14:12.750] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:12.750] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:143:0) after 1m0s
[05:15:12.774] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:143:0)
[05:15:12.774] Timer tick, asking Buildbucket for the build status
[05:15:12.879] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:12.879] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:144:0) after 1m0s
[05:16:12.907] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:144:0)
[05:16:12.907] Timer tick, asking Buildbucket for the build status
[05:16:13.031] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:13.031] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:145:0) after 1m0s
[05:17:13.157] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:145:0)
[05:17:13.157] Timer tick, asking Buildbucket for the build status
[05:17:13.245] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:13.245] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:146:0) after 1m0s
[05:18:13.275] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:146:0)
[05:18:13.275] Timer tick, asking Buildbucket for the build status
[05:18:13.527] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:13.527] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:147:0) after 1m0s
[05:19:13.553] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:147:0)
[05:19:13.553] Timer tick, asking Buildbucket for the build status
[05:19:13.643] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:13.643] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:148:0) after 1m0s
[05:20:13.690] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:148:0)
[05:20:13.690] Timer tick, asking Buildbucket for the build status
[05:20:13.765] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:13.765] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:149:0) after 1m0s
[05:21:13.806] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:149:0)
[05:21:13.806] Timer tick, asking Buildbucket for the build status
[05:21:13.922] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:13.922] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:150:0) after 1m0s
[05:22:13.949] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:150:0)
[05:22:13.949] Timer tick, asking Buildbucket for the build status
[05:22:14.613] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:14.613] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:151:0) after 1m0s
[05:23:14.669] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:151:0)
[05:23:14.669] Timer tick, asking Buildbucket for the build status
[05:23:14.756] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:14.756] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:152:0) after 1m0s
[05:24:14.781] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:152:0)
[05:24:14.781] Timer tick, asking Buildbucket for the build status
[05:24:14.832] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:14.832] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:153:0) after 1m0s
[05:25:14.874] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:153:0)
[05:25:14.874] Timer tick, asking Buildbucket for the build status
[05:25:14.936] Build 8932907228291232064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:14.936] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098337290500032464:154:0) after 1m0s
[05:26:14.021] Received PubSub notification, asking Buildbucket for the build status
[05:26:14.067] Build 8932907228291232064: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[05:26:14.067] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1539321971668164",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1539312827746383",
"failure_reason": "BUILD_FAILURE",
"id": "8932907228291232064",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"b9d0776816b987172184af69d6de4a09def7dca6\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"ui\": {\"info\": \"Failure compare_build_artifacts\"}, \"build_run_result\": {\"infraFailure\": null, \"recipeResult\": {\"failure\": {\"failure\": {\"step\": \"compare_build_artifacts\"}, \"humanReason\": \"Step('compare_build_artifacts') 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/8932907228291232064/+/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\": [\"3809-a31bbe1\"], \"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\": \"2ebbdab4bfb1ed9dfe52ce756b34829207dbe25f\", \"got_swarming_client_revision\": \"486c9b53c4d54dd4b95bb6ce0e31160e600dfc11\", \"got_revision\": \"b9d0776816b987172184af69d6de4a09def7dca6\", \"recipe\": \"swarming/deterministic_build\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#25132}\", \"$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@{#599078}\", \"branch\": \"refs/heads/master\", \"revision\": \"b9d0776816b987172184af69d6de4a09def7dca6\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"1cd39fa9ea0c29acd67008919f5b524cf071a3ae\", \"mastername\": \"chromium.fyi\", \"got_angle_revision\": \"f2ed299569c0075e83c8f42e44345ffada9231b9\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1539312827746383, \"tags\": [\"builder:Windows deterministic\", \"buildset:commit/git/b9d0776816b987172184af69d6de4a09def7dca6\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b9d0776816b987172184af69d6de4a09def7dca6\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9098337290500032464\", \"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\": \"8932907228291232064\"}}, \"got_v8_revision\": \"f70aaa8ab2e8815505a6145c745e50d8328cd28c\", \"got_v8_revision_cp\": \"refs/heads/7.1.302@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 10377, \"swarm_hashes\": {\"shell_dialogs_unittests\": \"9c6422dedabec5aeacd5b223e381ec5c09249d8f\", \"notification_helper_unittests\": \"06eb8e3b739c123ef0a97df43d24e3dfb2346993\", \"gpu_perftests\": \"f3fc594f29e38e223456e17a2e9c7ab198816b5e\", \"vr_common_unittests\": \"2cc4ef6b114f49cae74a0973c9b755d19ed91070\", \"telemetry_unittests\": \"03add0a10cbadef2854e456543979f5987db1053\", \"load_library_perf_tests\": \"801390e50829b6cfbf78d85e04b7a53bf8dd15be\", \"devtools_closure_compile\": \"b52707605a3b9e0391a9f7f384181aec1d8de81a\", \"chromedriver_py_tests\": \"aedb23b8428719b1c5babdb3a7fbc175a296ac33\", \"mojo_core_unittests\": \"a849e70ac0e985ceb4e6e106ffd2d457fdc18dcb\", \"install_static_unittests\": \"fef81944badb39582ed6349f201491c2aead7474\", \"leveldb_service_unittests\": \"7dbeeab76be1c4a252e901db6a8c9a781c81ffe2\", \"mini_installer_tests\": \"7bc1e57188774d99a0a8c369b0753c5e98b6a9ed\", \"jingle_unittests\": \"e3c78202d3210099cf667d468991840c265d1b0f\", \"sbox_integration_tests\": \"258392e4eeecde9d4128ef07457d82158eba3303\", \"angle_unittests\": \"c1499f17c9234fc867cb3499daa32f7fa57e4e3d\", \"cast_unittests\": \"14deba27d1c7838a5947a997c9de2309e34b47bc\", \"cc_unittests\": \"dcd4dcecdda7617295b33ba3d67e23fe33841e2b\", \"blink_common_unittests\": \"657dbc32fce399c541c3431a7444b6b3530d0f30\", \"crashpad_tests\": \"56ecbc62d6fad826df00291dbfb6004408b6d9ae\", \"telemetry_perf_tests_without_chrome\": \"b141e841d0b6f9a49c3a744fddbb306b726eec9a\", \"metrics_python_tests\": \"b4e4090d7c42df6b958c4a40710412bed7ee9ad6\", \"message_center_unittests\": \"f982cd567d413e6e0c3ce922dc23ea214fc1c397\", \"ppapi_unittests\": \"89c3b8b96985a0210a15dd868bc4ceed09e815c1\", \"extensions_unittests\": \"19ae00df6530c8bd78e4c2851d4649348a74e024\", \"gl_unittests_ozone\": \"d8287207988cae397c46aa38b58984534f21fe6e\", \"app_shell_unittests\": \"25e77bf8744b572abdacd947ae70f987a55288fc\", \"chrome_app_unittests\": \"bfa4f268b99317ee038970929c75936b823cb1ec\", \"gl_unittests\": \"d8287207988cae397c46aa38b58984534f21fe6e\", \"gcm_unit_tests\": \"d1cec79913c7025277dacef2497430083fa3aa39\", \"views_unittests\": \"b68b5a5c519501dfb9443fcbe38375f269d2f74d\", \"content_shell_crash_test\": \"970854065184b739d087c7a78aa613d7e8349304\", \"ui_base_unittests\": \"597c283376bf15d657410c3e0dcb36971f32491a\", \"gles2_conform_test\": \"68164687bee6b571c540e40b2b7997d657a0d249\", \"cronet_unittests\": \"d0705dd3eca78d9972849a90bce0bd82633b3154\", \"performance_test_suite\": \"20a6aa65b48823694d25380b3095eebf35185b5e\", \"wm_unittests\": \"80c6a0bb4b7cb64307caeb7d16bf2b39c1c04182\", \"boringssl_crypto_tests\": \"b263ff2f28a8c042b555b2e4ea14b63a0103656d\", \"gin_unittests\": \"cdf2a9e732e29303bec0eec1706622dd52035736\", \"performance_browser_tests\": \"228ffa280b7374d70596e7772ff8f8fbe44ca494\", \"extensions_browsertests\": \"4615ad2844c4817c988bb714ac6de5e9cadd2b42\", \"webkit_python_tests\": \"848697bd2dafbb6702e2106ada7a6650a58c396e\", \"headless_unittests\": \"f56aac4cbd8d7c035e860f5fadf517e18d4f3532\", \"webkit_layout_tests\": \"08463724b9cc401faa3845ea5eb33ef51738e4cc\", \"vr_pixeltests\": \"6a80149f145dd388e9e70e75aeab47657826ba6f\", \"setup_unittests\": \"65334358ddaf3bcb09bee072eb67e1c3ae05f769\", \"webkit_unit_tests\": \"67ea14a2811dbd4858ca62d1f8bd3a64dfcc0857\", \"content_unittests\": \"5cba4d8105468811b2112d931a28f0aea08f6015\", \"performance_webview_test_suite\": \"e3232f94af56597125ad6e11c7dd77e112124ca2\", \"wtf_unittests\": \"68da9ccfcb62c39d5ab3c47db3de7de715bcd570\", \"webkit_layout_tests_exparchive\": \"a559069b607992c563410c791f42d884b846172b\", \"chrome_cleaner_unittests\": \"b17f2dbed563d4ecb089753b5c0ae937fd7688b0\", \"traffic_annotation_auditor_unittests\": \"0920819bac3e379af66034b653bf542579ffdbcf\", \"libjingle_xmpp_unittests\": \"33b6dd05333457aee544df952caa49d9aeee084f\", \"remoting_unittests\": \"d139cc56c6a30655ca0faa9340c3bc8e621e30d6\", \"vr_common_perftests\": \"2e12f414ae4ed5026ecd8c7c92b7a9ceed4966bd\", \"filesystem_service_unittests\": \"9ba64f1d12c1b8f6c018de9d514f5d74baf61577\", \"chrome_elf_import_unittests\": \"509ab8eeae0b893b875ea3957f4ecccde81b0bcf\", \"installer_util_unittests\": \"6ffa4e66aff4c7ee0d46c52eceed357de04def2b\", \"gfx_unittests\": \"9b656ee7078cf7e5c51334337f576ff3f1d9e6ec\", \"headless_browsertests\": \"b46895a05a1417c5c6cfeeb0ae22f44db8f49048\", \"capture_unittests\": \"5613ae6ac8ead5df2b5edcb7908f026366432787\", \"chrome_elf_unittests\": \"43f6c28c019221582ed8798a1ed56bf4b8745a1a\", \"telemetry_gpu_unittests\": \"481f543252dc6aa012ff5c8bad2de71d0cb4bc1b\", \"command_buffer_perftests\": \"93159c91bd11619fea0313a3781692995f3d4265\", \"media_unittests\": \"0e6df145b7bd1174882973ab157b676890b57280\", \"chromedriver_unittests\": \"1449a781b68bb18e7094c7bfc7df377f1a0b9afa\", \"views_mus_interactive_ui_tests\": \"1ab94763320ca1479010e8daf6a0f0db7643eabb\", \"storage_unittests\": \"b8adc9650c2a061f676c8e34f2562c79eb59af8e\", \"media_service_unittests\": \"0c886d363f94d0e524f5d82359268046a534e5c5\", \"compositor_unittests\": \"b908853e3e5fcf6189260644735aff4091a69c5f\", \"latency_unittests\": \"fd232f2ed4918853cc103f5245ba88fb2e0f2e98\", \"video_decode_accelerator_unittest\": \"bd3ec50cf19b51c0345080989f5d84198195bddc\", \"swiftshader_unittests\": \"e848b3f595a22ab7d219b8048daa83ab9adfb331\", \"telemetry_perf_unittests\": \"7d179106d98fb2db3a6cb236f48971239716ce06\", \"media_blink_unittests\": \"b5858ddad2a96a1e81b3ea7ccf04c7202f262448\", \"views_mus_unittests\": \"4f179fa77f733bb7ea4d765be61ed4a543fe3a5a\", \"components_unittests\": \"ee7bc35b43518727a1ed5aa30ec97534d10766b4\", \"snapshot_unittests\": \"99233f3b859175e931f734c4ef726a9f61a793ca\", \"accessibility_unittests\": \"afee00826d1b9815b55bc9cd55c8418591b5801c\", \"net_perftests\": \"f9ec1a560fc77a8ebea9ecf64c31a8805e514fb5\", \"aura_unittests\": \"0939c5db06e59e795dadfff404245655fe68ab33\", \"services_unittests\": \"9bba04f15ae4796be8f853ea5b974bfe13aa33ff\", \"components_perftests\": \"221fa32c90838498c7b63abbb4c824d7affa9309\", \"events_unittests\": \"c028e7ae368edd3e6e4b2c67ce2ce230e69b654a\", \"tracing_perftests\": \"ac644a406c205f6de44a1b0165dbcb5d94698fad\", \"chromedriver_replay_unittests\": \"5013a0f078eac50a549885e896698d163bf73605\", \"ui_touch_selection_unittests\": \"ec4c2305c9f65838812c53c0fecc7f72e1c926e6\", \"cacheinvalidation_unittests\": \"635cfb47016f158ad072586f32fc5f5a4546810d\", \"viz_unittests\": \"8536323f293d1bb40cfbba6bb50a05766c82d35e\", \"browser_tests\": \"29e7a37b9879b32e52ae32f8784dce3850a408f6\", \"net_unittests\": \"2709eaf077b1326fefe9fbd660eff7a525054617\", \"midi_unittests\": \"0b4ef41fc2e18bbf96a8f49071223189e5518b55\", \"boringssl_ssl_tests\": \"288dd41ea23552a77f1d31bcf3dae71bb1a9680f\", \"sbox_validation_tests\": \"92d9047c70793b4fd44ca44facad960a81898fed\", \"elevation_service_unittests\": \"4d0de95cfac6c93e69f8c4d184d1dc35b8695465\", \"gpu_unittests\": \"78f40f9cdba87d217310c71b3bbea6236f73c186\", \"native_theme_unittests\": \"9d144856e90a868a1be3c2c29bf74663dd807835\", \"interactive_ui_tests\": \"515fe9cefd24c6a7c4d1c2e10d1ae7625de4a2e2\", \"mojo_unittests\": \"106ddab259b2bd83caa1c7a5756d8b3abe3c6d8a\", \"url_unittests\": \"8ffa2cab1abe3bc78e8379597b57b7407a3efda9\", \"media_perftests\": \"c507bef9b7de28789a9cde10d5215c2d7b7e2226\", \"cronet_tests\": \"00108a2f30e06d229f35cbd1b00f00bd8709b56d\", \"google_apis_unittests\": \"c43b4749943c59a8499859c2d737b876670fe520\", \"telemetry_gpu_integration_test\": \"fe81d1506d6c73f44693f80bae1d45a43d782b8a\", \"blink_heap_unittests\": \"e2e676fe43d75fbd210ce4c12a59770ba202486f\", \"base_unittests\": \"2c0caefaa2176b6a94fd4d12b0c311276e9e63a1\", \"sync_integration_tests\": \"983a5bc2665df49ccfe77f43d69fed32d7a14e80\", \"sql_unittests\": \"d84462f1f4473e3de044ae4857bd080f9ef31d0b\", \"courgette_unittests\": \"94df7f00df0a4acc61c885219d82949536db5039\", \"blink_fuzzer_unittests\": \"228e8d4c6dcb37d3a62fbe30fc79bf7ec3a63caa\", \"gl_tests\": \"28b9a37606e73b83f08bd515f2d90fb297c8e902\", \"zucchini_unittests\": \"4e02529a19643f74135bdd0895195d53a41c1505\", \"ipc_tests\": \"6f19e3a5a9e1ab8826f5b2de199c96c73a5520fb\", \"angle_end2end_tests\": \"60885d5728aa3a31b62c194c6fda4436e81e1b70\", \"blink_platform_unittests\": \"8d486d144cdc62e2c83fd5a6c8d52bcdea449380\", \"components_browsertests\": \"0b372eb03338082c85fff9aca8ca2cae37d8e4e0\", \"views_perftests\": \"42c924db448dba41bf158fa6f960fe438e34ed7e\", \"angle_perftests\": \"a34d6b500f0dd2b7bb45b6b6577993701f834a32\", \"display_unittests\": \"916aeda42cf958f1c5c9ed8d1afd8331d9009850\", \"sbox_unittests\": \"f09e6e1af2d128d2876eb460c873bbd90566d94a\", \"service_manager_unittests\": \"858588ad2b171f0b6bc147aa5facb50aae039adb\", \"pdf_unittests\": \"c76b284736ef4f9754c2d726327e09a4de752f3c\", \"skia_unittests\": \"0503c0fd98e798b56ffdd44d7133693d57941bdf\", \"angle_white_box_tests\": \"9533345e468a6d1c7b4075d7a1c4b6ffec7d37f3\", \"crypto_unittests\": \"0641aac408a3aca08996c0207b0713ae60d3d201\", \"nacl_loader_unittests\": \"b6168188e53bd713e5f91758e41dc07f676b1166\", \"unit_tests\": \"bda99dd93e6a448050d230037c4f19a5ae854474\", \"content_browsertests\": \"c20e34b1b9a57ae1b3664db4c0ffc993c7dd6cc1\", \"printing_unittests\": \"ae10d6a6cce9608b094405d6931b7661d90880c0\", \"device_unittests\": \"902e5859803b3dcf11c2376f0bc7836cde9801f6\"}, \"path_config\": \"generic\", \"bot_id\": \"swarm2173-c4\", \"got_buildtools_revision\": \"2dff9c9c74e9d732e6fe57c84ef7fd044cc45d96\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1539312839911382",
"status": "COMPLETED",
"status_changed_ts": "1539321972468092",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/10377",
"builder:Windows deterministic",
"buildset:commit/git/b9d0776816b987172184af69d6de4a09def7dca6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b9d0776816b987172184af69d6de4a09def7dca6",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9098337290500032464",
"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/10377",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8932907228291232064",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:4c790cbde9342069ed9b59b6709c8807b14f2913",
"swarming_tag:builder:Windows deterministic",
"swarming_tag:buildset:commit/git/b9d0776816b987172184af69d6de4a09def7dca6",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b9d0776816b987172184af69d6de4a09def7dca6",
"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/8932907228291232064/+/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:9098337290500032464",
"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:9d4e55f09f057cb6c6ed423e82fe5a8bae98ab2b",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:407f0348b604f110",
"user_agent:luci-scheduler"
],
"updated_ts": "1539321972472212",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/10377",
"utcnow_ts": "1539321974054508"
}
}
[05:26:14.067] Invocation finished in 2h32m27.811665366s with status FAILED