[14:23:50.610] New invocation is queued and will start shortly
[14:23:51.849] Starting the invocation (attempt 1)
[14:23:51.881] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:23:51.881] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:23:51.881] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9093220444843180880",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"1d074a97a7b9bc0adfb948cdc4ce998467f7e661\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9093220444843180880",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/1d074a97a7b9bc0adfb948cdc4ce998467f7e661",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1d074a97a7b9bc0adfb948cdc4ce998467f7e661",
"gitiles_ref:refs/heads/master"
]
}
[14:23:53.930] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1544192631950919",
"id": "8927790382718740336",
"lease_expiration_ts": "1544311433596226",
"lease_key": "569730224",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"1d074a97a7b9bc0adfb948cdc4ce998467f7e661\"}}",
"project": "chromium",
"result_details_json": "null",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1544192633817496",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/10997",
"builder:Windows deterministic",
"buildset:commit/git/1d074a97a7b9bc0adfb948cdc4ce998467f7e661",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1d074a97a7b9bc0adfb948cdc4ce998467f7e661",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9093220444843180880",
"scheduler_job_id:chromium/Windows deterministic",
"swarming_dimension:builder:Windows deterministic",
"swarming_dimension:caches:builder_e8fffaf9fac16164b1ec16ebaa1f377b792d39a582f7640830518d8ff7303d0c_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/10997",
"swarming_tag:buildbucket_bucket:chromium/ci",
"swarming_tag:buildbucket_build_id:8927790382718740336",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:2f19301aebbc779351dc95ab1dc03603fa761240",
"swarming_tag:builder:Windows deterministic",
"swarming_tag:buildset:commit/git/1d074a97a7b9bc0adfb948cdc4ce998467f7e661",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1d074a97a7b9bc0adfb948cdc4ce998467f7e661",
"swarming_tag:caches:builder_e8fffaf9fac16164b1ec16ebaa1f377b792d39a582f7640830518d8ff7303d0c_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/8927790382718740336/+/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:9093220444843180880",
"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:aa4d7f227c4aff56f42e9511a50bccdbb982b02b",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:41a1df31e07c3910",
"user_agent:luci-scheduler"
],
"updated_ts": "1544192633817641",
"url": "https://ci.chromium.org/b/8927790382718740336",
"utcnow_ts": "1544192633913495"
}
}
[14:23:53.930] Task URL: https://ci.chromium.org/b/8927790382718740336
[14:23:53.930] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:2:0) after 1m0s
[14:23:56.336] Received PubSub notification, asking Buildbucket for the build status
[14:23:56.407] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:53.949] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:2:0)
[14:24:53.949] Timer tick, asking Buildbucket for the build status
[14:24:54.115] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:54.115] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:4:0) after 1m0s
[14:25:54.141] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:4:0)
[14:25:54.141] Timer tick, asking Buildbucket for the build status
[14:25:54.178] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:54.178] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:5:0) after 1m0s
[14:26:54.196] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:5:0)
[14:26:54.196] Timer tick, asking Buildbucket for the build status
[14:26:54.293] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:54.293] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:6:0) after 1m0s
[14:27:54.311] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:6:0)
[14:27:54.311] Timer tick, asking Buildbucket for the build status
[14:27:54.440] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:54.440] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:7:0) after 1m0s
[14:28:54.456] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:7:0)
[14:28:54.456] Timer tick, asking Buildbucket for the build status
[14:28:54.496] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:54.496] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:8:0) after 1m0s
[14:29:54.513] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:8:0)
[14:29:54.513] Timer tick, asking Buildbucket for the build status
[14:29:54.565] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:54.565] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:9:0) after 1m0s
[14:30:54.583] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:9:0)
[14:30:54.583] Timer tick, asking Buildbucket for the build status
[14:30:54.631] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:54.631] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:10:0) after 1m0s
[14:31:54.657] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:10:0)
[14:31:54.657] Timer tick, asking Buildbucket for the build status
[14:31:54.728] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:54.728] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:11:0) after 1m0s
[14:32:54.805] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:11:0)
[14:32:54.805] Timer tick, asking Buildbucket for the build status
[14:32:55.166] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:55.166] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:12:0) after 1m0s
[14:33:55.212] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:12:0)
[14:33:55.212] Timer tick, asking Buildbucket for the build status
[14:33:55.278] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:55.278] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:13:0) after 1m0s
[14:34:55.296] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:13:0)
[14:34:55.296] Timer tick, asking Buildbucket for the build status
[14:34:55.357] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:55.357] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:14:0) after 1m0s
[14:35:55.373] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:14:0)
[14:35:55.373] Timer tick, asking Buildbucket for the build status
[14:35:55.511] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:55.511] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:15:0) after 1m0s
[14:36:55.527] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:15:0)
[14:36:55.527] Timer tick, asking Buildbucket for the build status
[14:36:55.641] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:55.641] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:16:0) after 1m0s
[14:37:55.661] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:16:0)
[14:37:55.661] Timer tick, asking Buildbucket for the build status
[14:37:56.035] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:56.035] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:17:0) after 1m0s
[14:38:56.154] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:17:0)
[14:38:56.154] Timer tick, asking Buildbucket for the build status
[14:38:56.287] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:56.287] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:18:0) after 1m0s
[14:39:56.305] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:18:0)
[14:39:56.305] Timer tick, asking Buildbucket for the build status
[14:39:56.522] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:56.522] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:19:0) after 1m0s
[14:40:56.540] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:19:0)
[14:40:56.540] Timer tick, asking Buildbucket for the build status
[14:40:56.602] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:56.602] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:20:0) after 1m0s
[14:41:56.617] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:20:0)
[14:41:56.617] Timer tick, asking Buildbucket for the build status
[14:41:56.653] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:56.653] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:21:0) after 1m0s
[14:42:56.678] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:21:0)
[14:42:56.678] Timer tick, asking Buildbucket for the build status
[14:42:56.712] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:56.712] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:22:0) after 1m0s
[14:43:56.729] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:22:0)
[14:43:56.729] Timer tick, asking Buildbucket for the build status
[14:43:57.272] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:57.272] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:23:0) after 1m0s
[14:44:57.286] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:23:0)
[14:44:57.286] Timer tick, asking Buildbucket for the build status
[14:44:57.319] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:57.319] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:24:0) after 1m0s
[14:45:57.365] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:24:0)
[14:45:57.365] Timer tick, asking Buildbucket for the build status
[14:45:57.443] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:57.443] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:25:0) after 1m0s
[14:46:57.460] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:25:0)
[14:46:57.460] Timer tick, asking Buildbucket for the build status
[14:46:57.520] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:57.520] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:26:0) after 1m0s
[14:47:57.536] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:26:0)
[14:47:57.536] Timer tick, asking Buildbucket for the build status
[14:47:57.592] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:57.592] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:27:0) after 1m0s
[14:48:57.610] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:27:0)
[14:48:57.610] Timer tick, asking Buildbucket for the build status
[14:48:57.647] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:57.647] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:28:0) after 1m0s
[14:49:57.721] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:28:0)
[14:49:57.721] Timer tick, asking Buildbucket for the build status
[14:49:57.765] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:57.765] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:29:0) after 1m0s
[14:50:57.783] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:29:0)
[14:50:57.783] Timer tick, asking Buildbucket for the build status
[14:50:57.922] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:57.922] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:30:0) after 1m0s
[14:51:57.944] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:30:0)
[14:51:57.944] Timer tick, asking Buildbucket for the build status
[14:51:58.139] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:58.139] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:31:0) after 1m0s
[14:52:58.159] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:31:0)
[14:52:58.159] Timer tick, asking Buildbucket for the build status
[14:52:58.221] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:58.221] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:32:0) after 1m0s
[14:53:58.247] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:32:0)
[14:53:58.247] Timer tick, asking Buildbucket for the build status
[14:53:58.426] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:58.426] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:33:0) after 1m0s
[14:54:58.485] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:33:0)
[14:54:58.485] Timer tick, asking Buildbucket for the build status
[14:54:58.541] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:58.541] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:34:0) after 1m0s
[14:55:58.557] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:34:0)
[14:55:58.557] Timer tick, asking Buildbucket for the build status
[14:55:58.606] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:58.606] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:35:0) after 1m0s
[14:56:58.645] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:35:0)
[14:56:58.645] Timer tick, asking Buildbucket for the build status
[14:56:58.969] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:58.969] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:36:0) after 1m0s
[14:57:59.015] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:36:0)
[14:57:59.015] Timer tick, asking Buildbucket for the build status
[14:57:59.515] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:59.515] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:37:0) after 1m0s
[14:58:59.537] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:37:0)
[14:58:59.537] Timer tick, asking Buildbucket for the build status
[14:58:59.570] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:59.570] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:38:0) after 1m0s
[14:59:59.588] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:38:0)
[14:59:59.588] Timer tick, asking Buildbucket for the build status
[14:59:59.645] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:59.645] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:39:0) after 1m0s
[15:00:59.665] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:39:0)
[15:00:59.665] Timer tick, asking Buildbucket for the build status
[15:00:59.711] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:59.711] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:40:0) after 1m0s
[15:01:59.736] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:40:0)
[15:01:59.736] Timer tick, asking Buildbucket for the build status
[15:01:59.781] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:59.781] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:41:0) after 1m0s
[15:02:59.846] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:41:0)
[15:02:59.846] Timer tick, asking Buildbucket for the build status
[15:02:59.883] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:59.883] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:42:0) after 1m0s
[15:03:59.903] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:42:0)
[15:03:59.903] Timer tick, asking Buildbucket for the build status
[15:03:59.946] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:59.946] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:43:0) after 1m0s
[15:04:59.965] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:43:0)
[15:04:59.965] Timer tick, asking Buildbucket for the build status
[15:04:59.999] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:59.999] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:44:0) after 1m0s
[15:06:00.017] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:44:0)
[15:06:00.017] Timer tick, asking Buildbucket for the build status
[15:06:00.137] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:00.137] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:45:0) after 1m0s
[15:07:00.240] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:45:0)
[15:07:00.240] Timer tick, asking Buildbucket for the build status
[15:07:00.278] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:00.278] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:46:0) after 1m0s
[15:08:00.308] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:46:0)
[15:08:00.308] Timer tick, asking Buildbucket for the build status
[15:08:00.492] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:00.492] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:47:0) after 1m0s
[15:09:00.515] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:47:0)
[15:09:00.515] Timer tick, asking Buildbucket for the build status
[15:09:00.834] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:00.834] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:48:0) after 1m0s
[15:10:00.854] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:48:0)
[15:10:00.854] Timer tick, asking Buildbucket for the build status
[15:10:00.993] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:00.993] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:49:0) after 1m0s
[15:11:01.010] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:49:0)
[15:11:01.010] Timer tick, asking Buildbucket for the build status
[15:11:01.046] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:01.046] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:50:0) after 1m0s
[15:12:01.074] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:50:0)
[15:12:01.074] Timer tick, asking Buildbucket for the build status
[15:12:01.120] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:01.120] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:51:0) after 1m0s
[15:13:01.139] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:51:0)
[15:13:01.139] Timer tick, asking Buildbucket for the build status
[15:13:01.195] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:01.195] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:52:0) after 1m0s
[15:14:01.221] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:52:0)
[15:14:01.221] Timer tick, asking Buildbucket for the build status
[15:14:01.266] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:01.266] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:53:0) after 1m0s
[15:15:01.333] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:53:0)
[15:15:01.333] Timer tick, asking Buildbucket for the build status
[15:15:01.427] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:01.427] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:54:0) after 1m0s
[15:16:01.452] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:54:0)
[15:16:01.452] Timer tick, asking Buildbucket for the build status
[15:16:01.524] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:01.524] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:55:0) after 1m0s
[15:17:01.544] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:55:0)
[15:17:01.544] Timer tick, asking Buildbucket for the build status
[15:17:01.772] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:01.772] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:56:0) after 1m0s
[15:18:01.793] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:56:0)
[15:18:01.793] Timer tick, asking Buildbucket for the build status
[15:18:01.982] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:01.982] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:57:0) after 1m0s
[15:19:02.008] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:57:0)
[15:19:02.008] Timer tick, asking Buildbucket for the build status
[15:19:02.059] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:02.059] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:58:0) after 1m0s
[15:20:02.142] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:58:0)
[15:20:02.142] Timer tick, asking Buildbucket for the build status
[15:20:02.233] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:02.233] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:59:0) after 1m0s
[15:21:02.252] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:59:0)
[15:21:02.252] Timer tick, asking Buildbucket for the build status
[15:21:02.295] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:02.295] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:60:0) after 1m0s
[15:22:02.318] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:60:0)
[15:22:02.318] Timer tick, asking Buildbucket for the build status
[15:22:02.375] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:02.375] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:61:0) after 1m0s
[15:23:02.395] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:61:0)
[15:23:02.395] Timer tick, asking Buildbucket for the build status
[15:23:02.508] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:02.508] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:62:0) after 1m0s
[15:24:02.528] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:62:0)
[15:24:02.528] Timer tick, asking Buildbucket for the build status
[15:24:02.603] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:02.603] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:63:0) after 1m0s
[15:25:02.671] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:63:0)
[15:25:02.671] Timer tick, asking Buildbucket for the build status
[15:25:02.717] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:02.717] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:64:0) after 1m0s
[15:26:02.737] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:64:0)
[15:26:02.737] Timer tick, asking Buildbucket for the build status
[15:26:02.877] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:02.877] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:65:0) after 1m0s
[15:27:02.894] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:65:0)
[15:27:02.894] Timer tick, asking Buildbucket for the build status
[15:27:03.172] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:03.172] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:66:0) after 1m0s
[15:28:03.209] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:66:0)
[15:28:03.209] Timer tick, asking Buildbucket for the build status
[15:28:03.262] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:03.262] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:67:0) after 1m0s
[15:29:03.282] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:67:0)
[15:29:03.282] Timer tick, asking Buildbucket for the build status
[15:29:03.349] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:03.349] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:68:0) after 1m0s
[15:30:03.368] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:68:0)
[15:30:03.368] Timer tick, asking Buildbucket for the build status
[15:30:03.410] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:03.410] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:69:0) after 1m0s
[15:31:03.432] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:69:0)
[15:31:03.432] Timer tick, asking Buildbucket for the build status
[15:31:03.470] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:03.470] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:70:0) after 1m0s
[15:32:03.486] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:70:0)
[15:32:03.486] Timer tick, asking Buildbucket for the build status
[15:32:03.523] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:03.523] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:71:0) after 1m0s
[15:33:03.543] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:71:0)
[15:33:03.543] Timer tick, asking Buildbucket for the build status
[15:33:03.742] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:03.742] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:72:0) after 1m0s
[15:34:03.766] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:72:0)
[15:34:03.766] Timer tick, asking Buildbucket for the build status
[15:34:03.865] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:03.865] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:73:0) after 1m0s
[15:35:03.900] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:73:0)
[15:35:03.900] Timer tick, asking Buildbucket for the build status
[15:35:03.960] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:03.960] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:74:0) after 1m0s
[15:36:03.981] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:74:0)
[15:36:03.981] Timer tick, asking Buildbucket for the build status
[15:36:04.098] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:04.098] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:75:0) after 1m0s
[15:37:04.154] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:75:0)
[15:37:04.154] Timer tick, asking Buildbucket for the build status
[15:37:04.192] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:04.192] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:76:0) after 1m0s
[15:38:04.212] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:76:0)
[15:38:04.212] Timer tick, asking Buildbucket for the build status
[15:38:04.344] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:04.344] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:77:0) after 1m0s
[15:39:04.361] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:77:0)
[15:39:04.361] Timer tick, asking Buildbucket for the build status
[15:39:04.405] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:04.405] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:78:0) after 1m0s
[15:40:04.425] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:78:0)
[15:40:04.425] Timer tick, asking Buildbucket for the build status
[15:40:04.590] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:04.590] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:79:0) after 1m0s
[15:41:04.609] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:79:0)
[15:41:04.609] Timer tick, asking Buildbucket for the build status
[15:41:04.644] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:04.644] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:80:0) after 1m0s
[15:42:04.665] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:80:0)
[15:42:04.665] Timer tick, asking Buildbucket for the build status
[15:42:04.858] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:04.858] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:81:0) after 1m0s
[15:43:04.878] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:81:0)
[15:43:04.878] Timer tick, asking Buildbucket for the build status
[15:43:04.950] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:04.950] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:82:0) after 1m0s
[15:44:04.959] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:82:0)
[15:44:04.959] Timer tick, asking Buildbucket for the build status
[15:44:05.194] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:05.194] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:83:0) after 1m0s
[15:45:05.225] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:83:0)
[15:45:05.225] Timer tick, asking Buildbucket for the build status
[15:45:05.352] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:05.352] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:84:0) after 1m0s
[15:46:05.373] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:84:0)
[15:46:05.373] Timer tick, asking Buildbucket for the build status
[15:46:05.509] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:05.509] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:85:0) after 1m0s
[15:47:05.546] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:85:0)
[15:47:05.546] Timer tick, asking Buildbucket for the build status
[15:47:05.608] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:05.608] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:86:0) after 1m0s
[15:48:05.626] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:86:0)
[15:48:05.626] Timer tick, asking Buildbucket for the build status
[15:48:05.666] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:05.666] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:87:0) after 1m0s
[15:49:05.688] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:87:0)
[15:49:05.688] Timer tick, asking Buildbucket for the build status
[15:49:05.749] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:05.749] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:88:0) after 1m0s
[15:50:05.767] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:88:0)
[15:50:05.767] Timer tick, asking Buildbucket for the build status
[15:50:05.824] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:05.824] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:89:0) after 1m0s
[15:51:05.865] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:89:0)
[15:51:05.865] Timer tick, asking Buildbucket for the build status
[15:51:05.924] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:05.924] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:90:0) after 1m0s
[15:52:06.002] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:90:0)
[15:52:06.002] Timer tick, asking Buildbucket for the build status
[15:52:06.054] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:06.054] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:91:0) after 1m0s
[15:53:06.079] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:91:0)
[15:53:06.079] Timer tick, asking Buildbucket for the build status
[15:53:06.133] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:06.133] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:92:0) after 1m0s
[15:54:06.163] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:92:0)
[15:54:06.163] Timer tick, asking Buildbucket for the build status
[15:54:06.215] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:06.215] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:93:0) after 1m0s
[15:55:06.241] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:93:0)
[15:55:06.241] Timer tick, asking Buildbucket for the build status
[15:55:06.462] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:06.462] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:94:0) after 1m0s
[15:56:06.484] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:94:0)
[15:56:06.484] Timer tick, asking Buildbucket for the build status
[15:56:06.517] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:06.517] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:95:0) after 1m0s
[15:57:06.556] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:95:0)
[15:57:06.556] Timer tick, asking Buildbucket for the build status
[15:57:06.690] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:06.690] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:96:0) after 1m0s
[15:58:06.714] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:96:0)
[15:58:06.714] Timer tick, asking Buildbucket for the build status
[15:58:06.743] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:06.743] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:97:0) after 1m0s
[15:59:06.775] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:97:0)
[15:59:06.775] Timer tick, asking Buildbucket for the build status
[15:59:06.839] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:06.839] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:98:0) after 1m0s
[16:00:06.864] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:98:0)
[16:00:06.864] Timer tick, asking Buildbucket for the build status
[16:00:06.908] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:06.908] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:99:0) after 1m0s
[16:01:06.943] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:99:0)
[16:01:06.943] Timer tick, asking Buildbucket for the build status
[16:01:06.981] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:06.981] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:100:0) after 1m0s
[16:02:07.011] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:100:0)
[16:02:07.011] Timer tick, asking Buildbucket for the build status
[16:02:07.055] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:07.055] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:101:0) after 1m0s
[16:03:07.077] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:101:0)
[16:03:07.077] Timer tick, asking Buildbucket for the build status
[16:03:07.136] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:07.136] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:102:0) after 1m0s
[16:04:07.156] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:102:0)
[16:04:07.156] Timer tick, asking Buildbucket for the build status
[16:04:07.222] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:07.222] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:103:0) after 1m0s
[16:05:07.244] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:103:0)
[16:05:07.244] Timer tick, asking Buildbucket for the build status
[16:05:07.302] Build 8927790382718740336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:07.302] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9093220444843180880:104:0) after 1m0s
[16:05:10.111] Received PubSub notification, asking Buildbucket for the build status
[16:05:10.155] Build 8927790382718740336: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[16:05:10.155] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1544198708487662",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1544192631950919",
"id": "8927790382718740336",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"1d074a97a7b9bc0adfb948cdc4ce998467f7e661\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"ui\": {\"info\": \"\"}, \"swarming\": {\"bot_dimensions\": {\"gce\": [\"1\"], \"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\"], \"id\": [\"swarm2173-c4\"], \"python\": [\"2.7.6\"], \"server_version\": [\"3921-6311e5b\"], \"image\": [\"chrome-win10-1703-2018-04-20-61acaf8983f\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"integrity\": [\"high\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\"], \"pool\": [\"luci.chromium.ci\"], \"caches\": [\"builder_e8fffaf9fac16164b1ec16ebaa1f377b792d39a582f7640830518d8ff7303d0c_v2\", \"git\", \"goma_v2\", \"vpython\"]}}, \"properties\": {\"got_nacl_revision\": \"8e13fe5e85640b063aefcb88a3ccb52abf6e28e8\", \"got_swarming_client_revision\": \"0e3e1c4dc4e79f25a5b58fcbc135dc93183c0c54\", \"got_revision\": \"1d074a97a7b9bc0adfb948cdc4ce998467f7e661\", \"recipe\": \"swarming/deterministic_build\", \"got_dawn_revision\": \"6f0e1f9d8235842e3c1130e4133e92e850166584\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#25919}\", \"$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@{#614690}\", \"branch\": \"refs/heads/master\", \"revision\": \"1d074a97a7b9bc0adfb948cdc4ce998467f7e661\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"18f0c3c038fc45be6951301a3c2726e6810565d3\", \"mastername\": \"chromium.win\", \"got_angle_revision\": \"e14feaf5b9aaa5bbe880ab9f67e0cf3fd5201ab8\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1544192631950919, \"tags\": [\"builder:Windows deterministic\", \"buildset:commit/git/1d074a97a7b9bc0adfb948cdc4ce998467f7e661\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1d074a97a7b9bc0adfb948cdc4ce998467f7e661\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9093220444843180880\", \"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\": \"8927790382718740336\"}}, \"got_v8_revision\": \"9dff9d14e339976e7f6c658fc9739173b6a19e1d\", \"got_v8_revision_cp\": \"refs/heads/7.3.60@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 10997, \"path_config\": \"generic\", \"bot_id\": \"swarm2173-c4\", \"got_buildtools_revision\": \"7d88270de197ebe8b439ab5eb57a4a2a0bb810e0\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1544192634879063",
"status": "COMPLETED",
"status_changed_ts": "1544198709695691",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/10997",
"builder:Windows deterministic",
"buildset:commit/git/1d074a97a7b9bc0adfb948cdc4ce998467f7e661",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1d074a97a7b9bc0adfb948cdc4ce998467f7e661",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9093220444843180880",
"scheduler_job_id:chromium/Windows deterministic",
"swarming_dimension:builder:Windows deterministic",
"swarming_dimension:caches:builder_e8fffaf9fac16164b1ec16ebaa1f377b792d39a582f7640830518d8ff7303d0c_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/10997",
"swarming_tag:buildbucket_bucket:chromium/ci",
"swarming_tag:buildbucket_build_id:8927790382718740336",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:2f19301aebbc779351dc95ab1dc03603fa761240",
"swarming_tag:builder:Windows deterministic",
"swarming_tag:buildset:commit/git/1d074a97a7b9bc0adfb948cdc4ce998467f7e661",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1d074a97a7b9bc0adfb948cdc4ce998467f7e661",
"swarming_tag:caches:builder_e8fffaf9fac16164b1ec16ebaa1f377b792d39a582f7640830518d8ff7303d0c_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/8927790382718740336/+/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:9093220444843180880",
"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:aa4d7f227c4aff56f42e9511a50bccdbb982b02b",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:41a1df31e07c3910",
"user_agent:luci-scheduler"
],
"updated_ts": "1544198709696622",
"url": "https://ci.chromium.org/b/8927790382718740336",
"utcnow_ts": "1544198710143118"
}
}
[16:05:10.155] Invocation finished in 1h41m19.6153602s with status SUCCEEDED