[11:47:30.173] New invocation is queued and will start shortly
[11:47:31.490] Starting the invocation (attempt 1)
[11:47:31.521] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:47:31.521] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:47:31.521] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9101112216964328416",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"3cdeae874cc9479e6b71ae8b48163c8c3e2e99a6\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9101112216964328416",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/3cdeae874cc9479e6b71ae8b48163c8c3e2e99a6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3cdeae874cc9479e6b71ae8b48163c8c3e2e99a6",
"gitiles_ref:refs/heads/master"
]
}
[11:47:35.878] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1536666451604709",
"id": "8935682154801371280",
"lease_expiration_ts": "1536810455350048",
"lease_key": "2011887748",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"3cdeae874cc9479e6b71ae8b48163c8c3e2e99a6\"}}",
"project": "chromium",
"result_details_json": "null",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1536666455793689",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/10051",
"builder:Windows deterministic",
"buildset:commit/git/3cdeae874cc9479e6b71ae8b48163c8c3e2e99a6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3cdeae874cc9479e6b71ae8b48163c8c3e2e99a6",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9101112216964328416",
"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/10051",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8935682154801371280",
"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/3cdeae874cc9479e6b71ae8b48163c8c3e2e99a6",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3cdeae874cc9479e6b71ae8b48163c8c3e2e99a6",
"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/8935682154801371280/+/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:9101112216964328416",
"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:330bd7d6e144325cfde38e2af74daf2646e1a502",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:3fe146d263834c10",
"user_agent:luci-scheduler"
],
"updated_ts": "1536666455793828",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/10051",
"utcnow_ts": "1536666455867186"
}
}
[11:47:35.878] Task URL: https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/10051
[11:47:35.878] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:2:0) after 1m0s
[11:47:38.655] Received PubSub notification, asking Buildbucket for the build status
[11:47:39.127] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:35.895] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:2:0)
[11:48:35.895] Timer tick, asking Buildbucket for the build status
[11:48:36.007] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:36.007] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:4:0) after 1m0s
[11:49:36.113] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:4:0)
[11:49:36.113] Timer tick, asking Buildbucket for the build status
[11:49:36.158] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:36.158] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:5:0) after 1m0s
[11:50:36.177] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:5:0)
[11:50:36.177] Timer tick, asking Buildbucket for the build status
[11:50:36.302] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:36.302] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:6:0) after 1m0s
[11:51:36.344] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:6:0)
[11:51:36.344] Timer tick, asking Buildbucket for the build status
[11:51:36.384] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:36.384] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:7:0) after 1m0s
[11:52:36.428] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:7:0)
[11:52:36.428] Timer tick, asking Buildbucket for the build status
[11:52:36.468] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:36.468] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:8:0) after 1m0s
[11:53:36.495] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:8:0)
[11:53:36.495] Timer tick, asking Buildbucket for the build status
[11:53:36.684] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:36.684] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:9:0) after 1m0s
[11:54:36.761] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:9:0)
[11:54:36.761] Timer tick, asking Buildbucket for the build status
[11:54:37.038] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:37.038] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:10:0) after 1m0s
[11:55:37.136] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:10:0)
[11:55:37.136] Timer tick, asking Buildbucket for the build status
[11:55:37.200] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:37.200] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:11:0) after 1m0s
[11:56:37.220] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:11:0)
[11:56:37.220] Timer tick, asking Buildbucket for the build status
[11:56:37.253] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:37.253] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:12:0) after 1m0s
[11:57:37.270] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:12:0)
[11:57:37.270] Timer tick, asking Buildbucket for the build status
[11:57:37.324] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:37.324] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:13:0) after 1m0s
[11:58:37.342] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:13:0)
[11:58:37.342] Timer tick, asking Buildbucket for the build status
[11:58:37.395] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:37.395] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:14:0) after 1m0s
[11:59:37.412] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:14:0)
[11:59:37.412] Timer tick, asking Buildbucket for the build status
[11:59:37.461] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:37.461] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:15:0) after 1m0s
[12:00:37.479] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:15:0)
[12:00:37.479] Timer tick, asking Buildbucket for the build status
[12:00:37.514] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:37.514] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:16:0) after 1m0s
[12:01:37.530] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:16:0)
[12:01:37.530] Timer tick, asking Buildbucket for the build status
[12:01:37.684] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:37.684] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:17:0) after 1m0s
[12:02:37.706] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:17:0)
[12:02:37.706] Timer tick, asking Buildbucket for the build status
[12:02:37.775] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:37.775] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:18:0) after 1m0s
[12:03:37.815] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:18:0)
[12:03:37.815] Timer tick, asking Buildbucket for the build status
[12:03:37.972] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:37.972] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:19:0) after 1m0s
[12:04:37.989] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:19:0)
[12:04:37.989] Timer tick, asking Buildbucket for the build status
[12:04:38.126] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:38.126] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:20:0) after 1m0s
[12:05:38.219] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:20:0)
[12:05:38.219] Timer tick, asking Buildbucket for the build status
[12:05:38.307] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:38.307] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:21:0) after 1m0s
[12:06:38.327] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:21:0)
[12:06:38.327] Timer tick, asking Buildbucket for the build status
[12:06:38.459] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:38.459] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:22:0) after 1m0s
[12:07:38.480] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:22:0)
[12:07:38.480] Timer tick, asking Buildbucket for the build status
[12:07:38.534] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:38.534] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:23:0) after 1m0s
[12:08:38.551] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:23:0)
[12:08:38.551] Timer tick, asking Buildbucket for the build status
[12:08:38.697] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:38.697] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:24:0) after 1m0s
[12:09:38.739] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:24:0)
[12:09:38.739] Timer tick, asking Buildbucket for the build status
[12:09:38.803] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:38.803] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:25:0) after 1m0s
[12:10:38.884] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:25:0)
[12:10:38.884] Timer tick, asking Buildbucket for the build status
[12:10:38.974] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:38.974] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:26:0) after 1m0s
[12:11:38.994] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:26:0)
[12:11:38.994] Timer tick, asking Buildbucket for the build status
[12:11:39.081] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:39.081] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:27:0) after 1m0s
[12:12:39.207] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:27:0)
[12:12:39.207] Timer tick, asking Buildbucket for the build status
[12:12:39.385] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:39.385] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:28:0) after 1m0s
[12:13:39.500] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:28:0)
[12:13:39.500] Timer tick, asking Buildbucket for the build status
[12:13:39.542] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:39.542] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:29:0) after 1m0s
[12:14:39.685] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:29:0)
[12:14:39.685] Timer tick, asking Buildbucket for the build status
[12:14:39.769] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:39.769] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:30:0) after 1m0s
[12:15:39.815] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:30:0)
[12:15:39.815] Timer tick, asking Buildbucket for the build status
[12:15:39.886] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:39.886] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:31:0) after 1m0s
[12:16:39.902] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:31:0)
[12:16:39.902] Timer tick, asking Buildbucket for the build status
[12:16:40.083] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:40.083] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:32:0) after 1m0s
[12:17:40.119] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:32:0)
[12:17:40.119] Timer tick, asking Buildbucket for the build status
[12:17:40.196] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:40.196] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:33:0) after 1m0s
[12:18:40.236] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:33:0)
[12:18:40.236] Timer tick, asking Buildbucket for the build status
[12:18:40.307] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:40.307] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:34:0) after 1m0s
[12:19:40.327] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:34:0)
[12:19:40.327] Timer tick, asking Buildbucket for the build status
[12:19:40.384] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:40.384] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:35:0) after 1m0s
[12:20:40.403] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:35:0)
[12:20:40.403] Timer tick, asking Buildbucket for the build status
[12:20:40.461] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:40.461] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:36:0) after 1m0s
[12:21:40.478] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:36:0)
[12:21:40.478] Timer tick, asking Buildbucket for the build status
[12:21:40.630] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:40.630] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:37:0) after 1m0s
[12:22:40.700] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:37:0)
[12:22:40.700] Timer tick, asking Buildbucket for the build status
[12:22:40.758] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:40.758] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:38:0) after 1m0s
[12:23:40.787] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:38:0)
[12:23:40.787] Timer tick, asking Buildbucket for the build status
[12:23:40.840] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:40.840] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:39:0) after 1m0s
[12:24:40.963] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:39:0)
[12:24:40.963] Timer tick, asking Buildbucket for the build status
[12:24:41.067] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:41.067] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:40:0) after 1m0s
[12:25:41.086] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:40:0)
[12:25:41.086] Timer tick, asking Buildbucket for the build status
[12:25:41.232] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:41.232] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:41:0) after 1m0s
[12:26:41.251] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:41:0)
[12:26:41.251] Timer tick, asking Buildbucket for the build status
[12:26:41.411] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:41.411] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:42:0) after 1m0s
[12:27:41.439] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:42:0)
[12:27:41.439] Timer tick, asking Buildbucket for the build status
[12:27:41.499] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:41.499] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:43:0) after 1m0s
[12:28:41.533] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:43:0)
[12:28:41.533] Timer tick, asking Buildbucket for the build status
[12:28:41.607] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:41.607] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:44:0) after 1m0s
[12:29:41.630] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:44:0)
[12:29:41.630] Timer tick, asking Buildbucket for the build status
[12:29:41.692] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:41.692] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:45:0) after 1m0s
[12:30:41.715] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:45:0)
[12:30:41.715] Timer tick, asking Buildbucket for the build status
[12:30:41.826] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:41.826] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:46:0) after 1m0s
[12:31:41.907] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:46:0)
[12:31:41.907] Timer tick, asking Buildbucket for the build status
[12:31:41.962] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:41.962] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:47:0) after 1m0s
[12:32:41.988] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:47:0)
[12:32:41.988] Timer tick, asking Buildbucket for the build status
[12:32:42.055] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:42.055] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:48:0) after 1m0s
[12:33:42.078] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:48:0)
[12:33:42.078] Timer tick, asking Buildbucket for the build status
[12:33:42.143] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:42.143] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:49:0) after 1m0s
[12:34:42.163] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:49:0)
[12:34:42.163] Timer tick, asking Buildbucket for the build status
[12:34:42.246] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:42.246] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:50:0) after 1m0s
[12:35:42.265] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:50:0)
[12:35:42.265] Timer tick, asking Buildbucket for the build status
[12:35:42.300] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:42.300] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:51:0) after 1m0s
[12:36:42.319] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:51:0)
[12:36:42.319] Timer tick, asking Buildbucket for the build status
[12:36:42.441] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:42.441] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:52:0) after 1m0s
[12:37:42.506] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:52:0)
[12:37:42.506] Timer tick, asking Buildbucket for the build status
[12:37:42.542] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:42.542] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:53:0) after 1m0s
[12:38:42.583] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:53:0)
[12:38:42.583] Timer tick, asking Buildbucket for the build status
[12:38:42.622] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:42.622] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:54:0) after 1m0s
[12:39:42.648] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:54:0)
[12:39:42.648] Timer tick, asking Buildbucket for the build status
[12:39:42.688] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:42.688] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:55:0) after 1m0s
[12:40:42.712] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:55:0)
[12:40:42.712] Timer tick, asking Buildbucket for the build status
[12:40:42.774] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:42.774] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:56:0) after 1m0s
[12:41:42.793] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:56:0)
[12:41:42.793] Timer tick, asking Buildbucket for the build status
[12:41:42.851] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:42.851] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:57:0) after 1m0s
[12:42:42.874] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:57:0)
[12:42:42.874] Timer tick, asking Buildbucket for the build status
[12:42:43.023] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:43.023] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:58:0) after 1m0s
[12:43:43.046] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:58:0)
[12:43:43.046] Timer tick, asking Buildbucket for the build status
[12:43:43.106] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:43.106] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:59:0) after 1m0s
[12:44:43.210] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:59:0)
[12:44:43.210] Timer tick, asking Buildbucket for the build status
[12:44:43.357] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:43.357] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:60:0) after 1m0s
[12:45:43.375] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:60:0)
[12:45:43.375] Timer tick, asking Buildbucket for the build status
[12:45:43.430] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:43.430] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:61:0) after 1m0s
[12:46:43.559] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:61:0)
[12:46:43.559] Timer tick, asking Buildbucket for the build status
[12:46:43.630] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:43.630] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:62:0) after 1m0s
[12:47:43.651] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:62:0)
[12:47:43.651] Timer tick, asking Buildbucket for the build status
[12:47:43.774] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:43.774] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:63:0) after 1m0s
[12:48:43.794] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:63:0)
[12:48:43.794] Timer tick, asking Buildbucket for the build status
[12:48:43.958] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:43.958] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:64:0) after 1m0s
[12:49:43.978] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:64:0)
[12:49:43.978] Timer tick, asking Buildbucket for the build status
[12:49:44.083] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:44.083] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:65:0) after 1m0s
[12:50:44.131] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:65:0)
[12:50:44.131] Timer tick, asking Buildbucket for the build status
[12:50:44.208] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:44.208] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:66:0) after 1m0s
[12:51:44.234] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:66:0)
[12:51:44.234] Timer tick, asking Buildbucket for the build status
[12:51:44.308] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:44.308] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:67:0) after 1m0s
[12:52:44.339] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:67:0)
[12:52:44.339] Timer tick, asking Buildbucket for the build status
[12:52:44.394] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:44.394] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:68:0) after 1m0s
[12:53:44.413] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:68:0)
[12:53:44.413] Timer tick, asking Buildbucket for the build status
[12:53:44.664] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:44.664] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:69:0) after 1m0s
[12:54:44.692] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:69:0)
[12:54:44.692] Timer tick, asking Buildbucket for the build status
[12:54:44.757] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:44.757] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:70:0) after 1m0s
[12:55:44.776] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:70:0)
[12:55:44.776] Timer tick, asking Buildbucket for the build status
[12:55:44.900] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:44.900] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:71:0) after 1m0s
[12:56:44.989] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:71:0)
[12:56:44.989] Timer tick, asking Buildbucket for the build status
[12:56:45.033] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:45.033] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:72:0) after 1m0s
[12:57:45.445] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:72:0)
[12:57:45.445] Timer tick, asking Buildbucket for the build status
[12:57:45.482] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:45.482] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:73:0) after 1m0s
[12:58:45.525] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:73:0)
[12:58:45.525] Timer tick, asking Buildbucket for the build status
[12:58:45.560] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:45.560] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:74:0) after 1m0s
[12:59:45.579] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:74:0)
[12:59:45.579] Timer tick, asking Buildbucket for the build status
[12:59:45.645] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:45.645] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:75:0) after 1m0s
[13:00:45.708] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:75:0)
[13:00:45.708] Timer tick, asking Buildbucket for the build status
[13:00:45.921] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:45.921] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:76:0) after 1m0s
[13:01:45.941] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:76:0)
[13:01:45.941] Timer tick, asking Buildbucket for the build status
[13:01:45.999] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:45.999] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:77:0) after 1m0s
[13:02:46.031] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:77:0)
[13:02:46.031] Timer tick, asking Buildbucket for the build status
[13:02:46.092] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:46.092] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:78:0) after 1m0s
[13:03:46.123] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:78:0)
[13:03:46.123] Timer tick, asking Buildbucket for the build status
[13:03:46.158] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:46.158] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:79:0) after 1m0s
[13:04:46.225] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:79:0)
[13:04:46.225] Timer tick, asking Buildbucket for the build status
[13:04:46.289] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:46.289] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:80:0) after 1m0s
[13:05:46.308] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:80:0)
[13:05:46.308] Timer tick, asking Buildbucket for the build status
[13:05:46.384] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:46.384] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:81:0) after 1m0s
[13:06:46.413] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:81:0)
[13:06:46.413] Timer tick, asking Buildbucket for the build status
[13:06:46.556] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:46.557] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:82:0) after 1m0s
[13:07:46.645] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:82:0)
[13:07:46.645] Timer tick, asking Buildbucket for the build status
[13:07:46.704] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:46.704] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:83:0) after 1m0s
[13:08:46.902] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:83:0)
[13:08:46.902] Timer tick, asking Buildbucket for the build status
[13:08:46.957] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:46.957] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:84:0) after 1m0s
[13:09:47.905] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:84:0)
[13:09:47.905] Timer tick, asking Buildbucket for the build status
[13:09:48.088] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:48.088] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:85:0) after 1m0s
[13:10:49.137] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:85:0)
[13:10:49.137] Timer tick, asking Buildbucket for the build status
[13:10:49.199] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:49.199] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:86:0) after 1m0s
[13:11:49.219] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:86:0)
[13:11:49.219] Timer tick, asking Buildbucket for the build status
[13:11:49.367] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:49.367] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:87:0) after 1m0s
[13:12:49.856] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:87:0)
[13:12:49.856] Timer tick, asking Buildbucket for the build status
[13:12:49.957] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:49.957] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:88:0) after 1m0s
[13:13:50.467] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:88:0)
[13:13:50.467] Timer tick, asking Buildbucket for the build status
[13:13:50.561] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:50.561] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:89:0) after 1m0s
[13:14:51.011] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:89:0)
[13:14:51.011] Timer tick, asking Buildbucket for the build status
[13:14:51.123] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:51.123] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:90:0) after 1m0s
[13:15:52.085] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:90:0)
[13:15:52.085] Timer tick, asking Buildbucket for the build status
[13:15:52.155] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:52.155] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:91:0) after 1m0s
[13:16:53.140] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:91:0)
[13:16:53.140] Timer tick, asking Buildbucket for the build status
[13:16:53.176] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:53.176] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:92:0) after 1m0s
[13:17:53.217] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:92:0)
[13:17:53.217] Timer tick, asking Buildbucket for the build status
[13:17:53.282] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:53.282] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:93:0) after 1m0s
[13:18:53.316] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:93:0)
[13:18:53.316] Timer tick, asking Buildbucket for the build status
[13:18:53.348] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:53.348] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:94:0) after 1m0s
[13:19:53.467] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:94:0)
[13:19:53.467] Timer tick, asking Buildbucket for the build status
[13:19:53.526] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:53.526] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:95:0) after 1m0s
[13:20:53.547] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:95:0)
[13:20:53.547] Timer tick, asking Buildbucket for the build status
[13:20:53.631] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:53.631] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:96:0) after 1m0s
[13:21:53.826] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:96:0)
[13:21:53.827] Timer tick, asking Buildbucket for the build status
[13:21:53.893] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:53.893] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:97:0) after 1m0s
[13:22:53.915] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:97:0)
[13:22:53.915] Timer tick, asking Buildbucket for the build status
[13:22:54.002] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:54.002] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:98:0) after 1m0s
[13:23:54.156] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:98:0)
[13:23:54.156] Timer tick, asking Buildbucket for the build status
[13:23:54.537] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:54.537] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:99:0) after 1m0s
[13:24:54.705] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:99:0)
[13:24:54.705] Timer tick, asking Buildbucket for the build status
[13:24:54.771] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:54.771] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:100:0) after 1m0s
[13:25:54.923] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:100:0)
[13:25:54.923] Timer tick, asking Buildbucket for the build status
[13:25:55.031] Build 8935682154801371280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:55.031] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101112216964328416:101:0) after 1m0s
[13:26:27.647] Received PubSub notification, asking Buildbucket for the build status
[13:26:27.686] Build 8935682154801371280: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[13:26:27.686] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1536672384871780",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1536666451604709",
"id": "8935682154801371280",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"3cdeae874cc9479e6b71ae8b48163c8c3e2e99a6\"}}",
"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/8935682154801371280/+/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\": [\"3769-3c22b45\"], \"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\": \"5604391f597d84ef7065eb99432fcc09cdcf7d34\", \"got_swarming_client_revision\": \"486c9b53c4d54dd4b95bb6ce0e31160e600dfc11\", \"got_revision\": \"3cdeae874cc9479e6b71ae8b48163c8c3e2e99a6\", \"recipe\": \"swarming/deterministic_build\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#24665}\", \"$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@{#590265}\", \"branch\": \"refs/heads/master\", \"revision\": \"3cdeae874cc9479e6b71ae8b48163c8c3e2e99a6\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"49b2c3c4c43359bc86d8510d29d117f3d7a621a3\", \"mastername\": \"chromium.fyi\", \"got_angle_revision\": \"0d0fb43f34eeea20bf78089ca2a4e1f2831cffe5\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1536666451604709, \"tags\": [\"builder:Windows deterministic\", \"buildset:commit/git/3cdeae874cc9479e6b71ae8b48163c8c3e2e99a6\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3cdeae874cc9479e6b71ae8b48163c8c3e2e99a6\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9101112216964328416\", \"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\": \"8935682154801371280\"}}, \"got_v8_revision\": \"33b726db1e2a5b7de8ddca88e165596c34d08a0d\", \"got_v8_revision_cp\": \"refs/heads/7.1.71@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 10051, \"swarm_hashes\": {\"shell_dialogs_unittests\": \"87552ab1b27e7aa40ea045d54c190f27e098e2c0\", \"notification_helper_unittests\": \"e23735459f60eea64f04b85553f7c172e97a8058\", \"gpu_perftests\": \"bf06878e2e610d7757fd224c536c5af92537ddc2\", \"vr_common_unittests\": \"1a37d0056c8fe70aa042d9c39cdcbd8030e20694\", \"telemetry_unittests\": \"f4d3daae70d27edc947bdc3cd702c36bf00f3a0c\", \"load_library_perf_tests\": \"28df2be0d04a6d6e440600eef347e4478fe8974e\", \"devtools_closure_compile\": \"a2e6de05a09325f15ad8a852b313b2602824facc\", \"chromedriver_py_tests\": \"b9eb9f818fcf978e8b2b07e10e6f1967adeb3a7f\", \"mojo_core_unittests\": \"7aaf9883aa14fbc4ca4b193cf60d0b8ef5fe57b8\", \"install_static_unittests\": \"9869f9f0933ccb37e490771e36245c58ce16c392\", \"leveldb_service_unittests\": \"52d411dd9f1ce17f1b77fdc80e4ac567e840e3f3\", \"mini_installer_tests\": \"7ab658c8ffc1eac6914534b5c70a24c9ebf33cc0\", \"jingle_unittests\": \"7a9c8e4120d05b231de4ade641db174d59d9c58c\", \"sbox_integration_tests\": \"42a1b9b076a8b570875048fa864d4276564fc69c\", \"angle_unittests\": \"6929446767c1674ffc600740f9f370cdd3bd7420\", \"cast_unittests\": \"f20cd3b5390c4e585e54ba8459b19ca8867c60d7\", \"cc_unittests\": \"6ffb00c0f8915633a65a74745db05ef8816c6dd8\", \"blink_common_unittests\": \"fbdd5c3bd798c3c32ef429790851601fcd7f4343\", \"crashpad_tests\": \"009d6f6fb7e336958a13f67e45bcffb49d71ea3a\", \"telemetry_perf_tests_without_chrome\": \"37dac6650a8a36f2141b161a4c1f7d04b88a97c0\", \"metrics_python_tests\": \"a54ccbdcb57a0d4e104d4d9469fa880fb724183b\", \"message_center_unittests\": \"e4604a37a2309b4ccece829a797548f466bc34f6\", \"ppapi_unittests\": \"591e55ee68ecf364c9e2156d81ffb047ae0d767a\", \"extensions_unittests\": \"7b15cd19ceaec81f08eca1ab5fe57acb3d5854ff\", \"gl_unittests_ozone\": \"6c1edbd4e436f453c5e33b86cb927c96e4e76e0f\", \"app_shell_unittests\": \"3863da1bc126588ba2c2fca955c516710d34d7e7\", \"chrome_app_unittests\": \"2420c66ee49fee9fd97d31da56bda46477674f35\", \"gl_unittests\": \"6c1edbd4e436f453c5e33b86cb927c96e4e76e0f\", \"gcm_unit_tests\": \"a1aa467e64ee49c23545b85760325698a083bbb5\", \"views_unittests\": \"6d595d58145907cf8528afe04e0da49732655efe\", \"content_shell_crash_test\": \"ae0ed144895e0f3241f44c052d0aa76b9564c59d\", \"ui_base_unittests\": \"e71c0ee9495c09b65c9bb36c9f03dad01b49df26\", \"gles2_conform_test\": \"323853bfc9772276bf2da7222cb57b08be8e471d\", \"cronet_unittests\": \"bda60229c4e9f87ba96ba7f756336ffb30f18d41\", \"performance_test_suite\": \"b416d13583d279575ccf35824a91e0d54a4a6a0b\", \"wm_unittests\": \"0dde26246811603d91633a76120d175e6a718db9\", \"boringssl_crypto_tests\": \"62eab7d2bfd20a727c92202ddd7c5506b4372b9d\", \"gin_unittests\": \"276c34a84dbced840e0c481b13d782f3e37b5421\", \"performance_browser_tests\": \"b6e9b71cf987315c82e4ac78dec6a99452257b7f\", \"extensions_browsertests\": \"a12f91a3bd790e2f07ef8147516e5242b2a548ff\", \"webkit_python_tests\": \"bce7d315c416614114067c6717943bbc50bb2935\", \"headless_unittests\": \"893ca25b0ee57e526aadafd6c50096fa37cefbd7\", \"webkit_layout_tests\": \"ea94d4bb6d4e4ff4497687ac523149dc83bf4a59\", \"vr_pixeltests\": \"86c381a65ed528784bb9728a1e5864e6a850dddf\", \"setup_unittests\": \"e7353a4e59f1b39965c02e1e1a491420dcdc42ed\", \"webkit_unit_tests\": \"1137312a9bd2946d522a412a9bac4c1a7a979d7f\", \"content_unittests\": \"59392558c0191f526cc883feda71942d7a0705e2\", \"performance_webview_test_suite\": \"237c71390084eaeef4caeefd804affe75d0fb216\", \"wtf_unittests\": \"f84d259fca11f48145604b587758cbe4e7b46a06\", \"webkit_layout_tests_exparchive\": \"acdffe0d66f1c0d5665f73a9ee0839cc3f4bc196\", \"chrome_cleaner_unittests\": \"37a75823a18ec352ca04223d6585ab8735b07054\", \"traffic_annotation_auditor_unittests\": \"41ce913450c5d54d60029ccababb1e15e5126739\", \"libjingle_xmpp_unittests\": \"a9d5a2b1d4c66b16eea8de96e74ca42a7712fb96\", \"remoting_unittests\": \"cee3e2f7385ee9532c76ab095e1e44ac9ac28302\", \"vr_common_perftests\": \"4a6d554f07094df640991b2d8784a3a454d88891\", \"filesystem_service_unittests\": \"ecbeefdcd6b07e2d94a357b2ef81f5e196ee9289\", \"chrome_elf_import_unittests\": \"ace20e3bd9ac0f0c97adf493a6852087f50e8c9f\", \"installer_util_unittests\": \"abc5d5926feceac5cbbc2e272a0fe2fd050536e1\", \"gfx_unittests\": \"081d377f6c166beb1f9459ecda59f41bd0c09e2c\", \"headless_browsertests\": \"d3efbba0f217c2a2dc28c35bec4cc9258e5c38d3\", \"capture_unittests\": \"43c816768362f116bb6b683bcf492bb9b7bc4f6b\", \"chrome_elf_unittests\": \"82e232f3ae28151d18a3b289eb9220eab9fa4a17\", \"telemetry_gpu_unittests\": \"268003a7d4baf6b776bb1e726343d28e6f3ed04b\", \"command_buffer_perftests\": \"03788fef383627cc2c36fc51043cdfda25c90c86\", \"media_unittests\": \"4f884ba8d8695c2e69b6d29ffe02695c4ee18760\", \"chromedriver_unittests\": \"3d09034019695b127a8e3d400de7320243bc7344\", \"views_mus_interactive_ui_tests\": \"552cee9447857a68aa18a617c435b4c14e9e6f5f\", \"storage_unittests\": \"41426a4670df6e97079eff5bb7280339f0e2c0a9\", \"media_service_unittests\": \"9cff7182fca1cf6b6291668966a87e696f5f2b75\", \"compositor_unittests\": \"93032ae118cfac3d0aff78abc3f9fe6dc167c657\", \"latency_unittests\": \"410aee4635a6eef80f0886dabedc85006171a8b1\", \"video_decode_accelerator_unittest\": \"dccf0de0867789da963923656e4d9e816d22bfa0\", \"swiftshader_unittests\": \"85753303c5f707b16d40869d6325c0c1c7aa5e5c\", \"telemetry_perf_unittests\": \"7ecf84c14391891cba296df3710704f27d6207c8\", \"media_blink_unittests\": \"b567b5aff11b28bcef131d025bd47f15751af59f\", \"views_mus_unittests\": \"9f710e2a5070ec53f394689f7ee99753c32906de\", \"components_unittests\": \"d3bfded85addd9aef52cfc0961af4257fad39c13\", \"snapshot_unittests\": \"d55b5127845ff0d7de117e8421643705bfaf1af9\", \"accessibility_unittests\": \"bac117eeb4d42df96a02ed52e5dbf58eb35fc15b\", \"net_perftests\": \"71c085a8fde3adc85da9e7087d09708a2085ab1c\", \"aura_unittests\": \"9dcb7e770aa3e62c0a95f188642b302a97613339\", \"services_unittests\": \"53927ba1ebe021cc84831b05e3f47e68f943cc43\", \"components_perftests\": \"76c9f730ae84d3a526185f522609761d45cb9425\", \"events_unittests\": \"4b7ea88b726cebd6280609d5719f307b26d08cf1\", \"tracing_perftests\": \"6a6c3b33ad13c9d607c2e56cefa41e35b26db5a9\", \"chromedriver_replay_unittests\": \"e2ec5b5deadfbf1a0e00246e86e8f3e11e9cace0\", \"ui_touch_selection_unittests\": \"7735cb4c25a59d08114a5983fecb4e9021c96a67\", \"cacheinvalidation_unittests\": \"4b0000e4db96ae48d8b34b90a9b2f3eba4594ed2\", \"viz_unittests\": \"b584f0f05260b55f46928d49da671bc508f173db\", \"browser_tests\": \"4c61bc926b9007812b1f84db5679c4d160b3236b\", \"net_unittests\": \"34e52843423bcfbe9552b5916c3b57abe3ed9af2\", \"midi_unittests\": \"cfe7b39a15a5c79170360945d35ed28a88ce94ae\", \"boringssl_ssl_tests\": \"5b1b2710cf3a92db249aa52efc6afcf62c727fcb\", \"sbox_validation_tests\": \"193f0e675e0ad748b1c726fd65fc198bcf1829e0\", \"elevation_service_unittests\": \"95d4f8d881a0b8b3eaca4d863a31b6047aa08c27\", \"gpu_unittests\": \"06deb791dfcb5f2ddd92a6da97d7e380f2e70e1d\", \"native_theme_unittests\": \"738f54358d1adb246df584ebdee1000c0b3ea5ba\", \"interactive_ui_tests\": \"a0a0c1fed458a78ba9a76cfb9931c3f610641f3a\", \"mojo_unittests\": \"ece631c4c5c07ad05c011601fc766b52147aaa5d\", \"url_unittests\": \"937664d8fd849f7970d3ea1bf76a5569538017e7\", \"media_perftests\": \"9cd3d39906e9ccffa30904103211a6ee5b61c947\", \"cronet_tests\": \"b3d8104072a80f592a62c456456e3c9ee5a52b0a\", \"google_apis_unittests\": \"381afa255a38c35c52b64be180fe0ae628b60032\", \"telemetry_gpu_integration_test\": \"03858b4d2facfb678501ad8c83b81e3e135c282e\", \"blink_heap_unittests\": \"57552180dc947a2efa4573b7f528baca4c6e9d9e\", \"base_unittests\": \"a2ab4fdb7a412d2a04c7ef78176375474fab4f93\", \"sync_integration_tests\": \"efdecdb13f0e14c6f621e6bfc766974253b5fdca\", \"sql_unittests\": \"7ac5df1e36081c5396305a2d5b717c3d84b1060c\", \"courgette_unittests\": \"516d91d31b9ecdc8eeb1ac039bf9c740558ca787\", \"blink_fuzzer_unittests\": \"de37ff101bda582dff0019a427eef352706f3303\", \"gl_tests\": \"832dc7cbbe144c7313dedff4ffea307bd116b52d\", \"zucchini_unittests\": \"12c39549cf157ae720acdc498b2d4a10189b7420\", \"ipc_tests\": \"dc69950a9a1a8f8bce8757df081f7b227a2c73dc\", \"angle_end2end_tests\": \"3fe57da9698a3825d660edc1e0c31af276ae70fe\", \"blink_platform_unittests\": \"4dfe22a5518e64294d264880f1e40a89e9610dac\", \"components_browsertests\": \"3bb5caf7377b86750657f4ba5c0625f544f3b515\", \"views_perftests\": \"a4b7e43bdb51199b3c1a7e34aaae4b8194478a5f\", \"angle_perftests\": \"09ddc225e730c6481beac87cba21d2be058f67a4\", \"display_unittests\": \"0609fc9430a9f9fc00ab1e0669fcefdbe35d7d1f\", \"sbox_unittests\": \"6aa623759f2ab2fe8c9d1bf667a1fd73f6709169\", \"service_manager_unittests\": \"510f87da5ca44d0a51f46613c3315af7be0c4b63\", \"pdf_unittests\": \"3260e8a80a884f74777398db70fbafd391ab918a\", \"skia_unittests\": \"81dadd9d0465a57c9f27a3b385cd0335173e66a2\", \"angle_white_box_tests\": \"2820e26ec7f329ca802281d54623bdf5879b300f\", \"crypto_unittests\": \"cf57b2d2ee409c533e0e42b0d26e03e929045a16\", \"nacl_loader_unittests\": \"9605c48287de9f41c2545a1d2731d5ecf655d127\", \"unit_tests\": \"0920dfb44fe4ef4155524b59423b299274fdea8e\", \"content_browsertests\": \"2b7f4dba508bc3c15cbf0d793bb2cf91560162e2\", \"printing_unittests\": \"27826239fe207d9bc9c7bcb6eada045c7f58ee94\", \"device_unittests\": \"beeaaa852b277c01a43c73fe3de090ab4490cd40\"}, \"path_config\": \"generic\", \"bot_id\": \"swarm2173-c4\", \"got_buildtools_revision\": \"2dff9c9c74e9d732e6fe57c84ef7fd044cc45d96\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1536666457781107",
"status": "COMPLETED",
"status_changed_ts": "1536672386982990",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/10051",
"builder:Windows deterministic",
"buildset:commit/git/3cdeae874cc9479e6b71ae8b48163c8c3e2e99a6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3cdeae874cc9479e6b71ae8b48163c8c3e2e99a6",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9101112216964328416",
"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/10051",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8935682154801371280",
"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/3cdeae874cc9479e6b71ae8b48163c8c3e2e99a6",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3cdeae874cc9479e6b71ae8b48163c8c3e2e99a6",
"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/8935682154801371280/+/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:9101112216964328416",
"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:330bd7d6e144325cfde38e2af74daf2646e1a502",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:3fe146d263834c10",
"user_agent:luci-scheduler"
],
"updated_ts": "1536672386990185",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/10051",
"utcnow_ts": "1536672387675077"
}
}
[13:26:27.686] Invocation finished in 1h38m57.52907051s with status SUCCEEDED