[05:30:54.232] New invocation is queued and will start shortly
[05:30:55.385] Starting the invocation (attempt 1)
[05:30:55.429] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:30:55.429] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:30:55.430] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9088271141298585264",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"4c57e74700bd6d40304168de251c668b65ad9eff\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9088271141298585264",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/4c57e74700bd6d40304168de251c668b65ad9eff",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4c57e74700bd6d40304168de251c668b65ad9eff",
"gitiles_ref:refs/heads/master"
]
}
[05:30:59.225] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1548912655560003",
"id": "8922841079241811440",
"lease_expiration_ts": "1549031456940720",
"lease_key": "1849241633",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"4c57e74700bd6d40304168de251c668b65ad9eff\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1548912658299336",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/11782",
"builder:Windows deterministic",
"buildset:commit/git/4c57e74700bd6d40304168de251c668b65ad9eff",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4c57e74700bd6d40304168de251c668b65ad9eff",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9088271141298585264",
"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/11782",
"swarming_tag:buildbucket_bucket:chromium/ci",
"swarming_tag:buildbucket_build_id:8922841079241811440",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:01134cf47e8a2d5d7cea5ec1232ca64ed2f16a5c",
"swarming_tag:builder:Windows deterministic",
"swarming_tag:buildset:commit/git/4c57e74700bd6d40304168de251c668b65ad9eff",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4c57e74700bd6d40304168de251c668b65ad9eff",
"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/8922841079241811440/+/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:9088271141298585264",
"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:724637359ef54fb792b6dc06eeabdfab76cd979c",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:42bb35093c961310",
"user_agent:luci-scheduler"
],
"updated_ts": "1548912658299353",
"url": "https://ci.chromium.org/b/8922841079241811440",
"utcnow_ts": "1548912659208718"
}
}
[05:30:59.225] Task URL: https://ci.chromium.org/b/8922841079241811440
[05:30:59.225] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:2:0) after 1m0s
[05:31:59.249] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:2:0)
[05:31:59.249] Timer tick, asking Buildbucket for the build status
[05:31:59.323] Build 8922841079241811440: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:31:59.323] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:3:0) after 1m0s
[05:32:59.362] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:3:0)
[05:32:59.362] Timer tick, asking Buildbucket for the build status
[05:32:59.609] Build 8922841079241811440: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:32:59.609] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:4:0) after 1m0s
[05:33:03.473] Received PubSub notification, asking Buildbucket for the build status
[05:33:03.616] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:59.683] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:4:0)
[05:33:59.683] Timer tick, asking Buildbucket for the build status
[05:33:59.754] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:59.754] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:6:0) after 1m0s
[05:34:59.777] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:6:0)
[05:34:59.777] Timer tick, asking Buildbucket for the build status
[05:34:59.827] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:59.827] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:7:0) after 1m0s
[05:35:59.883] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:7:0)
[05:35:59.883] Timer tick, asking Buildbucket for the build status
[05:35:59.952] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:59.955] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:8:0) after 1m0s
[05:37:00.008] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:8:0)
[05:37:00.008] Timer tick, asking Buildbucket for the build status
[05:37:00.241] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:00.242] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:9:0) after 1m0s
[05:38:00.265] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:9:0)
[05:38:00.265] Timer tick, asking Buildbucket for the build status
[05:38:00.347] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:00.347] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:10:0) after 1m0s
[05:39:00.398] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:10:0)
[05:39:00.398] Timer tick, asking Buildbucket for the build status
[05:39:00.447] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:00.447] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:11:0) after 1m0s
[05:40:00.470] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:11:0)
[05:40:00.470] Timer tick, asking Buildbucket for the build status
[05:40:00.656] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:00.656] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:12:0) after 1m0s
[05:41:00.677] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:12:0)
[05:41:00.677] Timer tick, asking Buildbucket for the build status
[05:41:00.999] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:00.999] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:13:0) after 1m0s
[05:42:01.027] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:13:0)
[05:42:01.027] Timer tick, asking Buildbucket for the build status
[05:42:01.334] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:01.334] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:14:0) after 1m0s
[05:43:01.356] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:14:0)
[05:43:01.356] Timer tick, asking Buildbucket for the build status
[05:43:01.781] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:43:01.781] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:15:0) after 1m0s
[05:44:01.800] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:15:0)
[05:44:01.800] Timer tick, asking Buildbucket for the build status
[05:44:02.114] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:44:02.114] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:16:0) after 1m0s
[05:45:02.182] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:16:0)
[05:45:02.183] Timer tick, asking Buildbucket for the build status
[05:45:02.300] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:45:02.300] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:17:0) after 1m0s
[05:46:02.335] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:17:0)
[05:46:02.335] Timer tick, asking Buildbucket for the build status
[05:46:02.504] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:46:02.504] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:18:0) after 1m0s
[05:47:02.529] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:18:0)
[05:47:02.529] Timer tick, asking Buildbucket for the build status
[05:47:02.792] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:47:02.792] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:19:0) after 1m0s
[05:48:02.816] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:19:0)
[05:48:02.817] Timer tick, asking Buildbucket for the build status
[05:48:02.908] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:48:02.908] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:20:0) after 1m0s
[05:49:02.930] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:20:0)
[05:49:02.930] Timer tick, asking Buildbucket for the build status
[05:49:03.010] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:49:03.010] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:21:0) after 1m0s
[05:50:03.046] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:21:0)
[05:50:03.046] Timer tick, asking Buildbucket for the build status
[05:50:03.102] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:50:03.102] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:22:0) after 1m0s
[05:51:03.144] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:22:0)
[05:51:03.144] Timer tick, asking Buildbucket for the build status
[05:51:03.291] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:51:03.291] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:23:0) after 1m0s
[05:52:03.357] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:23:0)
[05:52:03.357] Timer tick, asking Buildbucket for the build status
[05:52:03.491] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:52:03.491] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:24:0) after 1m0s
[05:53:03.523] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:24:0)
[05:53:03.523] Timer tick, asking Buildbucket for the build status
[05:53:03.776] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:53:03.776] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:25:0) after 1m0s
[05:54:03.801] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:25:0)
[05:54:03.801] Timer tick, asking Buildbucket for the build status
[05:54:04.290] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:54:04.290] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:26:0) after 1m0s
[05:55:04.312] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:26:0)
[05:55:04.312] Timer tick, asking Buildbucket for the build status
[05:55:04.417] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:55:04.417] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:27:0) after 1m0s
[05:56:04.440] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:27:0)
[05:56:04.440] Timer tick, asking Buildbucket for the build status
[05:56:04.491] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:56:04.492] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:28:0) after 1m0s
[05:57:04.531] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:28:0)
[05:57:04.531] Timer tick, asking Buildbucket for the build status
[05:57:04.637] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:57:04.637] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:29:0) after 1m0s
[05:58:04.677] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:29:0)
[05:58:04.677] Timer tick, asking Buildbucket for the build status
[05:58:04.949] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:58:04.949] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:30:0) after 1m0s
[05:59:04.970] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:30:0)
[05:59:04.970] Timer tick, asking Buildbucket for the build status
[05:59:05.019] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:59:05.019] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:31:0) after 1m0s
[06:00:05.039] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:31:0)
[06:00:05.039] Timer tick, asking Buildbucket for the build status
[06:00:05.467] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:00:05.467] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:32:0) after 1m0s
[06:01:05.503] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:32:0)
[06:01:05.503] Timer tick, asking Buildbucket for the build status
[06:01:05.552] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:05.552] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:33:0) after 1m0s
[06:02:05.602] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:33:0)
[06:02:05.602] Timer tick, asking Buildbucket for the build status
[06:02:05.751] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:02:05.751] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:34:0) after 1m0s
[06:03:05.774] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:34:0)
[06:03:05.774] Timer tick, asking Buildbucket for the build status
[06:03:05.862] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:03:05.862] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:35:0) after 1m0s
[06:04:05.886] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:35:0)
[06:04:05.886] Timer tick, asking Buildbucket for the build status
[06:04:05.968] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:04:05.968] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:36:0) after 1m0s
[06:05:06.057] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:36:0)
[06:05:06.057] Timer tick, asking Buildbucket for the build status
[06:05:06.128] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:05:06.128] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:37:0) after 1m0s
[06:06:06.148] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:37:0)
[06:06:06.148] Timer tick, asking Buildbucket for the build status
[06:06:06.265] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:06:06.265] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:38:0) after 1m0s
[06:07:06.299] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:38:0)
[06:07:06.299] Timer tick, asking Buildbucket for the build status
[06:07:06.365] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:07:06.365] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:39:0) after 1m0s
[06:08:06.398] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:39:0)
[06:08:06.398] Timer tick, asking Buildbucket for the build status
[06:08:06.509] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:08:06.509] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:40:0) after 1m0s
[06:09:06.579] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:40:0)
[06:09:06.579] Timer tick, asking Buildbucket for the build status
[06:09:06.674] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:09:06.674] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:41:0) after 1m0s
[06:10:06.696] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:41:0)
[06:10:06.696] Timer tick, asking Buildbucket for the build status
[06:10:06.884] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:10:06.884] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:42:0) after 1m0s
[06:11:06.904] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:42:0)
[06:11:06.904] Timer tick, asking Buildbucket for the build status
[06:11:06.982] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:06.982] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:43:0) after 1m0s
[06:12:07.005] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:43:0)
[06:12:07.005] Timer tick, asking Buildbucket for the build status
[06:12:07.292] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:07.292] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:44:0) after 1m0s
[06:13:07.323] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:44:0)
[06:13:07.323] Timer tick, asking Buildbucket for the build status
[06:13:07.566] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:07.566] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:45:0) after 1m0s
[06:14:07.593] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:45:0)
[06:14:07.593] Timer tick, asking Buildbucket for the build status
[06:14:07.703] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:07.703] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:46:0) after 1m0s
[06:15:07.724] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:46:0)
[06:15:07.724] Timer tick, asking Buildbucket for the build status
[06:15:07.776] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:07.776] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:47:0) after 1m0s
[06:16:07.837] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:47:0)
[06:16:07.837] Timer tick, asking Buildbucket for the build status
[06:16:07.894] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:16:07.894] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:48:0) after 1m0s
[06:17:07.929] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:48:0)
[06:17:07.929] Timer tick, asking Buildbucket for the build status
[06:17:08.021] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:08.021] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:49:0) after 1m0s
[06:18:08.050] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:49:0)
[06:18:08.050] Timer tick, asking Buildbucket for the build status
[06:18:08.104] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:08.104] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:50:0) after 1m0s
[06:19:08.194] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:50:0)
[06:19:08.194] Timer tick, asking Buildbucket for the build status
[06:19:08.313] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:08.313] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:51:0) after 1m0s
[06:20:08.336] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:51:0)
[06:20:08.336] Timer tick, asking Buildbucket for the build status
[06:20:08.398] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:08.398] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:52:0) after 1m0s
[06:21:08.440] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:52:0)
[06:21:08.440] Timer tick, asking Buildbucket for the build status
[06:21:08.522] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:08.522] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:53:0) after 1m0s
[06:22:08.622] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:53:0)
[06:22:08.622] Timer tick, asking Buildbucket for the build status
[06:22:08.671] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:08.671] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:54:0) after 1m0s
[06:23:08.699] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:54:0)
[06:23:08.699] Timer tick, asking Buildbucket for the build status
[06:23:08.782] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:08.782] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:55:0) after 1m0s
[06:24:08.841] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:55:0)
[06:24:08.842] Timer tick, asking Buildbucket for the build status
[06:24:08.987] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:24:08.987] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:56:0) after 1m0s
[06:25:09.110] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:56:0)
[06:25:09.110] Timer tick, asking Buildbucket for the build status
[06:25:09.203] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:09.203] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:57:0) after 1m0s
[06:26:09.227] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:57:0)
[06:26:09.227] Timer tick, asking Buildbucket for the build status
[06:26:09.300] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:09.300] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:58:0) after 1m0s
[06:27:09.323] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:58:0)
[06:27:09.323] Timer tick, asking Buildbucket for the build status
[06:27:09.382] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:09.382] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:59:0) after 1m0s
[06:28:09.421] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:59:0)
[06:28:09.421] Timer tick, asking Buildbucket for the build status
[06:28:09.551] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:09.551] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:60:0) after 1m0s
[06:29:09.584] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:60:0)
[06:29:09.584] Timer tick, asking Buildbucket for the build status
[06:29:09.649] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:09.649] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:61:0) after 1m0s
[06:30:09.671] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:61:0)
[06:30:09.671] Timer tick, asking Buildbucket for the build status
[06:30:09.735] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:09.735] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:62:0) after 1m0s
[06:31:09.768] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:62:0)
[06:31:09.768] Timer tick, asking Buildbucket for the build status
[06:31:09.905] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:09.906] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:63:0) after 1m0s
[06:32:09.928] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:63:0)
[06:32:09.928] Timer tick, asking Buildbucket for the build status
[06:32:10.079] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:10.079] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:64:0) after 1m0s
[06:33:10.141] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:64:0)
[06:33:10.141] Timer tick, asking Buildbucket for the build status
[06:33:10.211] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:10.211] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:65:0) after 1m0s
[06:34:10.420] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:65:0)
[06:34:10.420] Timer tick, asking Buildbucket for the build status
[06:34:10.532] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:10.532] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:66:0) after 1m0s
[06:35:10.559] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:66:0)
[06:35:10.559] Timer tick, asking Buildbucket for the build status
[06:35:10.763] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:10.763] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:67:0) after 1m0s
[06:36:10.782] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:67:0)
[06:36:10.782] Timer tick, asking Buildbucket for the build status
[06:36:10.834] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:10.834] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:68:0) after 1m0s
[06:37:10.856] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:68:0)
[06:37:10.856] Timer tick, asking Buildbucket for the build status
[06:37:11.366] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:11.366] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:69:0) after 1m0s
[06:38:11.399] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:69:0)
[06:38:11.399] Timer tick, asking Buildbucket for the build status
[06:38:11.463] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:11.463] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:70:0) after 1m0s
[06:39:11.495] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:70:0)
[06:39:11.495] Timer tick, asking Buildbucket for the build status
[06:39:11.716] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:11.716] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:71:0) after 1m0s
[06:40:11.742] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:71:0)
[06:40:11.742] Timer tick, asking Buildbucket for the build status
[06:40:11.997] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:11.997] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:72:0) after 1m0s
[06:41:12.053] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:72:0)
[06:41:12.053] Timer tick, asking Buildbucket for the build status
[06:41:12.177] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:12.177] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:73:0) after 1m0s
[06:42:12.230] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:73:0)
[06:42:12.230] Timer tick, asking Buildbucket for the build status
[06:42:12.655] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:12.655] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:74:0) after 1m0s
[06:43:12.676] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:74:0)
[06:43:12.676] Timer tick, asking Buildbucket for the build status
[06:43:12.758] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:12.758] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:75:0) after 1m0s
[06:44:12.777] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:75:0)
[06:44:12.777] Timer tick, asking Buildbucket for the build status
[06:44:12.874] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:12.874] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:76:0) after 1m0s
[06:45:12.911] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:76:0)
[06:45:12.911] Timer tick, asking Buildbucket for the build status
[06:45:13.049] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:13.049] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:77:0) after 1m0s
[06:46:13.081] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:77:0)
[06:46:13.081] Timer tick, asking Buildbucket for the build status
[06:46:13.169] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:13.169] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:78:0) after 1m0s
[06:47:13.200] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:78:0)
[06:47:13.200] Timer tick, asking Buildbucket for the build status
[06:47:13.401] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:13.401] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:79:0) after 1m0s
[06:48:13.438] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:79:0)
[06:48:13.438] Timer tick, asking Buildbucket for the build status
[06:48:13.584] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:13.584] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:80:0) after 1m0s
[06:49:13.608] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:80:0)
[06:49:13.608] Timer tick, asking Buildbucket for the build status
[06:49:13.689] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:13.689] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:81:0) after 1m0s
[06:50:13.779] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:81:0)
[06:50:13.779] Timer tick, asking Buildbucket for the build status
[06:50:13.854] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:13.854] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:82:0) after 1m0s
[06:51:13.880] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:82:0)
[06:51:13.880] Timer tick, asking Buildbucket for the build status
[06:51:13.958] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:13.958] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:83:0) after 1m0s
[06:52:13.992] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:83:0)
[06:52:13.992] Timer tick, asking Buildbucket for the build status
[06:52:14.179] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:14.179] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:84:0) after 1m0s
[06:53:14.232] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:84:0)
[06:53:14.232] Timer tick, asking Buildbucket for the build status
[06:53:14.363] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:14.363] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:85:0) after 1m0s
[06:54:14.391] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:85:0)
[06:54:14.391] Timer tick, asking Buildbucket for the build status
[06:54:14.473] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:14.473] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:86:0) after 1m0s
[06:55:14.505] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:86:0)
[06:55:14.505] Timer tick, asking Buildbucket for the build status
[06:55:14.560] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:14.560] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:87:0) after 1m0s
[06:56:14.585] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:87:0)
[06:56:14.585] Timer tick, asking Buildbucket for the build status
[06:56:14.666] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:14.666] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:88:0) after 1m0s
[06:57:14.695] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:88:0)
[06:57:14.695] Timer tick, asking Buildbucket for the build status
[06:57:14.889] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:14.889] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:89:0) after 1m0s
[06:58:14.920] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:89:0)
[06:58:14.920] Timer tick, asking Buildbucket for the build status
[06:58:14.970] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:14.970] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:90:0) after 1m0s
[06:59:15.016] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:90:0)
[06:59:15.016] Timer tick, asking Buildbucket for the build status
[06:59:15.087] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:15.087] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:91:0) after 1m0s
[07:00:15.109] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:91:0)
[07:00:15.109] Timer tick, asking Buildbucket for the build status
[07:00:15.190] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:15.190] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:92:0) after 1m0s
[07:01:15.213] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:92:0)
[07:01:15.213] Timer tick, asking Buildbucket for the build status
[07:01:15.259] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:15.259] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:93:0) after 1m0s
[07:02:15.286] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:93:0)
[07:02:15.286] Timer tick, asking Buildbucket for the build status
[07:02:15.332] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:15.332] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:94:0) after 1m0s
[07:03:15.371] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:94:0)
[07:03:15.371] Timer tick, asking Buildbucket for the build status
[07:03:15.593] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:15.593] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:95:0) after 1m0s
[07:04:15.616] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:95:0)
[07:04:15.616] Timer tick, asking Buildbucket for the build status
[07:04:15.696] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:15.696] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:96:0) after 1m0s
[07:05:15.730] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:96:0)
[07:05:15.730] Timer tick, asking Buildbucket for the build status
[07:05:15.899] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:15.899] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:97:0) after 1m0s
[07:06:15.924] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:97:0)
[07:06:15.924] Timer tick, asking Buildbucket for the build status
[07:06:15.985] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:15.985] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:98:0) after 1m0s
[07:07:16.056] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:98:0)
[07:07:16.056] Timer tick, asking Buildbucket for the build status
[07:07:16.171] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:16.171] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:99:0) after 1m0s
[07:08:16.223] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:99:0)
[07:08:16.223] Timer tick, asking Buildbucket for the build status
[07:08:16.272] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:16.272] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:100:0) after 1m0s
[07:09:16.297] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:100:0)
[07:09:16.297] Timer tick, asking Buildbucket for the build status
[07:09:16.653] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:16.653] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:101:0) after 1m0s
[07:10:16.758] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:101:0)
[07:10:16.758] Timer tick, asking Buildbucket for the build status
[07:10:17.138] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:17.138] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:102:0) after 1m0s
[07:11:17.160] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:102:0)
[07:11:17.160] Timer tick, asking Buildbucket for the build status
[07:11:17.212] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:17.212] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:103:0) after 1m0s
[07:12:17.235] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:103:0)
[07:12:17.235] Timer tick, asking Buildbucket for the build status
[07:12:17.374] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:17.374] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:104:0) after 1m0s
[07:13:17.401] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:104:0)
[07:13:17.401] Timer tick, asking Buildbucket for the build status
[07:13:17.458] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:17.458] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:105:0) after 1m0s
[07:14:17.483] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:105:0)
[07:14:17.483] Timer tick, asking Buildbucket for the build status
[07:14:17.579] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:17.579] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:106:0) after 1m0s
[07:15:17.610] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:106:0)
[07:15:17.610] Timer tick, asking Buildbucket for the build status
[07:15:17.792] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:17.792] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:107:0) after 1m0s
[07:16:17.822] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:107:0)
[07:16:17.822] Timer tick, asking Buildbucket for the build status
[07:16:17.908] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:17.908] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:108:0) after 1m0s
[07:17:17.952] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:108:0)
[07:17:17.952] Timer tick, asking Buildbucket for the build status
[07:17:18.002] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:18.002] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:109:0) after 1m0s
[07:18:18.028] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:109:0)
[07:18:18.028] Timer tick, asking Buildbucket for the build status
[07:18:18.388] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:18.388] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:110:0) after 1m0s
[07:19:18.418] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:110:0)
[07:19:18.418] Timer tick, asking Buildbucket for the build status
[07:19:18.695] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:18.695] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:111:0) after 1m0s
[07:20:18.722] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:111:0)
[07:20:18.722] Timer tick, asking Buildbucket for the build status
[07:20:18.828] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:18.828] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:112:0) after 1m0s
[07:21:18.864] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:112:0)
[07:21:18.864] Timer tick, asking Buildbucket for the build status
[07:21:18.986] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:18.986] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:113:0) after 1m0s
[07:22:19.044] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:113:0)
[07:22:19.044] Timer tick, asking Buildbucket for the build status
[07:22:19.473] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:19.473] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:114:0) after 1m0s
[07:23:19.530] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:114:0)
[07:23:19.530] Timer tick, asking Buildbucket for the build status
[07:23:19.668] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:19.668] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:115:0) after 1m0s
[07:24:19.695] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:115:0)
[07:24:19.695] Timer tick, asking Buildbucket for the build status
[07:24:20.133] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:20.133] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:116:0) after 1m0s
[07:25:20.159] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:116:0)
[07:25:20.159] Timer tick, asking Buildbucket for the build status
[07:25:20.241] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:20.241] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:117:0) after 1m0s
[07:26:20.262] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:117:0)
[07:26:20.263] Timer tick, asking Buildbucket for the build status
[07:26:20.612] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:20.612] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:118:0) after 1m0s
[07:27:20.631] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:118:0)
[07:27:20.631] Timer tick, asking Buildbucket for the build status
[07:27:20.724] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:20.724] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:119:0) after 1m0s
[07:28:20.758] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:119:0)
[07:28:20.758] Timer tick, asking Buildbucket for the build status
[07:28:20.849] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:20.849] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:120:0) after 1m0s
[07:29:20.871] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:120:0)
[07:29:20.871] Timer tick, asking Buildbucket for the build status
[07:29:20.921] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:20.921] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:121:0) after 1m0s
[07:30:20.960] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:121:0)
[07:30:20.960] Timer tick, asking Buildbucket for the build status
[07:30:21.045] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:21.045] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:122:0) after 1m0s
[07:31:21.075] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:122:0)
[07:31:21.075] Timer tick, asking Buildbucket for the build status
[07:31:21.273] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:21.273] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:123:0) after 1m0s
[07:32:21.298] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:123:0)
[07:32:21.298] Timer tick, asking Buildbucket for the build status
[07:32:21.371] Build 8922841079241811440: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:21.371] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088271141298585264:124:0) after 1m0s
[07:32:25.440] Received PubSub notification, asking Buildbucket for the build status
[07:32:25.559] Build 8922841079241811440: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[07:32:25.559] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1548919944124706",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1548912655560003",
"id": "8922841079241811440",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"4c57e74700bd6d40304168de251c668b65ad9eff\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"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\": [\"4042-3265c92\"], \"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\"]}}, \"ui\": {\"info\": \"\"}, \"properties\": {\"got_nacl_revision\": \"ff15c51d20785fa51c0ef03ccb480b87299acbeb\", \"got_swarming_client_revision\": \"0e3e1c4dc4e79f25a5b58fcbc135dc93183c0c54\", \"got_revision\": \"4c57e74700bd6d40304168de251c668b65ad9eff\", \"recipe\": \"swarming/deterministic_build\", \"got_dawn_revision\": \"baa3741c9fbe88e83e6d8cbbea1ca44d53e1da24\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#26468}\", \"$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@{#627796}\", \"branch\": \"refs/heads/master\", \"revision\": \"4c57e74700bd6d40304168de251c668b65ad9eff\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"9f3a44f5152816e3a0cb6902e0dadf365ff4e7d0\", \"mastername\": \"chromium.win\", \"got_angle_revision\": \"783809808ec09d2040047c252925b19f507b06c8\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1548912655560003.0, \"tags\": [\"build_address:luci.chromium.ci/Windows deterministic/11782\", \"builder:Windows deterministic\", \"buildset:commit/git/4c57e74700bd6d40304168de251c668b65ad9eff\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4c57e74700bd6d40304168de251c668b65ad9eff\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9088271141298585264\", \"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\": \"8922841079241811440\"}}, \"got_v8_revision\": \"e0899ad9ee97543e6f45961fe13435882df25b8a\", \"got_v8_revision_cp\": \"refs/heads/7.4.39@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 11782, \"$recipe_engine/buildbucket\": {\"build\": {\"tags\": [{\"key\": \"builder\", \"value\": \"Windows deterministic\"}, {\"key\": \"buildset\", \"value\": \"commit/git/4c57e74700bd6d40304168de251c668b65ad9eff\"}, {\"key\": \"buildset\", \"value\": \"commit/gitiles/chromium.googlesource.com/chromium/src/+/4c57e74700bd6d40304168de251c668b65ad9eff\"}, {\"key\": \"scheduler_invocation_id\", \"value\": \"9088271141298585264\"}, {\"value\": \"chromium/Windows deterministic\", \"key\": \"scheduler_job_id\"}, {\"key\": \"user_agent\", \"value\": \"luci-scheduler\"}], \"builder\": {\"project\": \"chromium\", \"builder\": \"Windows deterministic\", \"bucket\": \"ci\"}, \"number\": 11782.0, \"infra\": {\"buildbucket\": {\"serviceConfigRevision\": \"01134cf47e8a2d5d7cea5ec1232ca64ed2f16a5c\"}, \"recipe\": {\"name\": \"swarming/deterministic_build\", \"cipdPackage\": \"infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build\"}, \"swarming\": {\"hostname\": \"chromium-swarm.appspot.com\"}}, \"createdBy\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"id\": \"8922841079241811440\", \"input\": {\"gitilesCommit\": {\"project\": \"chromium/src\", \"host\": \"chromium.googlesource.com\", \"ref\": \"refs/heads/master\", \"id\": \"4c57e74700bd6d40304168de251c668b65ad9eff\"}}, \"createTime\": \"2019-01-31T05:30:55.560003Z\"}}, \"path_config\": \"generic\", \"bot_id\": \"swarm2173-c4\", \"got_buildtools_revision\": \"6fbda1b24c1893a893b17aa219b765b9e7c801d8\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1548912782498108",
"status": "COMPLETED",
"status_changed_ts": "1548919944862989",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/11782",
"builder:Windows deterministic",
"buildset:commit/git/4c57e74700bd6d40304168de251c668b65ad9eff",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4c57e74700bd6d40304168de251c668b65ad9eff",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9088271141298585264",
"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/11782",
"swarming_tag:buildbucket_bucket:chromium/ci",
"swarming_tag:buildbucket_build_id:8922841079241811440",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:01134cf47e8a2d5d7cea5ec1232ca64ed2f16a5c",
"swarming_tag:builder:Windows deterministic",
"swarming_tag:buildset:commit/git/4c57e74700bd6d40304168de251c668b65ad9eff",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4c57e74700bd6d40304168de251c668b65ad9eff",
"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/8922841079241811440/+/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:9088271141298585264",
"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:724637359ef54fb792b6dc06eeabdfab76cd979c",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:42bb35093c961310",
"user_agent:luci-scheduler"
],
"updated_ts": "1548919944864184",
"url": "https://ci.chromium.org/b/8922841079241811440",
"utcnow_ts": "1548919945547202"
}
}
[07:32:25.559] Invocation finished in 2h1m31.343702451s with status SUCCEEDED