[23:04:56.082] New invocation is queued and will start shortly
[23:04:57.414] Starting the invocation (attempt 1)
[23:04:57.448] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:04:57.448] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:04:57.449] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9092281690900073232",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"46c516e9ed2d91e637bf16428be9fe8a390ff60f\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9092281690900073232",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/46c516e9ed2d91e637bf16428be9fe8a390ff60f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/46c516e9ed2d91e637bf16428be9fe8a390ff60f",
"gitiles_ref:refs/heads/master"
]
}
[23:04:59.781] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1545087897543728",
"id": "8926851628704320240",
"lease_expiration_ts": "1545206699572005",
"lease_key": "1533848939",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"46c516e9ed2d91e637bf16428be9fe8a390ff60f\"}}",
"project": "chromium",
"result_details_json": "null",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1545087899692576",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/11161",
"builder:Windows deterministic",
"buildset:commit/git/46c516e9ed2d91e637bf16428be9fe8a390ff60f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/46c516e9ed2d91e637bf16428be9fe8a390ff60f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9092281690900073232",
"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/11161",
"swarming_tag:buildbucket_bucket:chromium/ci",
"swarming_tag:buildbucket_build_id:8926851628704320240",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:daaff082e95b94bec84fa6e440a8b97677d6f76d",
"swarming_tag:builder:Windows deterministic",
"swarming_tag:buildset:commit/git/46c516e9ed2d91e637bf16428be9fe8a390ff60f",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/46c516e9ed2d91e637bf16428be9fe8a390ff60f",
"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/8926851628704320240/+/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:9092281690900073232",
"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:c1bb9767852e530d6e0ecdbd762ba7bfa12fbd39",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:41d73bdd63137c10",
"user_agent:luci-scheduler"
],
"updated_ts": "1545087899692643",
"url": "https://ci.chromium.org/b/8926851628704320240",
"utcnow_ts": "1545087899770236"
}
}
[23:04:59.781] Task URL: https://ci.chromium.org/b/8926851628704320240
[23:04:59.781] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:2:0) after 1m0s
[23:05:00.901] Received PubSub notification, asking Buildbucket for the build status
[23:05:00.943] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:59.801] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:2:0)
[23:05:59.801] Timer tick, asking Buildbucket for the build status
[23:05:59.851] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:59.851] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:4:0) after 1m0s
[23:06:59.868] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:4:0)
[23:06:59.868] Timer tick, asking Buildbucket for the build status
[23:06:59.954] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:59.954] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:5:0) after 1m0s
[23:07:59.975] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:5:0)
[23:07:59.975] Timer tick, asking Buildbucket for the build status
[23:08:00.113] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:00.113] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:6:0) after 1m0s
[23:09:00.121] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:6:0)
[23:09:00.121] Timer tick, asking Buildbucket for the build status
[23:09:00.307] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:00.307] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:7:0) after 1m0s
[23:10:00.325] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:7:0)
[23:10:00.325] Timer tick, asking Buildbucket for the build status
[23:10:00.383] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:00.383] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:8:0) after 1m0s
[23:11:00.414] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:8:0)
[23:11:00.414] Timer tick, asking Buildbucket for the build status
[23:11:00.911] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:00.911] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:9:0) after 1m0s
[23:12:00.929] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:9:0)
[23:12:00.929] Timer tick, asking Buildbucket for the build status
[23:12:00.972] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:00.972] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:10:0) after 1m0s
[23:13:01.004] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:10:0)
[23:13:01.004] Timer tick, asking Buildbucket for the build status
[23:13:01.091] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:01.091] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:11:0) after 1m0s
[23:14:01.130] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:11:0)
[23:14:01.130] Timer tick, asking Buildbucket for the build status
[23:14:01.189] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:01.189] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:12:0) after 1m0s
[23:15:01.326] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:12:0)
[23:15:01.326] Timer tick, asking Buildbucket for the build status
[23:15:01.386] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:01.386] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:13:0) after 1m0s
[23:16:01.411] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:13:0)
[23:16:01.412] Timer tick, asking Buildbucket for the build status
[23:16:01.469] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:01.469] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:14:0) after 1m0s
[23:17:01.486] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:14:0)
[23:17:01.486] Timer tick, asking Buildbucket for the build status
[23:17:01.927] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:01.927] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:15:0) after 1m0s
[23:18:01.954] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:15:0)
[23:18:01.954] Timer tick, asking Buildbucket for the build status
[23:18:02.665] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:02.665] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:16:0) after 1m0s
[23:19:02.682] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:16:0)
[23:19:02.682] Timer tick, asking Buildbucket for the build status
[23:19:02.924] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:02.924] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:17:0) after 1m0s
[23:20:02.940] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:17:0)
[23:20:02.940] Timer tick, asking Buildbucket for the build status
[23:20:03.023] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:03.023] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:18:0) after 1m0s
[23:21:03.098] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:18:0)
[23:21:03.098] Timer tick, asking Buildbucket for the build status
[23:21:03.158] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:03.158] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:19:0) after 1m0s
[23:22:03.218] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:19:0)
[23:22:03.218] Timer tick, asking Buildbucket for the build status
[23:22:03.310] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:03.310] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:20:0) after 1m0s
[23:23:03.331] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:20:0)
[23:23:03.331] Timer tick, asking Buildbucket for the build status
[23:23:03.499] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:03.499] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:21:0) after 1m0s
[23:24:03.524] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:21:0)
[23:24:03.524] Timer tick, asking Buildbucket for the build status
[23:24:03.635] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:03.635] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:22:0) after 1m0s
[23:25:03.717] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:22:0)
[23:25:03.717] Timer tick, asking Buildbucket for the build status
[23:25:03.778] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:03.778] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:23:0) after 1m0s
[23:26:03.808] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:23:0)
[23:26:03.808] Timer tick, asking Buildbucket for the build status
[23:26:03.933] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:03.933] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:24:0) after 1m0s
[23:27:03.951] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:24:0)
[23:27:03.951] Timer tick, asking Buildbucket for the build status
[23:27:04.163] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:04.163] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:25:0) after 1m0s
[23:28:04.183] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:25:0)
[23:28:04.183] Timer tick, asking Buildbucket for the build status
[23:28:04.234] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:04.234] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:26:0) after 1m0s
[23:29:04.252] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:26:0)
[23:29:04.252] Timer tick, asking Buildbucket for the build status
[23:29:04.362] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:04.362] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:27:0) after 1m0s
[23:30:04.399] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:27:0)
[23:30:04.399] Timer tick, asking Buildbucket for the build status
[23:30:04.479] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:04.479] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:28:0) after 1m0s
[23:31:04.499] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:28:0)
[23:31:04.499] Timer tick, asking Buildbucket for the build status
[23:31:04.611] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:04.611] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:29:0) after 1m0s
[23:32:04.734] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:29:0)
[23:32:04.734] Timer tick, asking Buildbucket for the build status
[23:32:04.773] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:04.773] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:30:0) after 1m0s
[23:33:04.802] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:30:0)
[23:33:04.802] Timer tick, asking Buildbucket for the build status
[23:33:05.060] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:05.060] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:31:0) after 1m0s
[23:34:05.084] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:31:0)
[23:34:05.084] Timer tick, asking Buildbucket for the build status
[23:34:05.263] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:05.263] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:32:0) after 1m0s
[23:35:05.281] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:32:0)
[23:35:05.281] Timer tick, asking Buildbucket for the build status
[23:35:05.328] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:05.328] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:33:0) after 1m0s
[23:36:05.350] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:33:0)
[23:36:05.350] Timer tick, asking Buildbucket for the build status
[23:36:05.473] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:05.473] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:34:0) after 1m0s
[23:37:05.493] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:34:0)
[23:37:05.493] Timer tick, asking Buildbucket for the build status
[23:37:05.531] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:05.531] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:35:0) after 1m0s
[23:38:05.550] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:35:0)
[23:38:05.550] Timer tick, asking Buildbucket for the build status
[23:38:05.678] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:05.678] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:36:0) after 1m0s
[23:39:05.695] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:36:0)
[23:39:05.695] Timer tick, asking Buildbucket for the build status
[23:39:05.760] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:05.760] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:37:0) after 1m0s
[23:40:05.794] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:37:0)
[23:40:05.794] Timer tick, asking Buildbucket for the build status
[23:40:05.868] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:05.868] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:38:0) after 1m0s
[23:41:05.888] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:38:0)
[23:41:05.888] Timer tick, asking Buildbucket for the build status
[23:41:05.936] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:05.936] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:39:0) after 1m0s
[23:42:05.964] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:39:0)
[23:42:05.964] Timer tick, asking Buildbucket for the build status
[23:42:06.219] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:06.219] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:40:0) after 1m0s
[23:43:06.238] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:40:0)
[23:43:06.238] Timer tick, asking Buildbucket for the build status
[23:43:06.306] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:06.306] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:41:0) after 1m0s
[23:44:06.328] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:41:0)
[23:44:06.328] Timer tick, asking Buildbucket for the build status
[23:44:06.366] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:06.366] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:42:0) after 1m0s
[23:45:06.386] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:42:0)
[23:45:06.386] Timer tick, asking Buildbucket for the build status
[23:45:06.419] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:06.419] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:43:0) after 1m0s
[23:46:06.437] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:43:0)
[23:46:06.437] Timer tick, asking Buildbucket for the build status
[23:46:06.490] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:06.490] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:44:0) after 1m0s
[23:47:06.597] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:44:0)
[23:47:06.597] Timer tick, asking Buildbucket for the build status
[23:47:06.642] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:06.642] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:45:0) after 1m0s
[23:48:06.720] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:45:0)
[23:48:06.720] Timer tick, asking Buildbucket for the build status
[23:48:06.798] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:06.798] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:46:0) after 1m0s
[23:49:06.821] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:46:0)
[23:49:06.821] Timer tick, asking Buildbucket for the build status
[23:49:06.876] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:06.876] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:47:0) after 1m0s
[23:50:06.896] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:47:0)
[23:50:06.896] Timer tick, asking Buildbucket for the build status
[23:50:06.933] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:06.933] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:48:0) after 1m0s
[23:51:06.953] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:48:0)
[23:51:06.953] Timer tick, asking Buildbucket for the build status
[23:51:07.060] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:07.060] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:49:0) after 1m0s
[23:52:07.089] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:49:0)
[23:52:07.089] Timer tick, asking Buildbucket for the build status
[23:52:07.169] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:07.169] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:50:0) after 1m0s
[23:53:07.189] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:50:0)
[23:53:07.189] Timer tick, asking Buildbucket for the build status
[23:53:07.228] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:07.228] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:51:0) after 1m0s
[23:54:07.255] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:51:0)
[23:54:07.255] Timer tick, asking Buildbucket for the build status
[23:54:07.293] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:07.293] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:52:0) after 1m0s
[23:55:07.314] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:52:0)
[23:55:07.314] Timer tick, asking Buildbucket for the build status
[23:55:07.356] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:07.356] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:53:0) after 1m0s
[23:56:07.387] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:53:0)
[23:56:07.387] Timer tick, asking Buildbucket for the build status
[23:56:07.450] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:07.450] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:54:0) after 1m0s
[23:57:07.474] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:54:0)
[23:57:07.474] Timer tick, asking Buildbucket for the build status
[23:57:07.518] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:07.518] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:55:0) after 1m0s
[23:58:07.606] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:55:0)
[23:58:07.606] Timer tick, asking Buildbucket for the build status
[23:58:07.672] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:07.672] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:56:0) after 1m0s
[23:59:07.693] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:56:0)
[23:59:07.693] Timer tick, asking Buildbucket for the build status
[23:59:07.777] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:07.777] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:57:0) after 1m0s
[00:00:07.799] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:57:0)
[00:00:07.799] Timer tick, asking Buildbucket for the build status
[00:00:07.894] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:07.894] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:58:0) after 1m0s
[00:01:07.919] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:58:0)
[00:01:07.919] Timer tick, asking Buildbucket for the build status
[00:01:08.001] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:08.001] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:59:0) after 1m0s
[00:02:08.030] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:59:0)
[00:02:08.030] Timer tick, asking Buildbucket for the build status
[00:02:08.159] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:08.161] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:60:0) after 1m0s
[00:03:08.183] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:60:0)
[00:03:08.183] Timer tick, asking Buildbucket for the build status
[00:03:08.358] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:08.358] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:61:0) after 1m0s
[00:04:08.381] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:61:0)
[00:04:08.381] Timer tick, asking Buildbucket for the build status
[00:04:08.492] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:08.492] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:62:0) after 1m0s
[00:05:08.511] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:62:0)
[00:05:08.511] Timer tick, asking Buildbucket for the build status
[00:05:08.625] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:08.625] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:63:0) after 1m0s
[00:06:08.648] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:63:0)
[00:06:08.648] Timer tick, asking Buildbucket for the build status
[00:06:08.710] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:08.710] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:64:0) after 1m0s
[00:07:08.729] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:64:0)
[00:07:08.729] Timer tick, asking Buildbucket for the build status
[00:07:08.991] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:08.991] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:65:0) after 1m0s
[00:08:09.013] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:65:0)
[00:08:09.013] Timer tick, asking Buildbucket for the build status
[00:08:09.080] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:09.080] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:66:0) after 1m0s
[00:09:09.110] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:66:0)
[00:09:09.110] Timer tick, asking Buildbucket for the build status
[00:09:09.588] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:09.588] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:67:0) after 1m0s
[00:10:09.610] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:67:0)
[00:10:09.610] Timer tick, asking Buildbucket for the build status
[00:10:10.381] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:10.381] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:68:0) after 1m0s
[00:11:10.471] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:68:0)
[00:11:10.471] Timer tick, asking Buildbucket for the build status
[00:11:10.724] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:10.724] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:69:0) after 1m0s
[00:12:10.747] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:69:0)
[00:12:10.747] Timer tick, asking Buildbucket for the build status
[00:12:11.003] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:11.003] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:70:0) after 1m0s
[00:13:11.023] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:70:0)
[00:13:11.023] Timer tick, asking Buildbucket for the build status
[00:13:11.079] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:11.079] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:71:0) after 1m0s
[00:14:11.102] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:71:0)
[00:14:11.102] Timer tick, asking Buildbucket for the build status
[00:14:11.134] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:11.134] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:72:0) after 1m0s
[00:15:11.213] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:72:0)
[00:15:11.213] Timer tick, asking Buildbucket for the build status
[00:15:11.259] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:11.259] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:73:0) after 1m0s
[00:16:11.283] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:73:0)
[00:16:11.283] Timer tick, asking Buildbucket for the build status
[00:16:11.414] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:11.414] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:74:0) after 1m0s
[00:17:11.437] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:74:0)
[00:17:11.437] Timer tick, asking Buildbucket for the build status
[00:17:11.507] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:11.507] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:75:0) after 1m0s
[00:18:11.530] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:75:0)
[00:18:11.530] Timer tick, asking Buildbucket for the build status
[00:18:11.651] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:11.651] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:76:0) after 1m0s
[00:19:11.671] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:76:0)
[00:19:11.671] Timer tick, asking Buildbucket for the build status
[00:19:11.703] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:11.703] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:77:0) after 1m0s
[00:20:11.743] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:77:0)
[00:20:11.743] Timer tick, asking Buildbucket for the build status
[00:20:11.829] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:11.829] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:78:0) after 1m0s
[00:21:11.856] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:78:0)
[00:21:11.856] Timer tick, asking Buildbucket for the build status
[00:21:11.893] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:11.893] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:79:0) after 1m0s
[00:22:11.925] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:79:0)
[00:22:11.925] Timer tick, asking Buildbucket for the build status
[00:22:12.015] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:12.015] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:80:0) after 1m0s
[00:23:12.035] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:80:0)
[00:23:12.035] Timer tick, asking Buildbucket for the build status
[00:23:12.121] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:12.121] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:81:0) after 1m0s
[00:24:12.144] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:81:0)
[00:24:12.144] Timer tick, asking Buildbucket for the build status
[00:24:12.235] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:12.235] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:82:0) after 1m0s
[00:25:12.263] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:82:0)
[00:25:12.263] Timer tick, asking Buildbucket for the build status
[00:25:12.338] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:12.338] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:83:0) after 1m0s
[00:26:12.377] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:83:0)
[00:26:12.377] Timer tick, asking Buildbucket for the build status
[00:26:12.535] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:12.535] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:84:0) after 1m0s
[00:27:12.561] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:84:0)
[00:27:12.561] Timer tick, asking Buildbucket for the build status
[00:27:12.625] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:12.625] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:85:0) after 1m0s
[00:28:12.645] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:85:0)
[00:28:12.645] Timer tick, asking Buildbucket for the build status
[00:28:12.954] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:12.954] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:86:0) after 1m0s
[00:29:12.977] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:86:0)
[00:29:12.977] Timer tick, asking Buildbucket for the build status
[00:29:13.055] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:13.055] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:87:0) after 1m0s
[00:30:13.084] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:87:0)
[00:30:13.084] Timer tick, asking Buildbucket for the build status
[00:30:13.133] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:13.133] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:88:0) after 1m0s
[00:31:13.157] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:88:0)
[00:31:13.157] Timer tick, asking Buildbucket for the build status
[00:31:13.199] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:13.199] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:89:0) after 1m0s
[00:32:13.285] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:89:0)
[00:32:13.285] Timer tick, asking Buildbucket for the build status
[00:32:13.377] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:13.377] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:90:0) after 1m0s
[00:33:13.406] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:90:0)
[00:33:13.406] Timer tick, asking Buildbucket for the build status
[00:33:13.438] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:13.438] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:91:0) after 1m0s
[00:34:13.474] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:91:0)
[00:34:13.474] Timer tick, asking Buildbucket for the build status
[00:34:13.651] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:13.651] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:92:0) after 1m0s
[00:35:13.673] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:92:0)
[00:35:13.673] Timer tick, asking Buildbucket for the build status
[00:35:13.730] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:13.730] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:93:0) after 1m0s
[00:36:13.756] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:93:0)
[00:36:13.756] Timer tick, asking Buildbucket for the build status
[00:36:13.955] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:13.955] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:94:0) after 1m0s
[00:37:14.030] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:94:0)
[00:37:14.030] Timer tick, asking Buildbucket for the build status
[00:37:14.152] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:14.153] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:95:0) after 1m0s
[00:38:14.180] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:95:0)
[00:38:14.180] Timer tick, asking Buildbucket for the build status
[00:38:14.244] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:14.244] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:96:0) after 1m0s
[00:39:14.325] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:96:0)
[00:39:14.325] Timer tick, asking Buildbucket for the build status
[00:39:14.374] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:14.374] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:97:0) after 1m0s
[00:40:14.399] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:97:0)
[00:40:14.399] Timer tick, asking Buildbucket for the build status
[00:40:14.438] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:14.438] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:98:0) after 1m0s
[00:41:14.458] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:98:0)
[00:41:14.458] Timer tick, asking Buildbucket for the build status
[00:41:14.493] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:14.493] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:99:0) after 1m0s
[00:42:14.519] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:99:0)
[00:42:14.519] Timer tick, asking Buildbucket for the build status
[00:42:14.597] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:14.597] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:100:0) after 1m0s
[00:43:14.621] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:100:0)
[00:43:14.621] Timer tick, asking Buildbucket for the build status
[00:43:14.926] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:14.926] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:101:0) after 1m0s
[00:44:14.957] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:101:0)
[00:44:14.957] Timer tick, asking Buildbucket for the build status
[00:44:15.049] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:15.049] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:102:0) after 1m0s
[00:45:15.072] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:102:0)
[00:45:15.072] Timer tick, asking Buildbucket for the build status
[00:45:15.111] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:15.111] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:103:0) after 1m0s
[00:46:15.137] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:103:0)
[00:46:15.137] Timer tick, asking Buildbucket for the build status
[00:46:15.259] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:15.259] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:104:0) after 1m0s
[00:47:15.283] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:104:0)
[00:47:15.283] Timer tick, asking Buildbucket for the build status
[00:47:15.354] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:15.354] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:105:0) after 1m0s
[00:48:15.378] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:105:0)
[00:48:15.378] Timer tick, asking Buildbucket for the build status
[00:48:15.510] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:15.510] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:106:0) after 1m0s
[00:49:15.532] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:106:0)
[00:49:15.532] Timer tick, asking Buildbucket for the build status
[00:49:15.628] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:15.628] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:107:0) after 1m0s
[00:50:15.653] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:107:0)
[00:50:15.653] Timer tick, asking Buildbucket for the build status
[00:50:15.901] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:15.901] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:108:0) after 1m0s
[00:51:15.925] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:108:0)
[00:51:15.925] Timer tick, asking Buildbucket for the build status
[00:51:15.964] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:15.964] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:109:0) after 1m0s
[00:52:15.986] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:109:0)
[00:52:15.986] Timer tick, asking Buildbucket for the build status
[00:52:16.042] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:16.043] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:110:0) after 1m0s
[00:53:16.091] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:110:0)
[00:53:16.091] Timer tick, asking Buildbucket for the build status
[00:53:16.232] Build 8926851628704320240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:16.232] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9092281690900073232:111:0) after 1m0s
[00:53:26.982] Received PubSub notification, asking Buildbucket for the build status
[00:53:27.042] Build 8926851628704320240: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[00:53:27.042] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1545094405806239",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1545087897543728",
"id": "8926851628704320240",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"46c516e9ed2d91e637bf16428be9fe8a390ff60f\"}}",
"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\": [\"3945-402d3be\"], \"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\": \"af5947d13e6794eac489365ff58fd45c0dd4ca2a\", \"got_swarming_client_revision\": \"0e3e1c4dc4e79f25a5b58fcbc135dc93183c0c54\", \"got_revision\": \"46c516e9ed2d91e637bf16428be9fe8a390ff60f\", \"recipe\": \"swarming/deterministic_build\", \"got_dawn_revision\": \"63220100145f1e1e78b4a2aaad8c014e5cc1af00\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#26031}\", \"$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@{#617275}\", \"branch\": \"refs/heads/master\", \"revision\": \"46c516e9ed2d91e637bf16428be9fe8a390ff60f\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"73f2da9fa7cdb9ed03095c8c815d40b1aad3618d\", \"mastername\": \"chromium.win\", \"got_angle_revision\": \"5a8d0bc2884bd97e572b34fb291be399a6c1beae\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1545087897543728, \"tags\": [\"builder:Windows deterministic\", \"buildset:commit/git/46c516e9ed2d91e637bf16428be9fe8a390ff60f\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/46c516e9ed2d91e637bf16428be9fe8a390ff60f\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9092281690900073232\", \"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\": \"8926851628704320240\"}}, \"got_v8_revision\": \"6b92d64803513155302b4178e8a4c09cc87d797f\", \"got_v8_revision_cp\": \"refs/heads/7.3.167@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 11161, \"path_config\": \"generic\", \"bot_id\": \"swarm2173-c4\", \"got_buildtools_revision\": \"7d88270de197ebe8b439ab5eb57a4a2a0bb810e0\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1545087900240053",
"status": "COMPLETED",
"status_changed_ts": "1545094406507325",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/11161",
"builder:Windows deterministic",
"buildset:commit/git/46c516e9ed2d91e637bf16428be9fe8a390ff60f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/46c516e9ed2d91e637bf16428be9fe8a390ff60f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9092281690900073232",
"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/11161",
"swarming_tag:buildbucket_bucket:chromium/ci",
"swarming_tag:buildbucket_build_id:8926851628704320240",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:daaff082e95b94bec84fa6e440a8b97677d6f76d",
"swarming_tag:builder:Windows deterministic",
"swarming_tag:buildset:commit/git/46c516e9ed2d91e637bf16428be9fe8a390ff60f",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/46c516e9ed2d91e637bf16428be9fe8a390ff60f",
"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/8926851628704320240/+/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:9092281690900073232",
"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:c1bb9767852e530d6e0ecdbd762ba7bfa12fbd39",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:41d73bdd63137c10",
"user_agent:luci-scheduler"
],
"updated_ts": "1545094406508509",
"url": "https://ci.chromium.org/b/8926851628704320240",
"utcnow_ts": "1545094407029789"
}
}
[00:53:27.042] Invocation finished in 1h48m30.977197771s with status SUCCEEDED