[15:36:42.110] New invocation is queued and will start shortly
[15:36:43.454] Starting the invocation (attempt 1)
[15:36:43.522] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:36:43.522] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:36:43.523] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9102275557577861664",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"4fb91fa970d86bb66a7734f2495138055a9d0c80\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9102275557577861664",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/4fb91fa970d86bb66a7734f2495138055a9d0c80",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4fb91fa970d86bb66a7734f2495138055a9d0c80",
"gitiles_ref:refs/heads/master"
]
}
[15:36:44.937] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1535557003658240",
"id": "8936845495290319136",
"lease_expiration_ts": "1535701004661120",
"lease_key": "799323560",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"4fb91fa970d86bb66a7734f2495138055a9d0c80\"}}",
"project": "chromium",
"result_details_json": "null",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1535557004852970",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/9913",
"builder:Windows deterministic",
"buildset:commit/git/4fb91fa970d86bb66a7734f2495138055a9d0c80",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4fb91fa970d86bb66a7734f2495138055a9d0c80",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9102275557577861664",
"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/9913",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8936845495290319136",
"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/4fb91fa970d86bb66a7734f2495138055a9d0c80",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4fb91fa970d86bb66a7734f2495138055a9d0c80",
"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/8936845495290319136/+/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:9102275557577861664",
"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:e1e1f1cf15fde3b8cf0aaba2d46508c232f3b6af",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:3f9f25f473876010",
"user_agent:luci-scheduler"
],
"updated_ts": "1535557004853110",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/9913",
"utcnow_ts": "1535557004925020"
}
}
[15:36:44.937] Task URL: https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/9913
[15:36:44.937] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:2:0) after 1m0s
[15:37:44.971] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:2:0)
[15:37:44.971] Timer tick, asking Buildbucket for the build status
[15:37:45.041] Build 8936845495290319136: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:37:45.041] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:3:0) after 1m0s
[15:38:30.568] Received PubSub notification, asking Buildbucket for the build status
[15:38:30.627] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:45.058] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:3:0)
[15:38:45.058] Timer tick, asking Buildbucket for the build status
[15:38:45.177] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:45.177] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:5:0) after 1m0s
[15:39:45.290] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:5:0)
[15:39:45.290] Timer tick, asking Buildbucket for the build status
[15:39:45.392] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:45.392] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:6:0) after 1m0s
[15:40:45.434] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:6:0)
[15:40:45.434] Timer tick, asking Buildbucket for the build status
[15:40:45.470] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:45.470] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:7:0) after 1m0s
[15:41:45.494] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:7:0)
[15:41:45.494] Timer tick, asking Buildbucket for the build status
[15:41:45.531] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:45.531] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:8:0) after 1m0s
[15:42:45.611] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:8:0)
[15:42:45.611] Timer tick, asking Buildbucket for the build status
[15:42:45.649] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:45.649] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:9:0) after 1m0s
[15:43:46.405] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:9:0)
[15:43:46.406] Timer tick, asking Buildbucket for the build status
[15:43:46.957] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:46.957] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:10:0) after 1m0s
[15:44:47.522] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:10:0)
[15:44:47.522] Timer tick, asking Buildbucket for the build status
[15:44:47.596] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:47.596] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:11:0) after 1m0s
[15:45:47.743] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:11:0)
[15:45:47.743] Timer tick, asking Buildbucket for the build status
[15:45:47.806] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:47.807] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:12:0) after 1m0s
[15:46:50.238] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:12:0)
[15:46:50.238] Timer tick, asking Buildbucket for the build status
[15:46:50.298] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:50.298] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:13:0) after 1m0s
[15:47:51.112] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:13:0)
[15:47:51.112] Timer tick, asking Buildbucket for the build status
[15:47:51.168] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:51.168] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:14:0) after 1m0s
[15:48:51.242] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:14:0)
[15:48:51.242] Timer tick, asking Buildbucket for the build status
[15:48:51.288] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:51.288] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:15:0) after 1m0s
[15:49:52.302] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:15:0)
[15:49:52.302] Timer tick, asking Buildbucket for the build status
[15:49:52.338] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:52.338] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:16:0) after 1m0s
[15:50:52.761] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:16:0)
[15:50:52.761] Timer tick, asking Buildbucket for the build status
[15:50:52.808] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:52.808] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:17:0) after 1m0s
[15:51:54.723] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:17:0)
[15:51:54.723] Timer tick, asking Buildbucket for the build status
[15:51:54.787] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:54.787] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:18:0) after 1m0s
[15:52:55.554] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:18:0)
[15:52:55.554] Timer tick, asking Buildbucket for the build status
[15:52:55.659] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:55.659] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:19:0) after 1m0s
[15:53:57.340] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:19:0)
[15:53:57.341] Timer tick, asking Buildbucket for the build status
[15:53:57.632] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:57.633] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:20:0) after 1m0s
[15:54:57.669] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:20:0)
[15:54:57.669] Timer tick, asking Buildbucket for the build status
[15:54:58.067] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:58.067] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:21:0) after 1m0s
[15:55:58.086] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:21:0)
[15:55:58.086] Timer tick, asking Buildbucket for the build status
[15:55:58.316] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:58.316] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:22:0) after 1m0s
[15:56:58.805] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:22:0)
[15:56:58.805] Timer tick, asking Buildbucket for the build status
[15:56:58.840] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:58.840] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:23:0) after 1m0s
[15:57:59.395] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:23:0)
[15:57:59.395] Timer tick, asking Buildbucket for the build status
[15:57:59.454] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:59.454] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:24:0) after 1m0s
[15:59:00.517] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:24:0)
[15:59:00.517] Timer tick, asking Buildbucket for the build status
[15:59:00.592] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:00.592] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:25:0) after 1m0s
[16:00:02.273] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:25:0)
[16:00:02.273] Timer tick, asking Buildbucket for the build status
[16:00:02.429] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:02.429] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:26:0) after 1m0s
[16:01:02.522] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:26:0)
[16:01:02.522] Timer tick, asking Buildbucket for the build status
[16:01:02.590] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:02.590] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:27:0) after 1m0s
[16:02:03.183] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:27:0)
[16:02:03.183] Timer tick, asking Buildbucket for the build status
[16:02:03.246] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:03.246] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:28:0) after 1m0s
[16:03:03.818] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:28:0)
[16:03:03.818] Timer tick, asking Buildbucket for the build status
[16:03:03.957] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:03.957] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:29:0) after 1m0s
[16:04:04.460] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:29:0)
[16:04:04.460] Timer tick, asking Buildbucket for the build status
[16:04:04.643] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:04.643] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:30:0) after 1m0s
[16:05:05.791] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:30:0)
[16:05:05.791] Timer tick, asking Buildbucket for the build status
[16:05:05.838] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:05.838] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:31:0) after 1m0s
[16:06:05.983] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:31:0)
[16:06:05.983] Timer tick, asking Buildbucket for the build status
[16:06:06.119] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:06.119] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:32:0) after 1m0s
[16:07:07.456] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:32:0)
[16:07:07.456] Timer tick, asking Buildbucket for the build status
[16:07:07.657] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:07.657] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:33:0) after 1m0s
[16:08:07.677] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:33:0)
[16:08:07.677] Timer tick, asking Buildbucket for the build status
[16:08:07.715] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:07.715] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:34:0) after 1m0s
[16:09:07.870] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:34:0)
[16:09:07.870] Timer tick, asking Buildbucket for the build status
[16:09:07.963] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:07.963] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:35:0) after 1m0s
[16:10:08.044] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:35:0)
[16:10:08.044] Timer tick, asking Buildbucket for the build status
[16:10:08.123] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:08.123] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:36:0) after 1m0s
[16:11:09.444] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:36:0)
[16:11:09.444] Timer tick, asking Buildbucket for the build status
[16:11:09.489] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:09.489] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:37:0) after 1m0s
[16:12:09.891] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:37:0)
[16:12:09.891] Timer tick, asking Buildbucket for the build status
[16:12:10.023] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:10.023] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:38:0) after 1m0s
[16:13:12.004] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:38:0)
[16:13:12.004] Timer tick, asking Buildbucket for the build status
[16:13:12.138] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:12.138] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:39:0) after 1m0s
[16:14:12.312] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:39:0)
[16:14:12.312] Timer tick, asking Buildbucket for the build status
[16:14:12.370] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:12.370] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:40:0) after 1m0s
[16:15:12.425] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:40:0)
[16:15:12.425] Timer tick, asking Buildbucket for the build status
[16:15:12.492] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:12.492] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:41:0) after 1m0s
[16:16:12.515] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:41:0)
[16:16:12.515] Timer tick, asking Buildbucket for the build status
[16:16:12.585] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:12.585] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:42:0) after 1m0s
[16:17:12.626] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:42:0)
[16:17:12.626] Timer tick, asking Buildbucket for the build status
[16:17:12.716] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:12.716] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:43:0) after 1m0s
[16:18:13.149] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:43:0)
[16:18:13.149] Timer tick, asking Buildbucket for the build status
[16:18:13.222] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:13.222] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:44:0) after 1m0s
[16:19:14.167] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:44:0)
[16:19:14.167] Timer tick, asking Buildbucket for the build status
[16:19:14.205] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:14.205] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:45:0) after 1m0s
[16:20:14.238] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:45:0)
[16:20:14.238] Timer tick, asking Buildbucket for the build status
[16:20:14.308] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:14.308] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:46:0) after 1m0s
[16:21:14.332] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:46:0)
[16:21:14.332] Timer tick, asking Buildbucket for the build status
[16:21:14.436] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:14.436] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:47:0) after 1m0s
[16:22:14.595] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:47:0)
[16:22:14.595] Timer tick, asking Buildbucket for the build status
[16:22:14.726] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:14.726] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:48:0) after 1m0s
[16:23:14.747] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:48:0)
[16:23:14.747] Timer tick, asking Buildbucket for the build status
[16:23:14.840] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:14.840] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:49:0) after 1m0s
[16:24:14.865] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:49:0)
[16:24:14.865] Timer tick, asking Buildbucket for the build status
[16:24:14.925] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:14.925] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:50:0) after 1m0s
[16:25:15.121] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:50:0)
[16:25:15.121] Timer tick, asking Buildbucket for the build status
[16:25:15.227] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:15.227] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:51:0) after 1m0s
[16:26:15.260] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:51:0)
[16:26:15.260] Timer tick, asking Buildbucket for the build status
[16:26:15.359] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:15.359] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:52:0) after 1m0s
[16:27:16.763] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:52:0)
[16:27:16.763] Timer tick, asking Buildbucket for the build status
[16:27:16.894] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:16.894] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:53:0) after 1m0s
[16:28:17.866] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:53:0)
[16:28:17.866] Timer tick, asking Buildbucket for the build status
[16:28:17.930] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:17.930] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:54:0) after 1m0s
[16:29:18.077] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:54:0)
[16:29:18.077] Timer tick, asking Buildbucket for the build status
[16:29:18.158] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:18.158] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:55:0) after 1m0s
[16:30:19.254] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:55:0)
[16:30:19.255] Timer tick, asking Buildbucket for the build status
[16:30:19.308] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:19.308] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:56:0) after 1m0s
[16:31:19.677] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:56:0)
[16:31:19.677] Timer tick, asking Buildbucket for the build status
[16:31:19.745] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:19.745] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:57:0) after 1m0s
[16:32:21.076] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:57:0)
[16:32:21.076] Timer tick, asking Buildbucket for the build status
[16:32:21.147] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:21.147] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:58:0) after 1m0s
[16:33:21.169] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:58:0)
[16:33:21.169] Timer tick, asking Buildbucket for the build status
[16:33:21.223] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:21.223] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:59:0) after 1m0s
[16:34:22.341] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:59:0)
[16:34:22.341] Timer tick, asking Buildbucket for the build status
[16:34:22.405] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:22.405] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:60:0) after 1m0s
[16:35:23.448] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:60:0)
[16:35:23.448] Timer tick, asking Buildbucket for the build status
[16:35:23.558] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:23.558] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:61:0) after 1m0s
[16:36:24.804] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:61:0)
[16:36:24.804] Timer tick, asking Buildbucket for the build status
[16:36:24.891] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:24.891] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:62:0) after 1m0s
[16:37:25.508] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:62:0)
[16:37:25.508] Timer tick, asking Buildbucket for the build status
[16:37:25.790] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:25.790] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:63:0) after 1m0s
[16:38:27.036] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:63:0)
[16:38:27.036] Timer tick, asking Buildbucket for the build status
[16:38:27.148] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:27.148] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:64:0) after 1m0s
[16:39:28.411] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:64:0)
[16:39:28.411] Timer tick, asking Buildbucket for the build status
[16:39:28.504] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:28.504] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:65:0) after 1m0s
[16:40:29.684] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:65:0)
[16:40:29.684] Timer tick, asking Buildbucket for the build status
[16:40:29.729] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:29.729] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:66:0) after 1m0s
[16:41:31.006] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:66:0)
[16:41:31.006] Timer tick, asking Buildbucket for the build status
[16:41:31.060] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:31.060] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:67:0) after 1m0s
[16:42:31.340] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:67:0)
[16:42:31.340] Timer tick, asking Buildbucket for the build status
[16:42:31.398] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:31.398] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:68:0) after 1m0s
[16:43:32.364] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:68:0)
[16:43:32.364] Timer tick, asking Buildbucket for the build status
[16:43:32.517] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:32.517] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:69:0) after 1m0s
[16:44:33.728] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:69:0)
[16:44:33.728] Timer tick, asking Buildbucket for the build status
[16:44:33.821] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:33.821] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:70:0) after 1m0s
[16:45:34.809] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:70:0)
[16:45:34.809] Timer tick, asking Buildbucket for the build status
[16:45:34.898] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:34.898] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:71:0) after 1m0s
[16:46:36.188] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:71:0)
[16:46:36.188] Timer tick, asking Buildbucket for the build status
[16:46:36.225] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:36.225] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:72:0) after 1m0s
[16:47:36.249] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:72:0)
[16:47:36.249] Timer tick, asking Buildbucket for the build status
[16:47:36.309] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:36.309] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:73:0) after 1m0s
[16:48:36.328] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:73:0)
[16:48:36.328] Timer tick, asking Buildbucket for the build status
[16:48:36.392] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:36.392] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:74:0) after 1m0s
[16:49:36.413] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:74:0)
[16:49:36.413] Timer tick, asking Buildbucket for the build status
[16:49:36.522] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:36.522] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:75:0) after 1m0s
[16:50:36.547] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:75:0)
[16:50:36.547] Timer tick, asking Buildbucket for the build status
[16:50:36.674] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:36.674] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:76:0) after 1m0s
[16:51:36.757] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:76:0)
[16:51:36.757] Timer tick, asking Buildbucket for the build status
[16:51:36.801] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:36.801] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:77:0) after 1m0s
[16:52:36.823] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:77:0)
[16:52:36.823] Timer tick, asking Buildbucket for the build status
[16:52:37.159] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:37.160] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:78:0) after 1m0s
[16:53:37.191] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:78:0)
[16:53:37.191] Timer tick, asking Buildbucket for the build status
[16:53:37.387] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:37.387] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:79:0) after 1m0s
[16:54:37.409] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:79:0)
[16:54:37.409] Timer tick, asking Buildbucket for the build status
[16:54:37.486] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:37.486] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:80:0) after 1m0s
[16:55:37.507] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:80:0)
[16:55:37.507] Timer tick, asking Buildbucket for the build status
[16:55:37.605] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:37.605] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:81:0) after 1m0s
[16:56:37.708] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:81:0)
[16:56:37.708] Timer tick, asking Buildbucket for the build status
[16:56:37.767] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:37.768] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:82:0) after 1m0s
[16:57:37.788] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:82:0)
[16:57:37.788] Timer tick, asking Buildbucket for the build status
[16:57:37.823] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:37.823] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:83:0) after 1m0s
[16:58:37.856] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:83:0)
[16:58:37.856] Timer tick, asking Buildbucket for the build status
[16:58:37.911] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:37.911] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:84:0) after 1m0s
[16:59:37.949] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:84:0)
[16:59:37.949] Timer tick, asking Buildbucket for the build status
[16:59:38.014] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:38.014] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:85:0) after 1m0s
[17:00:38.036] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:85:0)
[17:00:38.036] Timer tick, asking Buildbucket for the build status
[17:00:38.118] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:38.118] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:86:0) after 1m0s
[17:01:38.152] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:86:0)
[17:01:38.152] Timer tick, asking Buildbucket for the build status
[17:01:38.227] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:38.227] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:87:0) after 1m0s
[17:02:38.255] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:87:0)
[17:02:38.255] Timer tick, asking Buildbucket for the build status
[17:02:38.350] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:38.350] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:88:0) after 1m0s
[17:03:38.381] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:88:0)
[17:03:38.381] Timer tick, asking Buildbucket for the build status
[17:03:38.420] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:38.420] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:89:0) after 1m0s
[17:04:38.462] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:89:0)
[17:04:38.462] Timer tick, asking Buildbucket for the build status
[17:04:38.660] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:38.660] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:90:0) after 1m0s
[17:05:38.689] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:90:0)
[17:05:38.689] Timer tick, asking Buildbucket for the build status
[17:05:38.780] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:38.780] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:91:0) after 1m0s
[17:06:38.806] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:91:0)
[17:06:38.806] Timer tick, asking Buildbucket for the build status
[17:06:38.868] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:38.868] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:92:0) after 1m0s
[17:07:38.906] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:92:0)
[17:07:38.906] Timer tick, asking Buildbucket for the build status
[17:07:39.052] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:39.052] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:93:0) after 1m0s
[17:08:39.118] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:93:0)
[17:08:39.118] Timer tick, asking Buildbucket for the build status
[17:08:39.195] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:39.195] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:94:0) after 1m0s
[17:09:39.219] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:94:0)
[17:09:39.219] Timer tick, asking Buildbucket for the build status
[17:09:39.321] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:39.321] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:95:0) after 1m0s
[17:10:39.347] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:95:0)
[17:10:39.347] Timer tick, asking Buildbucket for the build status
[17:10:39.430] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:39.430] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:96:0) after 1m0s
[17:11:39.452] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:96:0)
[17:11:39.452] Timer tick, asking Buildbucket for the build status
[17:11:39.526] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:39.526] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:97:0) after 1m0s
[17:12:39.564] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:97:0)
[17:12:39.564] Timer tick, asking Buildbucket for the build status
[17:12:39.654] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:39.654] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:98:0) after 1m0s
[17:13:39.684] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:98:0)
[17:13:39.684] Timer tick, asking Buildbucket for the build status
[17:13:39.842] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:39.842] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:99:0) after 1m0s
[17:14:39.862] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:99:0)
[17:14:39.862] Timer tick, asking Buildbucket for the build status
[17:14:39.939] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:39.939] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:100:0) after 1m0s
[17:15:39.966] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:100:0)
[17:15:39.966] Timer tick, asking Buildbucket for the build status
[17:15:40.022] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:40.022] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:101:0) after 1m0s
[17:16:40.076] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:101:0)
[17:16:40.076] Timer tick, asking Buildbucket for the build status
[17:16:40.154] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:40.154] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:102:0) after 1m0s
[17:17:40.260] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:102:0)
[17:17:40.260] Timer tick, asking Buildbucket for the build status
[17:17:40.331] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:40.331] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:103:0) after 1m0s
[17:18:40.351] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:103:0)
[17:18:40.351] Timer tick, asking Buildbucket for the build status
[17:18:40.411] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:40.411] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:104:0) after 1m0s
[17:19:40.432] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:104:0)
[17:19:40.432] Timer tick, asking Buildbucket for the build status
[17:19:40.466] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:40.466] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:105:0) after 1m0s
[17:20:40.486] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:105:0)
[17:20:40.486] Timer tick, asking Buildbucket for the build status
[17:20:40.576] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:40.576] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:106:0) after 1m0s
[17:21:40.602] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:106:0)
[17:21:40.602] Timer tick, asking Buildbucket for the build status
[17:21:40.761] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:40.761] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:107:0) after 1m0s
[17:22:40.786] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:107:0)
[17:22:40.786] Timer tick, asking Buildbucket for the build status
[17:22:40.824] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:40.824] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:108:0) after 1m0s
[17:23:40.854] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:108:0)
[17:23:40.854] Timer tick, asking Buildbucket for the build status
[17:23:40.961] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:40.961] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:109:0) after 1m0s
[17:24:40.983] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:109:0)
[17:24:40.983] Timer tick, asking Buildbucket for the build status
[17:24:41.105] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:41.105] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:110:0) after 1m0s
[17:25:41.132] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:110:0)
[17:25:41.132] Timer tick, asking Buildbucket for the build status
[17:25:41.175] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:41.175] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:111:0) after 1m0s
[17:26:41.204] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:111:0)
[17:26:41.204] Timer tick, asking Buildbucket for the build status
[17:26:41.314] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:41.314] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:112:0) after 1m0s
[17:27:41.340] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:112:0)
[17:27:41.340] Timer tick, asking Buildbucket for the build status
[17:27:41.454] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:41.454] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:113:0) after 1m0s
[17:28:41.482] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:113:0)
[17:28:41.482] Timer tick, asking Buildbucket for the build status
[17:28:41.627] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:41.627] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:114:0) after 1m0s
[17:29:41.656] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:114:0)
[17:29:41.656] Timer tick, asking Buildbucket for the build status
[17:29:41.693] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:41.693] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:115:0) after 1m0s
[17:30:41.722] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:115:0)
[17:30:41.722] Timer tick, asking Buildbucket for the build status
[17:30:41.824] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:41.824] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:116:0) after 1m0s
[17:31:41.859] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:116:0)
[17:31:41.859] Timer tick, asking Buildbucket for the build status
[17:31:41.896] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:41.896] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:117:0) after 1m0s
[17:32:41.928] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:117:0)
[17:32:41.928] Timer tick, asking Buildbucket for the build status
[17:32:42.068] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:42.068] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:118:0) after 1m0s
[17:33:42.094] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:118:0)
[17:33:42.094] Timer tick, asking Buildbucket for the build status
[17:33:42.156] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:42.156] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:119:0) after 1m0s
[17:34:42.223] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:119:0)
[17:34:42.223] Timer tick, asking Buildbucket for the build status
[17:34:44.594] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:44.594] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:120:0) after 1m0s
[17:35:44.633] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:120:0)
[17:35:44.633] Timer tick, asking Buildbucket for the build status
[17:35:44.911] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:44.911] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:121:0) after 1m0s
[17:36:44.940] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:121:0)
[17:36:44.940] Timer tick, asking Buildbucket for the build status
[17:36:45.042] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:45.042] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:122:0) after 1m0s
[17:37:45.077] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:122:0)
[17:37:45.077] Timer tick, asking Buildbucket for the build status
[17:37:45.168] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:45.168] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:123:0) after 1m0s
[17:38:45.196] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:123:0)
[17:38:45.196] Timer tick, asking Buildbucket for the build status
[17:38:45.526] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:45.526] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:124:0) after 1m0s
[17:39:45.709] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:124:0)
[17:39:45.709] Timer tick, asking Buildbucket for the build status
[17:39:45.743] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:45.743] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:125:0) after 1m0s
[17:40:45.767] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:125:0)
[17:40:45.767] Timer tick, asking Buildbucket for the build status
[17:40:45.827] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:45.827] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:126:0) after 1m0s
[17:41:45.862] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:126:0)
[17:41:45.862] Timer tick, asking Buildbucket for the build status
[17:41:45.931] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:45.931] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:127:0) after 1m0s
[17:42:46.013] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:127:0)
[17:42:46.014] Timer tick, asking Buildbucket for the build status
[17:42:46.099] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:46.099] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:128:0) after 1m0s
[17:43:46.277] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:128:0)
[17:43:46.277] Timer tick, asking Buildbucket for the build status
[17:43:46.497] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:46.497] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:129:0) after 1m0s
[17:44:46.521] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:129:0)
[17:44:46.521] Timer tick, asking Buildbucket for the build status
[17:44:46.590] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:46.590] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:130:0) after 1m0s
[17:45:46.611] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:130:0)
[17:45:46.611] Timer tick, asking Buildbucket for the build status
[17:45:46.674] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:46.674] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:131:0) after 1m0s
[17:46:46.702] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:131:0)
[17:46:46.702] Timer tick, asking Buildbucket for the build status
[17:46:46.822] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:46.822] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:132:0) after 1m0s
[17:47:46.847] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:132:0)
[17:47:46.847] Timer tick, asking Buildbucket for the build status
[17:47:46.885] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:46.885] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:133:0) after 1m0s
[17:48:46.926] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:133:0)
[17:48:46.926] Timer tick, asking Buildbucket for the build status
[17:48:46.977] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:46.977] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:134:0) after 1m0s
[17:49:47.004] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:134:0)
[17:49:47.004] Timer tick, asking Buildbucket for the build status
[17:49:47.101] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:47.101] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:135:0) after 1m0s
[17:50:47.125] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:135:0)
[17:50:47.125] Timer tick, asking Buildbucket for the build status
[17:50:47.242] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:47.242] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:136:0) after 1m0s
[17:51:47.311] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:136:0)
[17:51:47.311] Timer tick, asking Buildbucket for the build status
[17:51:47.435] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:47.435] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:137:0) after 1m0s
[17:52:47.466] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:137:0)
[17:52:47.466] Timer tick, asking Buildbucket for the build status
[17:52:47.608] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:47.608] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:138:0) after 1m0s
[17:53:47.637] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:138:0)
[17:53:47.637] Timer tick, asking Buildbucket for the build status
[17:53:47.772] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:47.772] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:139:0) after 1m0s
[17:54:47.797] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:139:0)
[17:54:47.797] Timer tick, asking Buildbucket for the build status
[17:54:47.937] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:47.937] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:140:0) after 1m0s
[17:55:47.960] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:140:0)
[17:55:47.960] Timer tick, asking Buildbucket for the build status
[17:55:48.114] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:48.114] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:141:0) after 1m0s
[17:56:48.200] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:141:0)
[17:56:48.200] Timer tick, asking Buildbucket for the build status
[17:56:48.289] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:48.289] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:142:0) after 1m0s
[17:57:48.345] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:142:0)
[17:57:48.345] Timer tick, asking Buildbucket for the build status
[17:57:48.381] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:48.381] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:143:0) after 1m0s
[17:58:48.405] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:143:0)
[17:58:48.405] Timer tick, asking Buildbucket for the build status
[17:58:48.497] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:48.497] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:144:0) after 1m0s
[17:59:48.580] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:144:0)
[17:59:48.580] Timer tick, asking Buildbucket for the build status
[17:59:48.619] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:48.619] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:145:0) after 1m0s
[18:00:48.646] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:145:0)
[18:00:48.646] Timer tick, asking Buildbucket for the build status
[18:00:48.822] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:48.822] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:146:0) after 1m0s
[18:01:48.848] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:146:0)
[18:01:48.848] Timer tick, asking Buildbucket for the build status
[18:01:48.931] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:48.931] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:147:0) after 1m0s
[18:02:48.960] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:147:0)
[18:02:48.960] Timer tick, asking Buildbucket for the build status
[18:02:49.120] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:49.120] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:148:0) after 1m0s
[18:03:49.155] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:148:0)
[18:03:49.155] Timer tick, asking Buildbucket for the build status
[18:03:49.214] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:49.214] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:149:0) after 1m0s
[18:04:49.241] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:149:0)
[18:04:49.241] Timer tick, asking Buildbucket for the build status
[18:04:49.337] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:49.337] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:150:0) after 1m0s
[18:05:49.496] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:150:0)
[18:05:49.496] Timer tick, asking Buildbucket for the build status
[18:05:49.606] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:49.606] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:151:0) after 1m0s
[18:06:51.427] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:151:0)
[18:06:51.427] Timer tick, asking Buildbucket for the build status
[18:06:51.471] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:51.471] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:152:0) after 1m0s
[18:07:51.553] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:152:0)
[18:07:51.553] Timer tick, asking Buildbucket for the build status
[18:07:51.604] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:51.604] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:153:0) after 1m0s
[18:08:52.549] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:153:0)
[18:08:52.549] Timer tick, asking Buildbucket for the build status
[18:08:52.606] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:52.606] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:154:0) after 1m0s
[18:09:53.206] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:154:0)
[18:09:53.206] Timer tick, asking Buildbucket for the build status
[18:09:53.291] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:53.291] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:155:0) after 1m0s
[18:10:55.684] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:155:0)
[18:10:55.684] Timer tick, asking Buildbucket for the build status
[18:10:55.769] Build 8936845495290319136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:55.769] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102275557577861664:156:0) after 1m0s
[18:11:48.528] Received PubSub notification, asking Buildbucket for the build status
[18:11:48.606] Build 8936845495290319136: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[18:11:48.606] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1535566307072230",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1535557003658240",
"failure_reason": "BUILD_FAILURE",
"id": "8936845495290319136",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"4fb91fa970d86bb66a7734f2495138055a9d0c80\"}}",
"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/8936845495290319136/+/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\": [\"3740-90b257a\"], \"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\": \"9a87d6b9de35325efb08321640879e79ed0dfcc9\", \"got_swarming_client_revision\": \"486c9b53c4d54dd4b95bb6ce0e31160e600dfc11\", \"got_revision\": \"4fb91fa970d86bb66a7734f2495138055a9d0c80\", \"recipe\": \"swarming/deterministic_build\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#24472}\", \"$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@{#587128}\", \"branch\": \"refs/heads/master\", \"revision\": \"4fb91fa970d86bb66a7734f2495138055a9d0c80\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"f18b35284288ac851b77db88df3e2e8d2273db97\", \"mastername\": \"chromium.fyi\", \"got_angle_revision\": \"3e217f65044d6d6c6bca789231f9f7866f7ca1ed\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1535557003658240, \"tags\": [\"builder:Windows deterministic\", \"buildset:commit/git/4fb91fa970d86bb66a7734f2495138055a9d0c80\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4fb91fa970d86bb66a7734f2495138055a9d0c80\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9102275557577861664\", \"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\": \"8936845495290319136\"}}, \"got_v8_revision\": \"d4c79096a59b46abaf45aa42a5fdd5b962d95a02\", \"got_v8_revision_cp\": \"refs/heads/7.0.291@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 9913, \"swarm_hashes\": {\"shell_dialogs_unittests\": \"4300933f6c9208236da0cd38497b0fe5023ac1c3\", \"notification_helper_unittests\": \"624f2b110dd85e74eabf42eab515f5b95658342b\", \"gpu_perftests\": \"6fe115d580ea5f2a2aa0fcec74f82ce332c99377\", \"vr_common_unittests\": \"9e17863e8b90228b377060bfe45c0a6d820cfcf5\", \"telemetry_unittests\": \"ee9b6e892900cd150ed6c9d6aee6965943fd757b\", \"load_library_perf_tests\": \"7408924496590b7c78cbc4ae1c232ed2f988daa6\", \"devtools_closure_compile\": \"b593eacd1b0cb4c9739c1894eb92655a27690a3d\", \"chromedriver_py_tests\": \"7bba5ca668da26cc6fb340ca981219c11cdbe741\", \"mojo_core_unittests\": \"b11aceb4c5e3e0340b30331aa49c42fd5f105f7f\", \"install_static_unittests\": \"12de1d030a1beb860a40a3da26951c2bc6d13967\", \"leveldb_service_unittests\": \"6fd15db18746f4b1f4de9c544d43b7a23ed3194c\", \"mini_installer_tests\": \"7ee98bd14410c0bcfff548e95999e1b55130536d\", \"jingle_unittests\": \"24eed5d16d7bd2aa5a387a2a1ebcbe65741b65bf\", \"sbox_integration_tests\": \"d4014a1c681315946e5240547c43df6329855e65\", \"angle_unittests\": \"50a3cddaa3c3f12cac5208d80968b87152044988\", \"cast_unittests\": \"c0adf9c660d09500d55b83036230115fe1eef45b\", \"cc_unittests\": \"a64aa5c5643d5274faebab6b1f1a086c2c041449\", \"blink_common_unittests\": \"d952951f1083662961d7869e8d6a877fcbba2ce7\", \"crashpad_tests\": \"30547d3d44376685247443addb40e0dd95e51bf2\", \"telemetry_perf_tests_without_chrome\": \"c4180362b1d2bebb3b442b85637bfec9638ea147\", \"metrics_python_tests\": \"a54ccbdcb57a0d4e104d4d9469fa880fb724183b\", \"message_center_unittests\": \"f017b2298f31b5fae2887a6a644c2a9bd465784f\", \"ppapi_unittests\": \"6fd07ae6c06390a1ce5637b7d07b408799273842\", \"extensions_unittests\": \"467e34a3aa8a9020a2310c7af70fa17ca40e3481\", \"gl_unittests_ozone\": \"ce5450d008d507c581387c43748ac1a170870ea4\", \"app_shell_unittests\": \"08423fc8678c898b886f8edc2f85f05ec026968b\", \"chrome_app_unittests\": \"e8d96b535e21a5255b2dc9af76a5e9da94f24ed0\", \"gl_unittests\": \"ce5450d008d507c581387c43748ac1a170870ea4\", \"gcm_unit_tests\": \"1e45eaea0b1a369d2a90904e774e4e2ce2398f46\", \"views_unittests\": \"23e5ac0d69e2f7cee188f00fcb30ed78204e0560\", \"content_shell_crash_test\": \"054b71ddc50259edd78d004bae1f4d201913e29d\", \"ui_base_unittests\": \"ff3d26493ae0699a94d84179129e4c2b19f72c70\", \"gles2_conform_test\": \"22c58411a0a973d43d5f306e4e9ad2c2a0ba067e\", \"cronet_unittests\": \"10207ab3b25bf4afe4ef38a02c2a1ba72e390110\", \"performance_test_suite\": \"0b8234ad8442ffbb558ff80fca477c14f4b650f9\", \"wm_unittests\": \"b3e9f553decda80f08adf27077decc704d8003d0\", \"boringssl_crypto_tests\": \"8ee9ffc4e3aafeb994c99a79c318874548c4595b\", \"gin_unittests\": \"f12a9abd8d4a3cb160a75e56f2b03ee4207c59ad\", \"performance_browser_tests\": \"93cdf20462da41ad07c9b4f3911cb82593689db8\", \"extensions_browsertests\": \"a917b2a45696ddfeb79582958219f71c144b3400\", \"webkit_python_tests\": \"c2348cfc454788439c9c6c437853127045bcba58\", \"headless_unittests\": \"733530d019cb4143461c045ced9418abd9951e47\", \"webkit_layout_tests\": \"aa193271a15218399350b52c7e4fb72221de1c33\", \"vr_pixeltests\": \"c6a3347e68380f1361a0bcde18c54c9dd280f974\", \"setup_unittests\": \"7063d52c7d217273b5c3fe356d13c8c4bf2a6eb1\", \"webkit_unit_tests\": \"96fcbc0814af9646f906a3687f1c2a33b5607799\", \"content_unittests\": \"5a0dfde1205016c08cad8ab1369b489c9fa7ac2d\", \"performance_webview_test_suite\": \"47dbcdb4c7f91ce8a7b8b741d138f15233dfb2da\", \"wtf_unittests\": \"631210ee50dfc989822b2919ee80d68d46e1c902\", \"webkit_layout_tests_exparchive\": \"c8fd8e074e605f3ed304d420aba4cfb14dc29d0e\", \"chrome_cleaner_unittests\": \"870548b59fc131eb2657fb8c99c515e3047efa20\", \"traffic_annotation_auditor_unittests\": \"1517d2b3e9ade7328357c28aa455f95a2a71be0f\", \"libjingle_xmpp_unittests\": \"1cefc7aa932e512949d77a36dedf35ab20082be9\", \"remoting_unittests\": \"19df8fd10c91a9a2a2854ceeaca60117d0a4d621\", \"vr_common_perftests\": \"c6cb7e974edbd5e90bfc1b1ceb640342fd78a493\", \"filesystem_service_unittests\": \"e93cce981021d092f8bd6565566542155ecb7a09\", \"chrome_elf_import_unittests\": \"05b7a1e0773bcac22d77ba31542f7fa1de637204\", \"installer_util_unittests\": \"3c889575c9766b7b43258a93d9e42efe13a5dbe3\", \"gfx_unittests\": \"38591a137b3ae62edc681318f0bd3a35bce01791\", \"headless_browsertests\": \"e542c4258d7db9f84805b08265626c8bc9711331\", \"capture_unittests\": \"448704f7785a85799afe75daea57dcad819f8892\", \"chrome_elf_unittests\": \"d02e0e12fd90eec8613108e6dff124e8ff6bf561\", \"telemetry_gpu_unittests\": \"c7325102c42240da52f77f5b9ece7d24a94cafcb\", \"command_buffer_perftests\": \"bb831d7bdb6d716bf990c574573647363643e203\", \"media_unittests\": \"012724489332bb3b0f61674b9da0b76f7236bd0b\", \"chromedriver_unittests\": \"523b07f0c253a7a1b21826aa4917b23665608fd8\", \"views_mus_interactive_ui_tests\": \"157b3f1937143bfe768db27dd21053207c6769ad\", \"storage_unittests\": \"3dad4e6cf9cd76edc9d7639547751c5f4ffe30ab\", \"media_service_unittests\": \"816fd4e1999c5f65c1a11100b569f1e483929ba3\", \"compositor_unittests\": \"a3635f5e22f5e3f9aa17ac73a19031f2bfb9c924\", \"latency_unittests\": \"92734879cf1778c82a3af2ca5f984be29afe53db\", \"video_decode_accelerator_unittest\": \"31422b065b58fc3c421b77608ea220ff1a4955ab\", \"swiftshader_unittests\": \"adf1c55d38a6603865d01ff67a171a6fb0ab0ac3\", \"telemetry_perf_unittests\": \"00fbbd89eea4f98e9f357957c0ed01718386749a\", \"media_blink_unittests\": \"80d79f798ff53860562052a5603570d6e6f12759\", \"views_mus_unittests\": \"b153274259ed606e26116c8f17c2d47cd8a050b4\", \"components_unittests\": \"7047e5ba60787721051274a94dbc67a5f8430111\", \"snapshot_unittests\": \"4f1d42c957fa8f8be7668295adeca263d5d79395\", \"accessibility_unittests\": \"bb49d677bd214ee40f3c5ca2e75c68302dac9cb7\", \"net_perftests\": \"5878af4a1239e2e809b6e13b626de5ac7fea776e\", \"aura_unittests\": \"8090c8972492d0854d3f9275a79a882bb3e99e6e\", \"services_unittests\": \"ea8ba71a9d48dd2dcdafa955d0b7ba83be019708\", \"components_perftests\": \"98810ca10dd0e7b1d8064f75865bf5a5959c3c64\", \"events_unittests\": \"6a34662d97de4fcbc61e56a7768b0812c22be989\", \"tracing_perftests\": \"2ee1bc3ee922b56c908413863d462debd9cb7893\", \"chromedriver_replay_unittests\": \"4066e84b2cc089e4c36c06bcefa376aaad92f41e\", \"ui_touch_selection_unittests\": \"d6994c44093624375ec59cb857a0bf61b0b5c215\", \"cacheinvalidation_unittests\": \"ee7cd01d29c21a972caf4687ca7b953570ead241\", \"viz_unittests\": \"394f64cff26f3d441d74c7f88ed15479d19db098\", \"browser_tests\": \"47c77a683471ed773a0b067483b2d38ec47561d9\", \"net_unittests\": \"02cadb07dad8eb7fea4ef69565e8f502023647f2\", \"midi_unittests\": \"c77d0da2c3d8ce40407748156692068cf80a57b1\", \"boringssl_ssl_tests\": \"22eccd0bd505a1a06436403e954671369ba77f32\", \"sbox_validation_tests\": \"66a94a9ef418d3e5369f65e343cd69e60d5c9ba8\", \"elevation_service_unittests\": \"78386f1d60b2069bb43c8bd6ca1937ae76434b55\", \"gpu_unittests\": \"7f3089b215c85d0b94ba824eb74c363ab26abb83\", \"native_theme_unittests\": \"d8c1023a9227a625df7db1d966db27628089944a\", \"interactive_ui_tests\": \"e99fdeba85385d8dead5b31737a716d680128c9f\", \"mojo_unittests\": \"6fc94d45834a9d8d77fc3d24f8f36941b00f8713\", \"url_unittests\": \"9ddfe96599670ad8e9da9324459e61430a8c90ed\", \"media_perftests\": \"260d2060bd98bb91ee0b913826f1c8eecec5aa34\", \"cronet_tests\": \"d0acecf5dcbab495f78c7d211c083f45f7184bef\", \"google_apis_unittests\": \"86a6b6563e0475c02d1007c62e9e693115be969e\", \"telemetry_gpu_integration_test\": \"d9e964354ea67293084c09a9705f9d21c8661295\", \"blink_heap_unittests\": \"53c07a2fb42fc4a38f61f8c6d6c7895a6aa09154\", \"base_unittests\": \"7825160df091de8a68aa00570e81a49e24a74f68\", \"sync_integration_tests\": \"1bfeb97b5a71151a968e1b6e36fe580c4bd8709d\", \"sql_unittests\": \"3a4244a961de95157c756064d4c7fc4170b4b6a0\", \"courgette_unittests\": \"573ab9df178f01ee40867769a7c76c60a8b430df\", \"blink_fuzzer_unittests\": \"8b9b26258f4064dd515f592c8317d6485858efb2\", \"gl_tests\": \"abc18d7b5e5a6122e3c4c2362d75511acbe4fc11\", \"zucchini_unittests\": \"1d73cda5fc45a0eb57f213d64a30aa917ccae642\", \"ipc_tests\": \"4210b231cb902737b6271c16ccb517700b5263bf\", \"angle_end2end_tests\": \"753fcb5c8c5460dc3dc90fdc3201e8a880882d9e\", \"blink_platform_unittests\": \"cd4e68daa66638dc7b8cd5e7af711c7fec365dc2\", \"components_browsertests\": \"fb574d3847c3ff7fa7d07365f4ece157399e4bd4\", \"views_perftests\": \"5fd93059b9167a5ab4b42f623332999638b55293\", \"angle_perftests\": \"21f99d84cad137be5143bdcad5d7ff0e05ba4157\", \"display_unittests\": \"5b7055caca51002bdeaa8517428856b95dfd68e9\", \"sbox_unittests\": \"4c909fcfd7a80b6c8af39b6acb990d41c54dc4be\", \"service_manager_unittests\": \"10c96b5bb87a2bc294ff59d327be98d09a8c39d3\", \"pdf_unittests\": \"0b10eeb65235613b8f3d593e41ca6e8b8b158446\", \"skia_unittests\": \"38070541795ff3ffb720ce113171e626e05d2acf\", \"angle_white_box_tests\": \"a250851b1ad355dd6063b36d1d0debf380aa89aa\", \"crypto_unittests\": \"18d52816772f2fb273faf0b38dde0eb43f39587f\", \"nacl_loader_unittests\": \"667169a819fe7a5d4ab9b9116a34b3305134eded\", \"unit_tests\": \"b449ba0d64d05005803e2bdf9b7666f980892248\", \"content_browsertests\": \"edfc0d580f4e8ac8fef0c2d6dcebdd6af9a06990\", \"printing_unittests\": \"a61ba7ae68dca820d1e441a5eb074666fbf3aacf\", \"device_unittests\": \"51d6bb59eba14621786c3f25b6a2a5f03c8e5a88\"}, \"path_config\": \"generic\", \"bot_id\": \"swarm2173-c4\", \"got_buildtools_revision\": \"2dff9c9c74e9d732e6fe57c84ef7fd044cc45d96\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1535557105386800",
"status": "COMPLETED",
"status_changed_ts": "1535566307885670",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/9913",
"builder:Windows deterministic",
"buildset:commit/git/4fb91fa970d86bb66a7734f2495138055a9d0c80",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4fb91fa970d86bb66a7734f2495138055a9d0c80",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9102275557577861664",
"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/9913",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8936845495290319136",
"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/4fb91fa970d86bb66a7734f2495138055a9d0c80",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4fb91fa970d86bb66a7734f2495138055a9d0c80",
"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/8936845495290319136/+/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:9102275557577861664",
"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:e1e1f1cf15fde3b8cf0aaba2d46508c232f3b6af",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:3f9f25f473876010",
"user_agent:luci-scheduler"
],
"updated_ts": "1535566307887570",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/9913",
"utcnow_ts": "1535566308571150"
}
}
[18:11:48.606] Invocation finished in 2h35m6.513112765s with status FAILED