[18:05:28.442] New invocation is queued and will start shortly
[18:05:29.647] Starting the invocation (attempt 1)
[18:05:29.678] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:05:29.678] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:05:29.678] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9102809779439106448",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"d4e8c1e22414ef1f9ab777fb36af556b03a954e4\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9102809779439106448",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/d4e8c1e22414ef1f9ab777fb36af556b03a954e4",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d4e8c1e22414ef1f9ab777fb36af556b03a954e4",
"gitiles_ref:refs/heads/master"
]
}
[18:05:31.865] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1535047529815730",
"id": "8937379717335069536",
"lease_expiration_ts": "1535191531068940",
"lease_key": "1537148295",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"d4e8c1e22414ef1f9ab777fb36af556b03a954e4\"}}",
"project": "chromium",
"result_details_json": "null",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1535047531621880",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/9837",
"builder:Windows deterministic",
"buildset:commit/git/d4e8c1e22414ef1f9ab777fb36af556b03a954e4",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d4e8c1e22414ef1f9ab777fb36af556b03a954e4",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9102809779439106448",
"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/9837",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8937379717335069536",
"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/d4e8c1e22414ef1f9ab777fb36af556b03a954e4",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d4e8c1e22414ef1f9ab777fb36af556b03a954e4",
"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/8937379717335069536/+/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:9102809779439106448",
"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:f2b2a3370f2f026c679aa594e6083e3108ab7862",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:3f80c80150974e10",
"user_agent:luci-scheduler"
],
"updated_ts": "1535047531622010",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/9837",
"utcnow_ts": "1535047531791590"
}
}
[18:05:31.865] Task URL: https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/9837
[18:05:31.865] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:2:0) after 1m0s
[18:05:33.913] Received PubSub notification, asking Buildbucket for the build status
[18:05:34.009] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:31.910] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:2:0)
[18:06:31.910] Timer tick, asking Buildbucket for the build status
[18:06:31.952] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:31.952] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:4:0) after 1m0s
[18:07:31.976] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:4:0)
[18:07:31.976] Timer tick, asking Buildbucket for the build status
[18:07:32.143] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:32.143] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:5:0) after 1m0s
[18:08:32.162] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:5:0)
[18:08:32.162] Timer tick, asking Buildbucket for the build status
[18:08:32.252] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:32.252] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:6:0) after 1m0s
[18:09:32.309] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:6:0)
[18:09:32.309] Timer tick, asking Buildbucket for the build status
[18:09:32.412] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:32.412] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:7:0) after 1m0s
[18:10:32.435] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:7:0)
[18:10:32.435] Timer tick, asking Buildbucket for the build status
[18:10:32.593] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:32.593] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:8:0) after 1m0s
[18:11:32.608] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:8:0)
[18:11:32.608] Timer tick, asking Buildbucket for the build status
[18:11:32.702] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:32.702] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:9:0) after 1m0s
[18:12:32.746] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:9:0)
[18:12:32.746] Timer tick, asking Buildbucket for the build status
[18:12:32.831] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:32.831] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:10:0) after 1m0s
[18:13:32.856] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:10:0)
[18:13:32.856] Timer tick, asking Buildbucket for the build status
[18:13:32.977] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:32.977] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:11:0) after 1m0s
[18:14:32.999] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:11:0)
[18:14:32.999] Timer tick, asking Buildbucket for the build status
[18:14:33.061] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:33.061] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:12:0) after 1m0s
[18:15:33.084] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:12:0)
[18:15:33.084] Timer tick, asking Buildbucket for the build status
[18:15:33.124] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:33.124] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:13:0) after 1m0s
[18:16:33.163] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:13:0)
[18:16:33.163] Timer tick, asking Buildbucket for the build status
[18:16:33.203] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:33.203] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:14:0) after 1m0s
[18:17:33.227] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:14:0)
[18:17:33.227] Timer tick, asking Buildbucket for the build status
[18:17:33.260] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:33.260] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:15:0) after 1m0s
[18:18:33.302] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:15:0)
[18:18:33.302] Timer tick, asking Buildbucket for the build status
[18:18:33.371] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:33.371] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:16:0) after 1m0s
[18:19:33.389] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:16:0)
[18:19:33.389] Timer tick, asking Buildbucket for the build status
[18:19:33.426] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:33.426] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:17:0) after 1m0s
[18:20:33.445] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:17:0)
[18:20:33.445] Timer tick, asking Buildbucket for the build status
[18:20:33.510] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:33.510] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:18:0) after 1m0s
[18:21:33.541] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:18:0)
[18:21:33.541] Timer tick, asking Buildbucket for the build status
[18:21:33.614] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:33.614] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:19:0) after 1m0s
[18:22:33.634] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:19:0)
[18:22:33.634] Timer tick, asking Buildbucket for the build status
[18:22:33.731] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:33.731] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:20:0) after 1m0s
[18:23:33.758] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:20:0)
[18:23:33.758] Timer tick, asking Buildbucket for the build status
[18:23:33.806] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:33.806] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:21:0) after 1m0s
[18:24:33.828] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:21:0)
[18:24:33.828] Timer tick, asking Buildbucket for the build status
[18:24:33.861] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:33.861] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:22:0) after 1m0s
[18:25:33.894] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:22:0)
[18:25:33.894] Timer tick, asking Buildbucket for the build status
[18:25:33.953] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:33.953] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:23:0) after 1m0s
[18:26:33.971] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:23:0)
[18:26:33.971] Timer tick, asking Buildbucket for the build status
[18:26:34.150] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:34.150] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:24:0) after 1m0s
[18:27:34.167] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:24:0)
[18:27:34.167] Timer tick, asking Buildbucket for the build status
[18:27:34.263] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:34.263] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:25:0) after 1m0s
[18:28:34.307] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:25:0)
[18:28:34.307] Timer tick, asking Buildbucket for the build status
[18:28:34.366] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:34.366] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:26:0) after 1m0s
[18:29:34.386] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:26:0)
[18:29:34.386] Timer tick, asking Buildbucket for the build status
[18:29:34.424] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:34.424] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:27:0) after 1m0s
[18:30:34.443] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:27:0)
[18:30:34.443] Timer tick, asking Buildbucket for the build status
[18:30:34.524] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:34.524] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:28:0) after 1m0s
[18:31:34.552] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:28:0)
[18:31:34.552] Timer tick, asking Buildbucket for the build status
[18:31:34.618] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:34.619] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:29:0) after 1m0s
[18:32:34.645] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:29:0)
[18:32:34.645] Timer tick, asking Buildbucket for the build status
[18:32:34.701] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:34.701] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:30:0) after 1m0s
[18:33:34.730] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:30:0)
[18:33:34.730] Timer tick, asking Buildbucket for the build status
[18:33:34.773] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:34.773] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:31:0) after 1m0s
[18:34:34.851] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:31:0)
[18:34:34.851] Timer tick, asking Buildbucket for the build status
[18:34:34.889] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:34.889] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:32:0) after 1m0s
[18:35:34.966] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:32:0)
[18:35:34.966] Timer tick, asking Buildbucket for the build status
[18:35:35.058] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:35.058] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:33:0) after 1m0s
[18:36:35.075] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:33:0)
[18:36:35.075] Timer tick, asking Buildbucket for the build status
[18:36:35.167] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:35.167] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:34:0) after 1m0s
[18:37:35.187] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:34:0)
[18:37:35.187] Timer tick, asking Buildbucket for the build status
[18:37:35.232] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:35.232] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:35:0) after 1m0s
[18:38:35.271] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:35:0)
[18:38:35.271] Timer tick, asking Buildbucket for the build status
[18:38:35.393] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:35.393] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:36:0) after 1m0s
[18:39:35.411] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:36:0)
[18:39:35.411] Timer tick, asking Buildbucket for the build status
[18:39:35.558] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:35.558] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:37:0) after 1m0s
[18:40:35.577] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:37:0)
[18:40:35.577] Timer tick, asking Buildbucket for the build status
[18:40:35.836] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:35.836] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:38:0) after 1m0s
[18:41:35.875] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:38:0)
[18:41:35.875] Timer tick, asking Buildbucket for the build status
[18:41:35.937] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:35.937] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:39:0) after 1m0s
[18:42:36.001] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:39:0)
[18:42:36.001] Timer tick, asking Buildbucket for the build status
[18:42:36.037] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:36.037] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:40:0) after 1m0s
[18:43:36.082] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:40:0)
[18:43:36.082] Timer tick, asking Buildbucket for the build status
[18:43:36.171] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:36.171] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:41:0) after 1m0s
[18:44:36.224] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:41:0)
[18:44:36.224] Timer tick, asking Buildbucket for the build status
[18:44:36.290] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:36.290] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:42:0) after 1m0s
[18:45:36.314] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:42:0)
[18:45:36.314] Timer tick, asking Buildbucket for the build status
[18:45:36.349] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:36.349] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:43:0) after 1m0s
[18:46:36.371] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:43:0)
[18:46:36.371] Timer tick, asking Buildbucket for the build status
[18:46:36.440] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:36.440] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:44:0) after 1m0s
[18:47:36.550] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:44:0)
[18:47:36.550] Timer tick, asking Buildbucket for the build status
[18:47:36.586] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:36.586] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:45:0) after 1m0s
[18:48:36.609] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:45:0)
[18:48:36.609] Timer tick, asking Buildbucket for the build status
[18:48:36.668] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:36.668] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:46:0) after 1m0s
[18:49:36.752] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:46:0)
[18:49:36.752] Timer tick, asking Buildbucket for the build status
[18:49:36.791] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:36.791] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:47:0) after 1m0s
[18:50:36.810] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:47:0)
[18:50:36.810] Timer tick, asking Buildbucket for the build status
[18:50:36.889] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:36.889] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:48:0) after 1m0s
[18:51:36.916] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:48:0)
[18:51:36.916] Timer tick, asking Buildbucket for the build status
[18:51:36.965] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:36.965] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:49:0) after 1m0s
[18:52:36.988] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:49:0)
[18:52:36.988] Timer tick, asking Buildbucket for the build status
[18:52:37.030] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:37.030] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:50:0) after 1m0s
[18:53:37.079] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:50:0)
[18:53:37.079] Timer tick, asking Buildbucket for the build status
[18:53:37.120] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:37.120] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:51:0) after 1m0s
[18:54:37.147] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:51:0)
[18:54:37.147] Timer tick, asking Buildbucket for the build status
[18:54:37.243] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:37.243] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:52:0) after 1m0s
[18:55:37.264] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:52:0)
[18:55:37.264] Timer tick, asking Buildbucket for the build status
[18:55:37.376] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:37.376] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:53:0) after 1m0s
[18:56:37.398] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:53:0)
[18:56:37.398] Timer tick, asking Buildbucket for the build status
[18:56:37.515] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:37.515] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:54:0) after 1m0s
[18:57:37.543] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:54:0)
[18:57:37.543] Timer tick, asking Buildbucket for the build status
[18:57:37.579] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:37.579] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:55:0) after 1m0s
[18:58:37.664] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:55:0)
[18:58:37.664] Timer tick, asking Buildbucket for the build status
[18:58:37.728] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:37.728] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:56:0) after 1m0s
[18:59:37.757] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:56:0)
[18:59:37.757] Timer tick, asking Buildbucket for the build status
[18:59:37.980] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:37.980] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:57:0) after 1m0s
[19:00:37.998] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:57:0)
[19:00:37.998] Timer tick, asking Buildbucket for the build status
[19:00:38.489] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:38.489] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:58:0) after 1m0s
[19:01:38.519] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:58:0)
[19:01:38.519] Timer tick, asking Buildbucket for the build status
[19:01:38.816] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:38.816] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:59:0) after 1m0s
[19:02:38.856] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:59:0)
[19:02:38.856] Timer tick, asking Buildbucket for the build status
[19:02:38.899] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:38.899] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:60:0) after 1m0s
[19:03:38.921] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:60:0)
[19:03:38.921] Timer tick, asking Buildbucket for the build status
[19:03:39.007] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:39.007] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:61:0) after 1m0s
[19:04:39.040] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:61:0)
[19:04:39.040] Timer tick, asking Buildbucket for the build status
[19:04:39.133] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:39.133] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:62:0) after 1m0s
[19:05:39.156] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:62:0)
[19:05:39.156] Timer tick, asking Buildbucket for the build status
[19:05:39.229] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:39.229] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:63:0) after 1m0s
[19:06:39.257] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:63:0)
[19:06:39.257] Timer tick, asking Buildbucket for the build status
[19:06:39.298] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:39.298] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:64:0) after 1m0s
[19:07:39.316] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:64:0)
[19:07:39.316] Timer tick, asking Buildbucket for the build status
[19:07:39.362] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:39.362] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:65:0) after 1m0s
[19:08:39.382] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:65:0)
[19:08:39.382] Timer tick, asking Buildbucket for the build status
[19:08:39.445] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:39.445] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:66:0) after 1m0s
[19:09:39.475] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:66:0)
[19:09:39.475] Timer tick, asking Buildbucket for the build status
[19:09:39.678] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:39.678] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:67:0) after 1m0s
[19:10:39.706] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:67:0)
[19:10:39.706] Timer tick, asking Buildbucket for the build status
[19:10:39.749] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:39.749] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:68:0) after 1m0s
[19:11:39.826] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:68:0)
[19:11:39.826] Timer tick, asking Buildbucket for the build status
[19:11:39.964] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:39.964] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:69:0) after 1m0s
[19:12:39.990] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:69:0)
[19:12:39.990] Timer tick, asking Buildbucket for the build status
[19:12:40.058] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:40.058] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:70:0) after 1m0s
[19:13:40.154] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:70:0)
[19:13:40.154] Timer tick, asking Buildbucket for the build status
[19:13:40.226] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:40.226] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:71:0) after 1m0s
[19:14:40.250] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:71:0)
[19:14:40.250] Timer tick, asking Buildbucket for the build status
[19:14:40.347] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:40.347] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:72:0) after 1m0s
[19:15:40.375] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:72:0)
[19:15:40.375] Timer tick, asking Buildbucket for the build status
[19:15:40.553] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:40.554] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:73:0) after 1m0s
[19:16:40.573] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:73:0)
[19:16:40.573] Timer tick, asking Buildbucket for the build status
[19:16:40.640] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:40.640] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:74:0) after 1m0s
[19:17:40.665] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:74:0)
[19:17:40.665] Timer tick, asking Buildbucket for the build status
[19:17:40.723] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:40.723] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:75:0) after 1m0s
[19:18:40.792] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:75:0)
[19:18:40.792] Timer tick, asking Buildbucket for the build status
[19:18:40.858] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:40.858] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:76:0) after 1m0s
[19:19:40.952] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:76:0)
[19:19:40.952] Timer tick, asking Buildbucket for the build status
[19:19:41.084] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:41.084] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:77:0) after 1m0s
[19:20:41.106] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:77:0)
[19:20:41.106] Timer tick, asking Buildbucket for the build status
[19:20:41.180] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:41.180] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:78:0) after 1m0s
[19:21:41.202] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:78:0)
[19:21:41.202] Timer tick, asking Buildbucket for the build status
[19:21:41.243] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:41.243] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:79:0) after 1m0s
[19:22:41.267] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:79:0)
[19:22:41.267] Timer tick, asking Buildbucket for the build status
[19:22:41.439] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:41.439] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:80:0) after 1m0s
[19:23:41.459] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:80:0)
[19:23:41.459] Timer tick, asking Buildbucket for the build status
[19:23:41.555] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:41.555] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:81:0) after 1m0s
[19:24:41.575] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:81:0)
[19:24:41.575] Timer tick, asking Buildbucket for the build status
[19:24:41.704] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:41.704] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:82:0) after 1m0s
[19:25:41.766] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:82:0)
[19:25:41.766] Timer tick, asking Buildbucket for the build status
[19:25:41.812] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:41.812] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:83:0) after 1m0s
[19:26:41.852] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:83:0)
[19:26:41.852] Timer tick, asking Buildbucket for the build status
[19:26:41.906] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:41.906] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:84:0) after 1m0s
[19:27:41.937] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:84:0)
[19:27:41.937] Timer tick, asking Buildbucket for the build status
[19:27:42.029] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:42.029] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:85:0) after 1m0s
[19:28:42.053] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:85:0)
[19:28:42.053] Timer tick, asking Buildbucket for the build status
[19:28:42.125] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:42.125] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:86:0) after 1m0s
[19:29:42.153] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:86:0)
[19:29:42.153] Timer tick, asking Buildbucket for the build status
[19:29:42.273] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:42.273] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:87:0) after 1m0s
[19:30:42.319] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:87:0)
[19:30:42.319] Timer tick, asking Buildbucket for the build status
[19:30:42.368] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:42.368] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:88:0) after 1m0s
[19:31:42.398] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:88:0)
[19:31:42.398] Timer tick, asking Buildbucket for the build status
[19:31:42.846] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:42.847] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:89:0) after 1m0s
[19:32:42.898] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:89:0)
[19:32:42.898] Timer tick, asking Buildbucket for the build status
[19:32:42.989] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:42.989] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:90:0) after 1m0s
[19:33:43.016] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:90:0)
[19:33:43.016] Timer tick, asking Buildbucket for the build status
[19:33:43.111] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:43.111] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:91:0) after 1m0s
[19:34:43.130] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:91:0)
[19:34:43.130] Timer tick, asking Buildbucket for the build status
[19:34:43.343] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:43.343] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:92:0) after 1m0s
[19:35:43.431] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:92:0)
[19:35:43.432] Timer tick, asking Buildbucket for the build status
[19:35:43.810] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:43.810] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:93:0) after 1m0s
[19:36:43.831] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:93:0)
[19:36:43.831] Timer tick, asking Buildbucket for the build status
[19:36:43.866] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:43.866] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:94:0) after 1m0s
[19:37:43.887] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:94:0)
[19:37:43.887] Timer tick, asking Buildbucket for the build status
[19:37:43.949] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:43.949] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:95:0) after 1m0s
[19:38:43.970] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:95:0)
[19:38:43.970] Timer tick, asking Buildbucket for the build status
[19:38:44.089] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:44.089] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:96:0) after 1m0s
[19:39:44.150] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:96:0)
[19:39:44.150] Timer tick, asking Buildbucket for the build status
[19:39:44.251] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:44.251] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:97:0) after 1m0s
[19:40:44.271] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:97:0)
[19:40:44.271] Timer tick, asking Buildbucket for the build status
[19:40:44.318] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:44.318] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:98:0) after 1m0s
[19:41:44.345] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:98:0)
[19:41:44.345] Timer tick, asking Buildbucket for the build status
[19:41:44.407] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:44.407] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:99:0) after 1m0s
[19:42:44.493] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:99:0)
[19:42:44.493] Timer tick, asking Buildbucket for the build status
[19:42:44.587] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:44.587] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:100:0) after 1m0s
[19:43:44.617] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:100:0)
[19:43:44.617] Timer tick, asking Buildbucket for the build status
[19:43:44.682] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:44.682] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:101:0) after 1m0s
[19:44:44.702] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:101:0)
[19:44:44.702] Timer tick, asking Buildbucket for the build status
[19:44:44.756] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:44.756] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:102:0) after 1m0s
[19:45:44.852] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:102:0)
[19:45:44.852] Timer tick, asking Buildbucket for the build status
[19:45:45.000] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:45.000] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:103:0) after 1m0s
[19:46:45.028] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:103:0)
[19:46:45.028] Timer tick, asking Buildbucket for the build status
[19:46:45.101] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:45.101] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:104:0) after 1m0s
[19:47:45.179] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:104:0)
[19:47:45.179] Timer tick, asking Buildbucket for the build status
[19:47:45.247] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:45.247] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:105:0) after 1m0s
[19:48:45.337] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:105:0)
[19:48:45.337] Timer tick, asking Buildbucket for the build status
[19:48:45.459] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:45.459] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:106:0) after 1m0s
[19:49:45.480] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:106:0)
[19:49:45.480] Timer tick, asking Buildbucket for the build status
[19:49:45.684] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:45.684] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:107:0) after 1m0s
[19:50:45.709] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:107:0)
[19:50:45.709] Timer tick, asking Buildbucket for the build status
[19:50:45.765] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:45.765] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:108:0) after 1m0s
[19:51:45.859] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:108:0)
[19:51:45.859] Timer tick, asking Buildbucket for the build status
[19:51:45.932] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:45.932] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:109:0) after 1m0s
[19:52:45.952] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:109:0)
[19:52:45.952] Timer tick, asking Buildbucket for the build status
[19:52:46.108] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:46.108] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:110:0) after 1m0s
[19:53:46.205] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:110:0)
[19:53:46.205] Timer tick, asking Buildbucket for the build status
[19:53:46.295] Build 8937379717335069536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:46.295] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102809779439106448:111:0) after 1m0s
[19:54:30.864] Received PubSub notification, asking Buildbucket for the build status
[19:54:30.904] Build 8937379717335069536: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[19:54:30.904] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1535054056331660",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1535047529815730",
"id": "8937379717335069536",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"d4e8c1e22414ef1f9ab777fb36af556b03a954e4\"}}",
"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/8937379717335069536/+/annotations\"}, \"swarming\": {\"bot_dimensions\": {\"machine_type\": [\"n1-standard-8\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"], \"locale\": [\"en_US.cp1252\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"builder\": [\"Windows deterministic\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\"], \"python\": [\"2.7.6\"], \"server_version\": [\"3740-90b257a\"], \"image\": [\"chrome-win10-1703-2018-04-20-61acaf8983f\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"integrity\": [\"high\"], \"id\": [\"swarm2173-c4\"], \"pool\": [\"luci.chromium.ci\"], \"caches\": [\"git\", \"goma_v2\", \"vpython\"]}}, \"properties\": {\"got_nacl_revision\": \"9a87d6b9de35325efb08321640879e79ed0dfcc9\", \"got_swarming_client_revision\": \"486c9b53c4d54dd4b95bb6ce0e31160e600dfc11\", \"got_revision\": \"d4e8c1e22414ef1f9ab777fb36af556b03a954e4\", \"recipe\": \"swarming/deterministic_build\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#24373}\", \"$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@{#585541}\", \"branch\": \"refs/heads/master\", \"revision\": \"d4e8c1e22414ef1f9ab777fb36af556b03a954e4\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"efbcb31cb67e3090b82c09ed5aabc4bbc53f37be\", \"mastername\": \"chromium.fyi\", \"got_angle_revision\": \"c40974417610afb020c0b99a6b038f81257435cd\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1535047529815730, \"tags\": [\"builder:Windows deterministic\", \"buildset:commit/git/d4e8c1e22414ef1f9ab777fb36af556b03a954e4\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d4e8c1e22414ef1f9ab777fb36af556b03a954e4\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9102809779439106448\", \"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\": \"8937379717335069536\"}}, \"got_v8_revision\": \"132145aaee8c9e4c7d089763a3dd40646e686516\", \"got_v8_revision_cp\": \"refs/heads/7.0.251@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 9837, \"path_config\": \"generic\", \"bot_id\": \"swarm2173-c4\", \"got_buildtools_revision\": \"2dff9c9c74e9d732e6fe57c84ef7fd044cc45d96\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1535047532900820",
"status": "COMPLETED",
"status_changed_ts": "1535054070399800",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/9837",
"builder:Windows deterministic",
"buildset:commit/git/d4e8c1e22414ef1f9ab777fb36af556b03a954e4",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d4e8c1e22414ef1f9ab777fb36af556b03a954e4",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9102809779439106448",
"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/9837",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8937379717335069536",
"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/d4e8c1e22414ef1f9ab777fb36af556b03a954e4",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d4e8c1e22414ef1f9ab777fb36af556b03a954e4",
"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/8937379717335069536/+/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:9102809779439106448",
"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:f2b2a3370f2f026c679aa594e6083e3108ab7862",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:3f80c80150974e10",
"user_agent:luci-scheduler"
],
"updated_ts": "1535054070404380",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/9837",
"utcnow_ts": "1535054070893420"
}
}
[19:54:30.904] Invocation finished in 1h49m2.478382917s with status SUCCEEDED