[04:40:54.362] New invocation is queued and will start shortly
[04:40:55.620] Starting the invocation (attempt 1)
[04:40:55.665] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:40:55.665] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:40:55.665] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9098783534994966624",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"a849c78b38bb9805a6c3fea7edba02811ead600c\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9098783534994966624",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/a849c78b38bb9805a6c3fea7edba02811ead600c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a849c78b38bb9805a6c3fea7edba02811ead600c",
"gitiles_ref:refs/heads/master"
]
}
[04:40:57.389] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1538887255811589",
"id": "8933353472808997072",
"lease_expiration_ts": "1539031257117472",
"lease_key": "404058206",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"a849c78b38bb9805a6c3fea7edba02811ead600c\"}}",
"project": "chromium",
"result_details_json": "null",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1538887257273956",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/10324",
"builder:Windows deterministic",
"buildset:commit/git/a849c78b38bb9805a6c3fea7edba02811ead600c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a849c78b38bb9805a6c3fea7edba02811ead600c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9098783534994966624",
"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/10324",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8933353472808997072",
"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/a849c78b38bb9805a6c3fea7edba02811ead600c",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a849c78b38bb9805a6c3fea7edba02811ead600c",
"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/8933353472808997072/+/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:9098783534994966624",
"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:7a2c6a280a68ea7a8c9976d934a89c38009336b9",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:4065a592bb5c8110",
"user_agent:luci-scheduler"
],
"updated_ts": "1538887257274075",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/10324",
"utcnow_ts": "1538887257372019"
}
}
[04:40:57.389] Task URL: https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/10324
[04:40:57.389] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:2:0) after 1m0s
[04:40:59.369] Received PubSub notification, asking Buildbucket for the build status
[04:40:59.428] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:57.491] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:2:0)
[04:41:57.491] Timer tick, asking Buildbucket for the build status
[04:41:57.605] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:57.605] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:4:0) after 1m0s
[04:42:57.639] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:4:0)
[04:42:57.639] Timer tick, asking Buildbucket for the build status
[04:42:57.731] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:57.731] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:5:0) after 1m0s
[04:43:57.801] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:5:0)
[04:43:57.801] Timer tick, asking Buildbucket for the build status
[04:43:57.868] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:57.868] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:6:0) after 1m0s
[04:44:57.909] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:6:0)
[04:44:57.909] Timer tick, asking Buildbucket for the build status
[04:44:57.981] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:57.981] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:7:0) after 1m0s
[04:45:57.997] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:7:0)
[04:45:57.997] Timer tick, asking Buildbucket for the build status
[04:45:58.096] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:58.096] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:8:0) after 1m0s
[04:46:58.136] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:8:0)
[04:46:58.136] Timer tick, asking Buildbucket for the build status
[04:46:58.233] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:58.233] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:9:0) after 1m0s
[04:47:58.253] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:9:0)
[04:47:58.253] Timer tick, asking Buildbucket for the build status
[04:47:58.307] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:58.307] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:10:0) after 1m0s
[04:48:58.421] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:10:0)
[04:48:58.421] Timer tick, asking Buildbucket for the build status
[04:48:58.500] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:58.500] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:11:0) after 1m0s
[04:49:58.594] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:11:0)
[04:49:58.594] Timer tick, asking Buildbucket for the build status
[04:49:58.893] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:58.893] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:12:0) after 1m0s
[04:50:58.981] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:12:0)
[04:50:58.981] Timer tick, asking Buildbucket for the build status
[04:50:59.031] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:59.031] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:13:0) after 1m0s
[04:51:59.198] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:13:0)
[04:51:59.198] Timer tick, asking Buildbucket for the build status
[04:51:59.401] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:59.401] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:14:0) after 1m0s
[04:52:59.523] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:14:0)
[04:52:59.523] Timer tick, asking Buildbucket for the build status
[04:52:59.602] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:59.602] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:15:0) after 1m0s
[04:53:59.661] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:15:0)
[04:53:59.661] Timer tick, asking Buildbucket for the build status
[04:53:59.772] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:59.772] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:16:0) after 1m0s
[04:54:59.847] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:16:0)
[04:54:59.847] Timer tick, asking Buildbucket for the build status
[04:54:59.971] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:59.971] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:17:0) after 1m0s
[04:56:00.056] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:17:0)
[04:56:00.056] Timer tick, asking Buildbucket for the build status
[04:56:00.172] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:00.172] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:18:0) after 1m0s
[04:57:00.202] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:18:0)
[04:57:00.202] Timer tick, asking Buildbucket for the build status
[04:57:00.263] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:00.263] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:19:0) after 1m0s
[04:58:00.369] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:19:0)
[04:58:00.369] Timer tick, asking Buildbucket for the build status
[04:58:00.723] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:00.723] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:20:0) after 1m0s
[04:59:00.740] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:20:0)
[04:59:00.740] Timer tick, asking Buildbucket for the build status
[04:59:01.023] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:01.023] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:21:0) after 1m0s
[05:00:01.042] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:21:0)
[05:00:01.042] Timer tick, asking Buildbucket for the build status
[05:00:01.185] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:01.185] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:22:0) after 1m0s
[05:01:01.207] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:22:0)
[05:01:01.207] Timer tick, asking Buildbucket for the build status
[05:01:01.285] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:01.285] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:23:0) after 1m0s
[05:02:01.307] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:23:0)
[05:02:01.307] Timer tick, asking Buildbucket for the build status
[05:02:01.463] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:01.463] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:24:0) after 1m0s
[05:03:01.514] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:24:0)
[05:03:01.514] Timer tick, asking Buildbucket for the build status
[05:03:01.584] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:01.584] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:25:0) after 1m0s
[05:04:01.617] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:25:0)
[05:04:01.617] Timer tick, asking Buildbucket for the build status
[05:04:02.042] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:02.042] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:26:0) after 1m0s
[05:05:02.071] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:26:0)
[05:05:02.071] Timer tick, asking Buildbucket for the build status
[05:05:02.192] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:02.192] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:27:0) after 1m0s
[05:06:02.279] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:27:0)
[05:06:02.279] Timer tick, asking Buildbucket for the build status
[05:06:02.358] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:02.358] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:28:0) after 1m0s
[05:07:02.438] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:28:0)
[05:07:02.438] Timer tick, asking Buildbucket for the build status
[05:07:02.545] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:02.545] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:29:0) after 1m0s
[05:08:02.641] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:29:0)
[05:08:02.641] Timer tick, asking Buildbucket for the build status
[05:08:02.700] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:02.700] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:30:0) after 1m0s
[05:09:02.776] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:30:0)
[05:09:02.776] Timer tick, asking Buildbucket for the build status
[05:09:02.851] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:02.851] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:31:0) after 1m0s
[05:10:02.904] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:31:0)
[05:10:02.904] Timer tick, asking Buildbucket for the build status
[05:10:02.996] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:02.996] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:32:0) after 1m0s
[05:11:03.104] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:32:0)
[05:11:03.104] Timer tick, asking Buildbucket for the build status
[05:11:03.235] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:03.235] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:33:0) after 1m0s
[05:12:03.257] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:33:0)
[05:12:03.257] Timer tick, asking Buildbucket for the build status
[05:12:03.344] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:03.344] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:34:0) after 1m0s
[05:13:03.405] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:34:0)
[05:13:03.405] Timer tick, asking Buildbucket for the build status
[05:13:03.701] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:03.701] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:35:0) after 1m0s
[05:14:03.745] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:35:0)
[05:14:03.745] Timer tick, asking Buildbucket for the build status
[05:14:03.827] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:03.827] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:36:0) after 1m0s
[05:15:03.844] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:36:0)
[05:15:03.844] Timer tick, asking Buildbucket for the build status
[05:15:04.066] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:04.066] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:37:0) after 1m0s
[05:16:04.087] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:37:0)
[05:16:04.087] Timer tick, asking Buildbucket for the build status
[05:16:04.178] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:04.178] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:38:0) after 1m0s
[05:17:04.195] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:38:0)
[05:17:04.195] Timer tick, asking Buildbucket for the build status
[05:17:04.273] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:04.273] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:39:0) after 1m0s
[05:18:04.347] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:39:0)
[05:18:04.347] Timer tick, asking Buildbucket for the build status
[05:18:04.431] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:04.431] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:40:0) after 1m0s
[05:19:04.449] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:40:0)
[05:19:04.449] Timer tick, asking Buildbucket for the build status
[05:19:04.486] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:04.486] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:41:0) after 1m0s
[05:20:04.502] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:41:0)
[05:20:04.502] Timer tick, asking Buildbucket for the build status
[05:20:04.579] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:04.579] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:42:0) after 1m0s
[05:21:04.743] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:42:0)
[05:21:04.743] Timer tick, asking Buildbucket for the build status
[05:21:04.870] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:04.870] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:43:0) after 1m0s
[05:22:04.930] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:43:0)
[05:22:04.930] Timer tick, asking Buildbucket for the build status
[05:22:04.989] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:04.989] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:44:0) after 1m0s
[05:23:05.009] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:44:0)
[05:23:05.009] Timer tick, asking Buildbucket for the build status
[05:23:05.126] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:05.126] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:45:0) after 1m0s
[05:24:05.201] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:45:0)
[05:24:05.201] Timer tick, asking Buildbucket for the build status
[05:24:05.277] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:05.277] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:46:0) after 1m0s
[05:25:05.297] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:46:0)
[05:25:05.297] Timer tick, asking Buildbucket for the build status
[05:25:05.377] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:05.377] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:47:0) after 1m0s
[05:26:05.394] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:47:0)
[05:26:05.394] Timer tick, asking Buildbucket for the build status
[05:26:05.468] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:05.468] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:48:0) after 1m0s
[05:27:05.495] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:48:0)
[05:27:05.495] Timer tick, asking Buildbucket for the build status
[05:27:05.643] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:05.643] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:49:0) after 1m0s
[05:28:05.665] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:49:0)
[05:28:05.665] Timer tick, asking Buildbucket for the build status
[05:28:05.706] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:05.706] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:50:0) after 1m0s
[05:29:05.724] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:50:0)
[05:29:05.724] Timer tick, asking Buildbucket for the build status
[05:29:05.783] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:05.783] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:51:0) after 1m0s
[05:30:05.887] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:51:0)
[05:30:05.887] Timer tick, asking Buildbucket for the build status
[05:30:05.937] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:05.937] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:52:0) after 1m0s
[05:31:05.980] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:52:0)
[05:31:05.980] Timer tick, asking Buildbucket for the build status
[05:31:06.042] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:06.042] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:53:0) after 1m0s
[05:32:06.068] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:53:0)
[05:32:06.068] Timer tick, asking Buildbucket for the build status
[05:32:06.108] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:06.108] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:54:0) after 1m0s
[05:33:06.295] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:54:0)
[05:33:06.295] Timer tick, asking Buildbucket for the build status
[05:33:06.421] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:06.421] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:55:0) after 1m0s
[05:34:06.445] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:55:0)
[05:34:06.445] Timer tick, asking Buildbucket for the build status
[05:34:06.749] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:06.750] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:56:0) after 1m0s
[05:35:06.772] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:56:0)
[05:35:06.772] Timer tick, asking Buildbucket for the build status
[05:35:06.949] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:06.949] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:57:0) after 1m0s
[05:36:07.022] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:57:0)
[05:36:07.022] Timer tick, asking Buildbucket for the build status
[05:36:07.072] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:07.072] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:58:0) after 1m0s
[05:37:07.124] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:58:0)
[05:37:07.124] Timer tick, asking Buildbucket for the build status
[05:37:07.209] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:07.209] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:59:0) after 1m0s
[05:38:07.244] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:59:0)
[05:38:07.244] Timer tick, asking Buildbucket for the build status
[05:38:07.364] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:07.364] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:60:0) after 1m0s
[05:39:07.434] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:60:0)
[05:39:07.434] Timer tick, asking Buildbucket for the build status
[05:39:07.650] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:07.650] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:61:0) after 1m0s
[05:40:07.671] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:61:0)
[05:40:07.671] Timer tick, asking Buildbucket for the build status
[05:40:07.832] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:07.832] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:62:0) after 1m0s
[05:41:07.854] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:62:0)
[05:41:07.854] Timer tick, asking Buildbucket for the build status
[05:41:07.931] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:07.931] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:63:0) after 1m0s
[05:42:07.971] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:63:0)
[05:42:07.971] Timer tick, asking Buildbucket for the build status
[05:42:08.056] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:08.056] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:64:0) after 1m0s
[05:43:08.076] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:64:0)
[05:43:08.076] Timer tick, asking Buildbucket for the build status
[05:43:08.162] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:43:08.162] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:65:0) after 1m0s
[05:44:08.180] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:65:0)
[05:44:08.180] Timer tick, asking Buildbucket for the build status
[05:44:08.236] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:44:08.236] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:66:0) after 1m0s
[05:45:08.302] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:66:0)
[05:45:08.302] Timer tick, asking Buildbucket for the build status
[05:45:08.348] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:45:08.348] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:67:0) after 1m0s
[05:46:08.367] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:67:0)
[05:46:08.367] Timer tick, asking Buildbucket for the build status
[05:46:08.514] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:46:08.514] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:68:0) after 1m0s
[05:47:08.545] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:68:0)
[05:47:08.545] Timer tick, asking Buildbucket for the build status
[05:47:08.636] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:47:08.636] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:69:0) after 1m0s
[05:48:08.655] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:69:0)
[05:48:08.655] Timer tick, asking Buildbucket for the build status
[05:48:08.722] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:48:08.722] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:70:0) after 1m0s
[05:49:08.741] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:70:0)
[05:49:08.741] Timer tick, asking Buildbucket for the build status
[05:49:08.806] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:49:08.806] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:71:0) after 1m0s
[05:50:08.825] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:71:0)
[05:50:08.825] Timer tick, asking Buildbucket for the build status
[05:50:09.018] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:50:09.019] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:72:0) after 1m0s
[05:51:09.042] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:72:0)
[05:51:09.042] Timer tick, asking Buildbucket for the build status
[05:51:09.097] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:51:09.097] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:73:0) after 1m0s
[05:52:09.118] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:73:0)
[05:52:09.118] Timer tick, asking Buildbucket for the build status
[05:52:09.188] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:52:09.188] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:74:0) after 1m0s
[05:53:09.218] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:74:0)
[05:53:09.218] Timer tick, asking Buildbucket for the build status
[05:53:09.348] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:53:09.348] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:75:0) after 1m0s
[05:54:09.368] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:75:0)
[05:54:09.368] Timer tick, asking Buildbucket for the build status
[05:54:09.445] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:54:09.445] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:76:0) after 1m0s
[05:55:09.524] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:76:0)
[05:55:09.525] Timer tick, asking Buildbucket for the build status
[05:55:09.697] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:55:09.697] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:77:0) after 1m0s
[05:56:09.795] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:77:0)
[05:56:09.795] Timer tick, asking Buildbucket for the build status
[05:56:09.870] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:56:09.870] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:78:0) after 1m0s
[05:57:09.892] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:78:0)
[05:57:09.892] Timer tick, asking Buildbucket for the build status
[05:57:10.004] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:57:10.005] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:79:0) after 1m0s
[05:58:10.024] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:79:0)
[05:58:10.024] Timer tick, asking Buildbucket for the build status
[05:58:10.079] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:58:10.079] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:80:0) after 1m0s
[05:59:10.103] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:80:0)
[05:59:10.103] Timer tick, asking Buildbucket for the build status
[05:59:10.233] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:59:10.233] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:81:0) after 1m0s
[06:00:10.260] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:81:0)
[06:00:10.260] Timer tick, asking Buildbucket for the build status
[06:00:10.343] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:00:10.343] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:82:0) after 1m0s
[06:01:10.364] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:82:0)
[06:01:10.364] Timer tick, asking Buildbucket for the build status
[06:01:10.402] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:10.402] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:83:0) after 1m0s
[06:02:10.420] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:83:0)
[06:02:10.420] Timer tick, asking Buildbucket for the build status
[06:02:10.463] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:02:10.463] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:84:0) after 1m0s
[06:03:10.483] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:84:0)
[06:03:10.483] Timer tick, asking Buildbucket for the build status
[06:03:10.565] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:03:10.565] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:85:0) after 1m0s
[06:04:10.603] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:85:0)
[06:04:10.603] Timer tick, asking Buildbucket for the build status
[06:04:10.758] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:04:10.758] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:86:0) after 1m0s
[06:05:10.812] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:86:0)
[06:05:10.812] Timer tick, asking Buildbucket for the build status
[06:05:10.900] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:05:10.900] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:87:0) after 1m0s
[06:06:10.938] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:87:0)
[06:06:10.938] Timer tick, asking Buildbucket for the build status
[06:06:11.029] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:06:11.029] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:88:0) after 1m0s
[06:07:11.142] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:88:0)
[06:07:11.142] Timer tick, asking Buildbucket for the build status
[06:07:11.181] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:07:11.181] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:89:0) after 1m0s
[06:08:11.238] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:89:0)
[06:08:11.238] Timer tick, asking Buildbucket for the build status
[06:08:11.442] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:08:11.442] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:90:0) after 1m0s
[06:09:11.508] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:90:0)
[06:09:11.508] Timer tick, asking Buildbucket for the build status
[06:09:11.608] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:09:11.608] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:91:0) after 1m0s
[06:10:11.646] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:91:0)
[06:10:11.646] Timer tick, asking Buildbucket for the build status
[06:10:11.686] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:10:11.686] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:92:0) after 1m0s
[06:11:11.725] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:92:0)
[06:11:11.725] Timer tick, asking Buildbucket for the build status
[06:11:11.822] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:11.822] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:93:0) after 1m0s
[06:12:11.846] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:93:0)
[06:12:11.846] Timer tick, asking Buildbucket for the build status
[06:12:11.920] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:11.920] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:94:0) after 1m0s
[06:13:11.941] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:94:0)
[06:13:11.941] Timer tick, asking Buildbucket for the build status
[06:13:12.003] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:12.003] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:95:0) after 1m0s
[06:14:12.023] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:95:0)
[06:14:12.023] Timer tick, asking Buildbucket for the build status
[06:14:12.131] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:12.131] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:96:0) after 1m0s
[06:15:12.155] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:96:0)
[06:15:12.155] Timer tick, asking Buildbucket for the build status
[06:15:12.210] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:12.210] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:97:0) after 1m0s
[06:16:12.302] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:97:0)
[06:16:12.302] Timer tick, asking Buildbucket for the build status
[06:16:12.502] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:16:12.502] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:98:0) after 1m0s
[06:17:12.614] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:98:0)
[06:17:12.614] Timer tick, asking Buildbucket for the build status
[06:17:12.693] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:12.693] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:99:0) after 1m0s
[06:18:12.712] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:99:0)
[06:18:12.712] Timer tick, asking Buildbucket for the build status
[06:18:12.846] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:12.846] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:100:0) after 1m0s
[06:19:12.878] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:100:0)
[06:19:12.878] Timer tick, asking Buildbucket for the build status
[06:19:13.015] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:13.015] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:101:0) after 1m0s
[06:20:13.039] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:101:0)
[06:20:13.039] Timer tick, asking Buildbucket for the build status
[06:20:13.081] Build 8933353472808997072: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:13.081] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9098783534994966624:102:0) after 1m0s
[06:21:03.843] Received PubSub notification, asking Buildbucket for the build status
[06:21:03.894] Build 8933353472808997072: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[06:21:03.894] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1538893262690907",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1538887255811589",
"id": "8933353472808997072",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"a849c78b38bb9805a6c3fea7edba02811ead600c\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"ui\": {\"info\": \"\"}, \"build_run_result\": {\"infraFailure\": null, \"recipeResult\": {\"jsonResult\": \"null\"}, \"recipeExitCode\": {\"value\": 0}, \"recipe\": {\"name\": \"swarming/deterministic_build\", \"repository\": \"\", \"revision\": \"\"}, \"annotationUrl\": \"logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8933353472808997072/+/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\": [\"3801-8761768\"], \"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\": \"a849c78b38bb9805a6c3fea7edba02811ead600c\", \"recipe\": \"swarming/deterministic_build\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#25031}\", \"$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@{#597451}\", \"branch\": \"refs/heads/master\", \"revision\": \"a849c78b38bb9805a6c3fea7edba02811ead600c\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"343f4144bee5ddde5efec1e1217403b444386cb4\", \"mastername\": \"chromium.fyi\", \"got_angle_revision\": \"450bf36f7918d18f3186be8ce570d9eac63b309d\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1538887255811589, \"tags\": [\"builder:Windows deterministic\", \"buildset:commit/git/a849c78b38bb9805a6c3fea7edba02811ead600c\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a849c78b38bb9805a6c3fea7edba02811ead600c\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9098783534994966624\", \"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\": \"8933353472808997072\"}}, \"got_v8_revision\": \"f7f89bcbe7a90ac5cc9a8535248f091c1af3720a\", \"got_v8_revision_cp\": \"refs/heads/7.1.277@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 10324, \"swarm_hashes\": {\"shell_dialogs_unittests\": \"2b8b00cf55a8e542d518a56caa037e64ba4cf0a0\", \"notification_helper_unittests\": \"f29a03f40cb85db01f016d58bbbbe33c3a0d71f9\", \"gpu_perftests\": \"7a6ec0b14acd12cb4ccd7cb9eabbc27a521f0587\", \"vr_common_unittests\": \"24fd4062c18e8b21412cf84427d8bfc08a50619d\", \"telemetry_unittests\": \"b75b032398f134c80981d30bec44ea89abced429\", \"load_library_perf_tests\": \"f892a497878c9a4e69828a18293ce457c27f80c6\", \"devtools_closure_compile\": \"947ceeb4ceaa909d325ac331beebc7cb1b6ac0a7\", \"chromedriver_py_tests\": \"58f61a152586b0c10ed29101ef367cf330c8a55b\", \"mojo_core_unittests\": \"2e41cce0117970fe692237d7dcf6a29d308bbeab\", \"install_static_unittests\": \"b1c2846100ca1e8fb40aa2fe5e950d22781182b9\", \"leveldb_service_unittests\": \"6ee4f1d12f08f692f30985efa3a95286a55d2265\", \"mini_installer_tests\": \"99bf0970ce0d41fb39b5d3983490a9a2feec812d\", \"jingle_unittests\": \"4199af37f7445d3e3e2d38bbe2f5acc13e4f5397\", \"sbox_integration_tests\": \"8c1c1c5376dc16d23fd3195d3d49b1a25172f74c\", \"angle_unittests\": \"fdb4facbe3943a6e18c603100d56dc0bbbfd1333\", \"cast_unittests\": \"f0de3dd3c47f1a531c120ec7a4e9409111bb6264\", \"cc_unittests\": \"49d6b3f8edae9b1043dbdf1e0ca3fee9de06bc7d\", \"blink_common_unittests\": \"97d8adc8cd4ea932d7ebedaa01ae0198afc9c291\", \"crashpad_tests\": \"f48764fdb3ad7cd7a7b17608ab088b55b2c50d06\", \"telemetry_perf_tests_without_chrome\": \"1e057d86c013d05f8c94963c8cc9f3aff1bf257d\", \"metrics_python_tests\": \"b4e4090d7c42df6b958c4a40710412bed7ee9ad6\", \"message_center_unittests\": \"92ae8e182606f679a9455465309f6df2e4f2c682\", \"ppapi_unittests\": \"42f2a87fb11e266d2e3b56ffcfedc4549f245724\", \"extensions_unittests\": \"ab933bae24cb4cfb3995523dd42e7ffee7e505bd\", \"gl_unittests_ozone\": \"3a3b710a16f50efe9947d4ac5b918cb4b5902f44\", \"app_shell_unittests\": \"46b65e04152855613f483f093a4d9291d024bd0f\", \"chrome_app_unittests\": \"13ec2f6dc8641159e437c15bb4235862145b6fc3\", \"gl_unittests\": \"3a3b710a16f50efe9947d4ac5b918cb4b5902f44\", \"gcm_unit_tests\": \"c4e51edaab652885be4a97bd37d799039dfa8b26\", \"views_unittests\": \"cc154be0e86ba833b53734bb25633412fe5f22c3\", \"content_shell_crash_test\": \"2708044876fd4bce462333fe16e63b0100f93341\", \"ui_base_unittests\": \"62482edf3586ccef1f2d5d3c3150fff8d5889313\", \"gles2_conform_test\": \"6efa037556c4c90ed80915795c6d1bdd68f0422c\", \"cronet_unittests\": \"142f0c4a04a047eea4ca37b8c3c15921c6d826fb\", \"performance_test_suite\": \"3ef08e9e39e4fe421c0c5e19f95a267dbd444bf6\", \"wm_unittests\": \"42c602a9f79948d52910145325547b1c208e1b9a\", \"boringssl_crypto_tests\": \"76b00aa8b78058f21ddd777e5fc991a9f7faf440\", \"gin_unittests\": \"3cd2e07672feaf9cb21d14536ccc025c1f2b952e\", \"performance_browser_tests\": \"5fb1c3756fd10aba0427b0f2a5f2b6121e1e5e3b\", \"extensions_browsertests\": \"f4d8df4c9135f73f3a65f94d51e746039052544f\", \"webkit_python_tests\": \"1182026ac5ebb0aa298541a05a56d3d287256f41\", \"headless_unittests\": \"6c4abb53bedad54fc34fde0f7868dd878f652139\", \"webkit_layout_tests\": \"73d3ff5ca5022dfd67298acf8f5f90fabaea94ff\", \"vr_pixeltests\": \"92057034b6f1dc812de8a07c95466cb7d32bea53\", \"setup_unittests\": \"4e74a1c41305be48fa4965d938f338d3a49ea31c\", \"webkit_unit_tests\": \"268c2b0fffedd154754055f605b6ae0ef798c2fc\", \"content_unittests\": \"5fec1c5cf572a04ca8b3a62f71a909ce87b01463\", \"performance_webview_test_suite\": \"a009e53899a385a50162abc4abe2c165df693418\", \"wtf_unittests\": \"adea6a8727c5e6a904230e12b634d19691bb8a04\", \"webkit_layout_tests_exparchive\": \"6a023f63d49ae9c8f35d5de1a793d2cc051a07d0\", \"chrome_cleaner_unittests\": \"f842a4c60035eb042ec5f261b62fb1924a6afd8a\", \"traffic_annotation_auditor_unittests\": \"8f616183037e13e45d0da0dbc13bdd0d1f031279\", \"libjingle_xmpp_unittests\": \"5243e0023cc94931975d8a1048e63f1e19eb20ad\", \"remoting_unittests\": \"8314547dd48ced5ae54418500f817f3e11c4d908\", \"vr_common_perftests\": \"864a9ce5aed366d72e07331fba8c4f37ad6f376f\", \"filesystem_service_unittests\": \"182d2bdc8da2a2ec999c790a298154dccb458944\", \"chrome_elf_import_unittests\": \"5a4a9d821085efa28bb52e3f1614941d79ab882e\", \"installer_util_unittests\": \"2a8b9ba7cccf6dcaeb01d28bd77790afb84cc368\", \"gfx_unittests\": \"fbc053ddbe4f0b809f98459934420284ca95f74a\", \"headless_browsertests\": \"03507d710209eb1a69ebdac9ee837009b937efa3\", \"capture_unittests\": \"d7141bec1561f2e088b47a7f2631dd9854b9021f\", \"chrome_elf_unittests\": \"a748947ae07866b5f8bd2bf1234d03581ad62d3c\", \"telemetry_gpu_unittests\": \"74deb9a1eacc0a042f05d8bc40d1ee07a6fc879d\", \"command_buffer_perftests\": \"d47a8b9df5cb5cf5c881ba91fe84998c0767e768\", \"media_unittests\": \"6da8f36fbfdd50e8b20689a60272be5a004eeb04\", \"chromedriver_unittests\": \"f882abcea9ed9157fac495d0392d2df3422e74af\", \"views_mus_interactive_ui_tests\": \"4f0dadb754f809b441b19e755dbd09b0e1b743bc\", \"storage_unittests\": \"ea9d364b7ea0190b47301b3978cefdeef3e084ea\", \"media_service_unittests\": \"0b3837ea90ef32135c5de563be698b793b465ff6\", \"compositor_unittests\": \"8af01d0cd07963d4e5760c2078513f0c68cbeaa2\", \"latency_unittests\": \"6239af7dd83a022fa9bca3e9c7458a8a9d87d352\", \"video_decode_accelerator_unittest\": \"e6d9d9b753ba8166d2f8d0499d87e685e0d109a5\", \"swiftshader_unittests\": \"5d7fddbc688528b7f4c07ebd04fc2e61773d1829\", \"telemetry_perf_unittests\": \"c14910b66d8681195791c5ce601cbe9cb6739734\", \"media_blink_unittests\": \"b4e05c4caf9ec832ba5492a549f4728b2687f18f\", \"views_mus_unittests\": \"25fdb1b2129318a5eb6611664f4bc79669fcdf53\", \"components_unittests\": \"3ce308c3b5ae079cd50b27bb49357fa29a833a7e\", \"snapshot_unittests\": \"d045d4d44ea76b57a619a3e5ece3336c58fcd092\", \"accessibility_unittests\": \"5f0ec900515e909a5db6ed198e882fce230b7279\", \"net_perftests\": \"7ef9fcf25f39f4f755413afe23f9660fdbf345d7\", \"aura_unittests\": \"553a0fcedf6fee5c49eb404f904280055985e699\", \"services_unittests\": \"d6477ffe85656e23440adb80948985e98fe85cf8\", \"components_perftests\": \"70b4bceec1e264adaa7821dcbda1454cdfc5d1b1\", \"events_unittests\": \"da75cdcefe52798f84b661bc97f1409b69fd9680\", \"tracing_perftests\": \"adb32e69723867aa1f257ae85d17e8aad864bfba\", \"chromedriver_replay_unittests\": \"5013a0f078eac50a549885e896698d163bf73605\", \"ui_touch_selection_unittests\": \"7b2789117edc4c7f2ec188111f03c4bc1d898c18\", \"cacheinvalidation_unittests\": \"abe6765495f6b4a7a28bd6c064941c4127771e4d\", \"viz_unittests\": \"b9993fcef9e635c8a3c30dd978ce35a3ffb29467\", \"browser_tests\": \"5001a0a5abb097bcb546001d6076ce276ed0ef64\", \"net_unittests\": \"bcb3aa4cb36a0ffd69a28f629b2ddec4dfe08bb1\", \"midi_unittests\": \"31e585719d714b50806a2148d3ee191e8d46e0f2\", \"boringssl_ssl_tests\": \"04a7c75a795271bcf061f279bd666ca2e87e74a5\", \"sbox_validation_tests\": \"69ac120f79c78f51972058640fb5b4d217762f86\", \"elevation_service_unittests\": \"74483d65f87ae0acef5ee56514d22a2ef350d741\", \"gpu_unittests\": \"6fc57f54dd5fcfd512313240616ae3b67ea2b7a8\", \"native_theme_unittests\": \"8c726132ac4258e7fdbf63bee254968bfc4da87b\", \"interactive_ui_tests\": \"5233f7481b184c3e943f5119afdbefa71150ad72\", \"mojo_unittests\": \"804480f600158449cd4cdba91ff0dab99da9efa2\", \"url_unittests\": \"55440fae2ba20fcc793f8c16f671bad497917e23\", \"media_perftests\": \"9abd0771cad978f654acf7b034bb9c237bd44092\", \"cronet_tests\": \"826199161ebe3099312efeb1d86f656d54ac7a40\", \"google_apis_unittests\": \"2ed3d5c099d2c83b949b1e34c736e88527c4d457\", \"telemetry_gpu_integration_test\": \"f6cec23734c25007da05e30375f65113707e5d92\", \"blink_heap_unittests\": \"59c4e12d17e5f0f65c84389d890e6cd72c8cbac8\", \"base_unittests\": \"a581a8c3498df7252d51af08d0b4a9a48907b258\", \"sync_integration_tests\": \"435e6ceb3d9c7d5536282873e8227b03a4930dd2\", \"sql_unittests\": \"0badaff5a06e6e3956974729ce35b1960d55faec\", \"courgette_unittests\": \"b0b685f3602093638ab944e10dc0bb170c1f5144\", \"blink_fuzzer_unittests\": \"f46284057f2a41080431c1b947c7aa4153e9d57c\", \"gl_tests\": \"e5bbd0498b5b43077db3c5b1ad2ef7654d23bba5\", \"zucchini_unittests\": \"c8a85a4e06570b0bb0689510c28479f8f3424615\", \"ipc_tests\": \"81eba6c2eb058104cf0ef06f8fd13f9f4f3caab4\", \"angle_end2end_tests\": \"9042135d1666db3e6dacdca67fe5987ecd5aac9a\", \"blink_platform_unittests\": \"d4ec853b9168fcea39e015ff211ab92eff67da05\", \"components_browsertests\": \"dd35032a384e7dd13403fc8e655512b498fd32fb\", \"views_perftests\": \"6e4d9d45ffb8803af29d291537a56cbef9fd417d\", \"angle_perftests\": \"04ab3288c69aad103e1948f85f1a6337b5b995ce\", \"display_unittests\": \"62233e72a7e4fe860fc5d0d2936e32abd2dfb9d2\", \"sbox_unittests\": \"108659137e672b3c7182355d009b76f9999182d5\", \"service_manager_unittests\": \"be26e6f0f657b06dfd1f133f53761b307558a049\", \"pdf_unittests\": \"898342993781934a065c50dbd144e84a36f578a0\", \"skia_unittests\": \"98be788592f2575dd4788f62f6aef4dff5e0ce49\", \"angle_white_box_tests\": \"0eb176a27cccb49efab8ef1d82221d746b85356a\", \"crypto_unittests\": \"d12d2e9e29559748b18d0f28925b03474c16ba48\", \"nacl_loader_unittests\": \"bafe2b1b7ffdaf98a87bfd7b7a53d5eb2e810f00\", \"unit_tests\": \"039250d40e1c781a94caecc2236e3ae11012a6b4\", \"content_browsertests\": \"4a4aa2c90f84cb1b79d34355134c5615a28a1909\", \"printing_unittests\": \"04ca32e674061bb079d7b96a20082b236c7744ca\", \"device_unittests\": \"e7ba783ed39a8aa96ac3841bba8ffa94858a9449\"}, \"path_config\": \"generic\", \"bot_id\": \"swarm2173-c4\", \"got_buildtools_revision\": \"2dff9c9c74e9d732e6fe57c84ef7fd044cc45d96\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1538887258373496",
"status": "COMPLETED",
"status_changed_ts": "1538893263271220",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/10324",
"builder:Windows deterministic",
"buildset:commit/git/a849c78b38bb9805a6c3fea7edba02811ead600c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a849c78b38bb9805a6c3fea7edba02811ead600c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9098783534994966624",
"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/10324",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8933353472808997072",
"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/a849c78b38bb9805a6c3fea7edba02811ead600c",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a849c78b38bb9805a6c3fea7edba02811ead600c",
"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/8933353472808997072/+/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:9098783534994966624",
"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:7a2c6a280a68ea7a8c9976d934a89c38009336b9",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:4065a592bb5c8110",
"user_agent:luci-scheduler"
],
"updated_ts": "1538893263273379",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/10324",
"utcnow_ts": "1538893263879726"
}
}
[06:21:03.894] Invocation finished in 1h40m9.550486497s with status SUCCEEDED