[08:55:12.764] New invocation is queued and will start shortly
[08:55:14.018] Starting the invocation (attempt 1)
[08:55:14.050] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:55:14.050] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:55:14.050] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9102663204954691936",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"72acdf58284a507f427c1a54c36a23f10646679f\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9102663204954691936",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/72acdf58284a507f427c1a54c36a23f10646679f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/72acdf58284a507f427c1a54c36a23f10646679f",
"gitiles_ref:refs/heads/master"
]
}
[08:55:15.780] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1535187314181390",
"id": "8937233142803816640",
"lease_expiration_ts": "1535331315526340",
"lease_key": "241769066",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"72acdf58284a507f427c1a54c36a23f10646679f\"}}",
"project": "chromium",
"result_details_json": "null",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1535187315663800",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/9858",
"builder:Windows deterministic",
"buildset:commit/git/72acdf58284a507f427c1a54c36a23f10646679f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/72acdf58284a507f427c1a54c36a23f10646679f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9102663204954691936",
"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/9858",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8937233142803816640",
"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/72acdf58284a507f427c1a54c36a23f10646679f",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/72acdf58284a507f427c1a54c36a23f10646679f",
"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/8937233142803816640/+/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:9102663204954691936",
"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:2fdf38dfa532bad52599ef89e33e1e0f1f1d4201",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:3f891cf15e2ef310",
"user_agent:luci-scheduler"
],
"updated_ts": "1535187315663920",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/9858",
"utcnow_ts": "1535187315767020"
}
}
[08:55:15.780] Task URL: https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/9858
[08:55:15.780] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:2:0) after 1m0s
[08:55:17.049] Received PubSub notification, asking Buildbucket for the build status
[08:55:17.081] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:15.797] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:2:0)
[08:56:15.797] Timer tick, asking Buildbucket for the build status
[08:56:15.963] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:15.963] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:4:0) after 1m0s
[08:57:15.981] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:4:0)
[08:57:15.982] Timer tick, asking Buildbucket for the build status
[08:57:16.019] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:16.019] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:5:0) after 1m0s
[08:58:16.045] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:5:0)
[08:58:16.045] Timer tick, asking Buildbucket for the build status
[08:58:16.115] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:16.115] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:6:0) after 1m0s
[08:59:16.211] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:6:0)
[08:59:16.211] Timer tick, asking Buildbucket for the build status
[08:59:16.322] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:16.322] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:7:0) after 1m0s
[09:00:16.345] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:7:0)
[09:00:16.345] Timer tick, asking Buildbucket for the build status
[09:00:16.469] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:16.469] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:8:0) after 1m0s
[09:01:16.582] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:8:0)
[09:01:16.582] Timer tick, asking Buildbucket for the build status
[09:01:16.641] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:16.641] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:9:0) after 1m0s
[09:02:16.792] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:9:0)
[09:02:16.792] Timer tick, asking Buildbucket for the build status
[09:02:16.830] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:16.830] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:10:0) after 1m0s
[09:03:16.897] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:10:0)
[09:03:16.897] Timer tick, asking Buildbucket for the build status
[09:03:17.146] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:17.146] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:11:0) after 1m0s
[09:04:17.167] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:11:0)
[09:04:17.167] Timer tick, asking Buildbucket for the build status
[09:04:17.266] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:17.266] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:12:0) after 1m0s
[09:05:17.317] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:12:0)
[09:05:17.317] Timer tick, asking Buildbucket for the build status
[09:05:17.354] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:17.354] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:13:0) after 1m0s
[09:06:17.388] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:13:0)
[09:06:17.388] Timer tick, asking Buildbucket for the build status
[09:06:17.552] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:17.552] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:14:0) after 1m0s
[09:07:17.688] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:14:0)
[09:07:17.688] Timer tick, asking Buildbucket for the build status
[09:07:17.752] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:17.752] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:15:0) after 1m0s
[09:08:17.773] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:15:0)
[09:08:17.773] Timer tick, asking Buildbucket for the build status
[09:08:17.830] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:17.830] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:16:0) after 1m0s
[09:09:17.936] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:16:0)
[09:09:17.936] Timer tick, asking Buildbucket for the build status
[09:09:17.998] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:17.998] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:17:0) after 1m0s
[09:10:18.074] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:17:0)
[09:10:18.074] Timer tick, asking Buildbucket for the build status
[09:10:18.136] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:18.136] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:18:0) after 1m0s
[09:11:18.154] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:18:0)
[09:11:18.154] Timer tick, asking Buildbucket for the build status
[09:11:18.373] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:18.373] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:19:0) after 1m0s
[09:12:18.391] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:19:0)
[09:12:18.391] Timer tick, asking Buildbucket for the build status
[09:12:18.572] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:18.572] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:20:0) after 1m0s
[09:13:18.592] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:20:0)
[09:13:18.592] Timer tick, asking Buildbucket for the build status
[09:13:18.686] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:18.686] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:21:0) after 1m0s
[09:14:18.707] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:21:0)
[09:14:18.707] Timer tick, asking Buildbucket for the build status
[09:14:18.853] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:18.853] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:22:0) after 1m0s
[09:15:18.873] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:22:0)
[09:15:18.873] Timer tick, asking Buildbucket for the build status
[09:15:18.982] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:18.982] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:23:0) after 1m0s
[09:16:19.002] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:23:0)
[09:16:19.002] Timer tick, asking Buildbucket for the build status
[09:16:19.202] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:19.202] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:24:0) after 1m0s
[09:17:19.221] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:24:0)
[09:17:19.221] Timer tick, asking Buildbucket for the build status
[09:17:19.299] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:19.299] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:25:0) after 1m0s
[09:18:19.341] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:25:0)
[09:18:19.341] Timer tick, asking Buildbucket for the build status
[09:18:19.392] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:19.392] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:26:0) after 1m0s
[09:19:19.412] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:26:0)
[09:19:19.412] Timer tick, asking Buildbucket for the build status
[09:19:19.532] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:19.532] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:27:0) after 1m0s
[09:20:19.603] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:27:0)
[09:20:19.603] Timer tick, asking Buildbucket for the build status
[09:20:19.664] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:19.664] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:28:0) after 1m0s
[09:21:20.312] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:28:0)
[09:21:20.312] Timer tick, asking Buildbucket for the build status
[09:21:20.462] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:20.462] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:29:0) after 1m0s
[09:22:20.491] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:29:0)
[09:22:20.491] Timer tick, asking Buildbucket for the build status
[09:22:20.548] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:20.548] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:30:0) after 1m0s
[09:23:20.658] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:30:0)
[09:23:20.658] Timer tick, asking Buildbucket for the build status
[09:23:20.787] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:20.787] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:31:0) after 1m0s
[09:24:20.806] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:31:0)
[09:24:20.806] Timer tick, asking Buildbucket for the build status
[09:24:20.848] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:20.848] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:32:0) after 1m0s
[09:25:20.910] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:32:0)
[09:25:20.910] Timer tick, asking Buildbucket for the build status
[09:25:20.974] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:20.974] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:33:0) after 1m0s
[09:26:21.010] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:33:0)
[09:26:21.010] Timer tick, asking Buildbucket for the build status
[09:26:21.048] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:21.048] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:34:0) after 1m0s
[09:27:21.072] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:34:0)
[09:27:21.072] Timer tick, asking Buildbucket for the build status
[09:27:21.125] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:21.125] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:35:0) after 1m0s
[09:28:21.153] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:35:0)
[09:28:21.153] Timer tick, asking Buildbucket for the build status
[09:28:21.342] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:21.342] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:36:0) after 1m0s
[09:29:21.361] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:36:0)
[09:29:21.361] Timer tick, asking Buildbucket for the build status
[09:29:21.409] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:21.409] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:37:0) after 1m0s
[09:30:21.429] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:37:0)
[09:30:21.429] Timer tick, asking Buildbucket for the build status
[09:30:21.484] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:21.484] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:38:0) after 1m0s
[09:31:21.510] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:38:0)
[09:31:21.510] Timer tick, asking Buildbucket for the build status
[09:31:21.572] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:21.572] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:39:0) after 1m0s
[09:32:21.631] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:39:0)
[09:32:21.631] Timer tick, asking Buildbucket for the build status
[09:32:21.721] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:21.721] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:40:0) after 1m0s
[09:33:21.741] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:40:0)
[09:33:21.741] Timer tick, asking Buildbucket for the build status
[09:33:21.852] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:21.852] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:41:0) after 1m0s
[09:34:21.869] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:41:0)
[09:34:21.869] Timer tick, asking Buildbucket for the build status
[09:34:21.925] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:21.925] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:42:0) after 1m0s
[09:35:21.946] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:42:0)
[09:35:21.946] Timer tick, asking Buildbucket for the build status
[09:35:22.016] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:22.016] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:43:0) after 1m0s
[09:36:22.041] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:43:0)
[09:36:22.041] Timer tick, asking Buildbucket for the build status
[09:36:22.149] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:22.149] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:44:0) after 1m0s
[09:37:22.204] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:44:0)
[09:37:22.204] Timer tick, asking Buildbucket for the build status
[09:37:22.244] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:22.244] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:45:0) after 1m0s
[09:38:22.269] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:45:0)
[09:38:22.269] Timer tick, asking Buildbucket for the build status
[09:38:22.340] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:22.340] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:46:0) after 1m0s
[09:39:22.365] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:46:0)
[09:39:22.365] Timer tick, asking Buildbucket for the build status
[09:39:22.439] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:22.439] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:47:0) after 1m0s
[09:40:22.459] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:47:0)
[09:40:22.459] Timer tick, asking Buildbucket for the build status
[09:40:22.538] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:22.538] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:48:0) after 1m0s
[09:41:22.559] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:48:0)
[09:41:22.559] Timer tick, asking Buildbucket for the build status
[09:41:22.598] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:22.598] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:49:0) after 1m0s
[09:42:22.620] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:49:0)
[09:42:22.620] Timer tick, asking Buildbucket for the build status
[09:42:22.686] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:22.686] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:50:0) after 1m0s
[09:43:22.738] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:50:0)
[09:43:22.738] Timer tick, asking Buildbucket for the build status
[09:43:22.774] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:22.774] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:51:0) after 1m0s
[09:44:22.917] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:51:0)
[09:44:22.917] Timer tick, asking Buildbucket for the build status
[09:44:22.979] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:22.979] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:52:0) after 1m0s
[09:45:23.112] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:52:0)
[09:45:23.112] Timer tick, asking Buildbucket for the build status
[09:45:23.366] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:23.366] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:53:0) after 1m0s
[09:46:23.467] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:53:0)
[09:46:23.467] Timer tick, asking Buildbucket for the build status
[09:46:23.506] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:23.506] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:54:0) after 1m0s
[09:47:23.637] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:54:0)
[09:47:23.638] Timer tick, asking Buildbucket for the build status
[09:47:23.781] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:23.781] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:55:0) after 1m0s
[09:48:23.815] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:55:0)
[09:48:23.815] Timer tick, asking Buildbucket for the build status
[09:48:23.942] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:23.942] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:56:0) after 1m0s
[09:49:24.034] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:56:0)
[09:49:24.034] Timer tick, asking Buildbucket for the build status
[09:49:24.068] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:24.068] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:57:0) after 1m0s
[09:50:24.196] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:57:0)
[09:50:24.196] Timer tick, asking Buildbucket for the build status
[09:50:24.302] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:24.302] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:58:0) after 1m0s
[09:51:24.398] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:58:0)
[09:51:24.398] Timer tick, asking Buildbucket for the build status
[09:51:24.472] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:24.472] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:59:0) after 1m0s
[09:52:24.558] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:59:0)
[09:52:24.558] Timer tick, asking Buildbucket for the build status
[09:52:24.673] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:24.673] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:60:0) after 1m0s
[09:53:24.704] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:60:0)
[09:53:24.704] Timer tick, asking Buildbucket for the build status
[09:53:24.874] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:24.874] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:61:0) after 1m0s
[09:54:24.913] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:61:0)
[09:54:24.913] Timer tick, asking Buildbucket for the build status
[09:54:25.069] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:25.069] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:62:0) after 1m0s
[09:55:25.096] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:62:0)
[09:55:25.096] Timer tick, asking Buildbucket for the build status
[09:55:25.149] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:25.149] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:63:0) after 1m0s
[09:56:25.261] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:63:0)
[09:56:25.261] Timer tick, asking Buildbucket for the build status
[09:56:25.332] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:25.332] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:64:0) after 1m0s
[09:57:25.428] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:64:0)
[09:57:25.428] Timer tick, asking Buildbucket for the build status
[09:57:25.728] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:25.728] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:65:0) after 1m0s
[09:58:25.802] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:65:0)
[09:58:25.802] Timer tick, asking Buildbucket for the build status
[09:58:25.866] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:25.866] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:66:0) after 1m0s
[09:59:25.929] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:66:0)
[09:59:25.929] Timer tick, asking Buildbucket for the build status
[09:59:26.059] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:26.059] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:67:0) after 1m0s
[10:00:26.101] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:67:0)
[10:00:26.101] Timer tick, asking Buildbucket for the build status
[10:00:26.214] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:26.214] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:68:0) after 1m0s
[10:01:26.293] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:68:0)
[10:01:26.293] Timer tick, asking Buildbucket for the build status
[10:01:26.497] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:26.497] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:69:0) after 1m0s
[10:02:26.519] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:69:0)
[10:02:26.519] Timer tick, asking Buildbucket for the build status
[10:02:26.618] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:26.618] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:70:0) after 1m0s
[10:03:26.637] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:70:0)
[10:03:26.637] Timer tick, asking Buildbucket for the build status
[10:03:26.702] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:26.702] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:71:0) after 1m0s
[10:04:26.726] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:71:0)
[10:04:26.726] Timer tick, asking Buildbucket for the build status
[10:04:26.764] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:26.764] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:72:0) after 1m0s
[10:05:26.789] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:72:0)
[10:05:26.789] Timer tick, asking Buildbucket for the build status
[10:05:26.845] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:26.845] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:73:0) after 1m0s
[10:06:26.875] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:73:0)
[10:06:26.875] Timer tick, asking Buildbucket for the build status
[10:06:26.945] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:26.945] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:74:0) after 1m0s
[10:07:26.964] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:74:0)
[10:07:26.964] Timer tick, asking Buildbucket for the build status
[10:07:27.037] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:27.037] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:75:0) after 1m0s
[10:08:27.118] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:75:0)
[10:08:27.118] Timer tick, asking Buildbucket for the build status
[10:08:27.190] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:27.190] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:76:0) after 1m0s
[10:09:27.280] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:76:0)
[10:09:27.280] Timer tick, asking Buildbucket for the build status
[10:09:27.365] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:27.365] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:77:0) after 1m0s
[10:10:27.498] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:77:0)
[10:10:27.498] Timer tick, asking Buildbucket for the build status
[10:10:27.586] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:27.586] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:78:0) after 1m0s
[10:11:27.606] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:78:0)
[10:11:27.606] Timer tick, asking Buildbucket for the build status
[10:11:27.655] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:27.655] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:79:0) after 1m0s
[10:12:27.754] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:79:0)
[10:12:27.754] Timer tick, asking Buildbucket for the build status
[10:12:27.795] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:27.795] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:80:0) after 1m0s
[10:13:27.826] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:80:0)
[10:13:27.826] Timer tick, asking Buildbucket for the build status
[10:13:27.890] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:27.890] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:81:0) after 1m0s
[10:14:27.916] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:81:0)
[10:14:27.916] Timer tick, asking Buildbucket for the build status
[10:14:28.089] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:28.089] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:82:0) after 1m0s
[10:15:28.116] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:82:0)
[10:15:28.116] Timer tick, asking Buildbucket for the build status
[10:15:28.174] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:28.174] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:83:0) after 1m0s
[10:16:28.228] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:83:0)
[10:16:28.228] Timer tick, asking Buildbucket for the build status
[10:16:28.517] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:28.517] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:84:0) after 1m0s
[10:17:28.539] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:84:0)
[10:17:28.539] Timer tick, asking Buildbucket for the build status
[10:17:28.689] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:28.689] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:85:0) after 1m0s
[10:18:28.730] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:85:0)
[10:18:28.730] Timer tick, asking Buildbucket for the build status
[10:18:28.769] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:28.769] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:86:0) after 1m0s
[10:19:28.793] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:86:0)
[10:19:28.793] Timer tick, asking Buildbucket for the build status
[10:19:28.845] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:28.845] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:87:0) after 1m0s
[10:20:28.866] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:87:0)
[10:20:28.866] Timer tick, asking Buildbucket for the build status
[10:20:28.995] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:28.995] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:88:0) after 1m0s
[10:21:29.015] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:88:0)
[10:21:29.015] Timer tick, asking Buildbucket for the build status
[10:21:29.124] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:29.124] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:89:0) after 1m0s
[10:22:29.148] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:89:0)
[10:22:29.148] Timer tick, asking Buildbucket for the build status
[10:22:29.222] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:29.222] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:90:0) after 1m0s
[10:23:29.295] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:90:0)
[10:23:29.295] Timer tick, asking Buildbucket for the build status
[10:23:29.349] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:29.349] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:91:0) after 1m0s
[10:24:29.370] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:91:0)
[10:24:29.370] Timer tick, asking Buildbucket for the build status
[10:24:29.432] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:29.432] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:92:0) after 1m0s
[10:25:29.456] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:92:0)
[10:25:29.456] Timer tick, asking Buildbucket for the build status
[10:25:29.535] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:29.535] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:93:0) after 1m0s
[10:26:29.570] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:93:0)
[10:26:29.570] Timer tick, asking Buildbucket for the build status
[10:26:29.626] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:29.626] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:94:0) after 1m0s
[10:27:29.663] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:94:0)
[10:27:29.663] Timer tick, asking Buildbucket for the build status
[10:27:29.725] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:29.725] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:95:0) after 1m0s
[10:28:29.747] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:95:0)
[10:28:29.747] Timer tick, asking Buildbucket for the build status
[10:28:29.837] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:29.837] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:96:0) after 1m0s
[10:29:29.857] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:96:0)
[10:29:29.857] Timer tick, asking Buildbucket for the build status
[10:29:29.912] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:29.912] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:97:0) after 1m0s
[10:30:29.937] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:97:0)
[10:30:29.937] Timer tick, asking Buildbucket for the build status
[10:30:29.993] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:29.993] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:98:0) after 1m0s
[10:31:30.016] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:98:0)
[10:31:30.016] Timer tick, asking Buildbucket for the build status
[10:31:30.059] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:30.059] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:99:0) after 1m0s
[10:32:30.083] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:99:0)
[10:32:30.083] Timer tick, asking Buildbucket for the build status
[10:32:30.523] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:30.523] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:100:0) after 1m0s
[10:33:30.544] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:100:0)
[10:33:30.544] Timer tick, asking Buildbucket for the build status
[10:33:30.696] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:30.696] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:101:0) after 1m0s
[10:34:30.766] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:101:0)
[10:34:30.766] Timer tick, asking Buildbucket for the build status
[10:34:30.918] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:30.918] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:102:0) after 1m0s
[10:35:30.939] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:102:0)
[10:35:30.939] Timer tick, asking Buildbucket for the build status
[10:35:31.238] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:31.238] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:103:0) after 1m0s
[10:36:31.261] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:103:0)
[10:36:31.261] Timer tick, asking Buildbucket for the build status
[10:36:31.405] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:31.405] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:104:0) after 1m0s
[10:37:31.502] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:104:0)
[10:37:31.502] Timer tick, asking Buildbucket for the build status
[10:37:31.705] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:31.705] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:105:0) after 1m0s
[10:38:31.736] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:105:0)
[10:38:31.736] Timer tick, asking Buildbucket for the build status
[10:38:31.862] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:31.862] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:106:0) after 1m0s
[10:39:31.894] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:106:0)
[10:39:31.894] Timer tick, asking Buildbucket for the build status
[10:39:31.952] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:31.952] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:107:0) after 1m0s
[10:40:31.986] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:107:0)
[10:40:31.986] Timer tick, asking Buildbucket for the build status
[10:40:32.128] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:32.128] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:108:0) after 1m0s
[10:41:32.201] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:108:0)
[10:41:32.201] Timer tick, asking Buildbucket for the build status
[10:41:32.302] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:32.302] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:109:0) after 1m0s
[10:42:32.334] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:109:0)
[10:42:32.334] Timer tick, asking Buildbucket for the build status
[10:42:32.417] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:32.417] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:110:0) after 1m0s
[10:43:32.442] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:110:0)
[10:43:32.442] Timer tick, asking Buildbucket for the build status
[10:43:32.642] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:32.642] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:111:0) after 1m0s
[10:44:32.662] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:111:0)
[10:44:32.663] Timer tick, asking Buildbucket for the build status
[10:44:32.722] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:32.722] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:112:0) after 1m0s
[10:45:32.815] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:112:0)
[10:45:32.815] Timer tick, asking Buildbucket for the build status
[10:45:33.053] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:33.053] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:113:0) after 1m0s
[10:46:33.118] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:113:0)
[10:46:33.118] Timer tick, asking Buildbucket for the build status
[10:46:33.388] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:33.388] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:114:0) after 1m0s
[10:47:33.419] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:114:0)
[10:47:33.419] Timer tick, asking Buildbucket for the build status
[10:47:33.535] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:33.535] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:115:0) after 1m0s
[10:48:33.557] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:115:0)
[10:48:33.557] Timer tick, asking Buildbucket for the build status
[10:48:33.630] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:33.630] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:116:0) after 1m0s
[10:49:33.656] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:116:0)
[10:49:33.656] Timer tick, asking Buildbucket for the build status
[10:49:33.823] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:33.823] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:117:0) after 1m0s
[10:50:33.855] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:117:0)
[10:50:33.855] Timer tick, asking Buildbucket for the build status
[10:50:33.971] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:33.971] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:118:0) after 1m0s
[10:51:34.012] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:118:0)
[10:51:34.012] Timer tick, asking Buildbucket for the build status
[10:51:34.518] Build 8937233142803816640: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:34.518] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9102663204954691936:119:0) after 1m0s
[10:52:19.642] Received PubSub notification, asking Buildbucket for the build status
[10:52:19.724] Build 8937233142803816640: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[10:52:19.724] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1535194338607040",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1535187314181390",
"id": "8937233142803816640",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"72acdf58284a507f427c1a54c36a23f10646679f\"}}",
"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/8937233142803816640/+/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\": \"72acdf58284a507f427c1a54c36a23f10646679f\", \"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@{#586131}\", \"branch\": \"refs/heads/master\", \"revision\": \"72acdf58284a507f427c1a54c36a23f10646679f\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"efbcb31cb67e3090b82c09ed5aabc4bbc53f37be\", \"mastername\": \"chromium.fyi\", \"got_angle_revision\": \"8707af516c46f42b38af0c5761b936dd98c81f1f\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1535187314181390, \"tags\": [\"builder:Windows deterministic\", \"buildset:commit/git/72acdf58284a507f427c1a54c36a23f10646679f\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/72acdf58284a507f427c1a54c36a23f10646679f\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9102663204954691936\", \"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\": \"8937233142803816640\"}}, \"got_v8_revision\": \"8f582e9841a9c945626029806b2ffe60b50ce9fd\", \"got_v8_revision_cp\": \"refs/heads/7.0.258@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 9858, \"path_config\": \"generic\", \"bot_id\": \"swarm2173-c4\", \"got_buildtools_revision\": \"2dff9c9c74e9d732e6fe57c84ef7fd044cc45d96\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1535187315751170",
"status": "COMPLETED",
"status_changed_ts": "1535194339146230",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/9858",
"builder:Windows deterministic",
"buildset:commit/git/72acdf58284a507f427c1a54c36a23f10646679f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/72acdf58284a507f427c1a54c36a23f10646679f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9102663204954691936",
"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/9858",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8937233142803816640",
"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/72acdf58284a507f427c1a54c36a23f10646679f",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/72acdf58284a507f427c1a54c36a23f10646679f",
"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/8937233142803816640/+/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:9102663204954691936",
"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:2fdf38dfa532bad52599ef89e33e1e0f1f1d4201",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:3f891cf15e2ef310",
"user_agent:luci-scheduler"
],
"updated_ts": "1535194339148120",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/9858",
"utcnow_ts": "1535194339697320"
}
}
[10:52:19.724] Invocation finished in 1h57m6.978107095s with status SUCCEEDED