[03:41:39.652] New invocation is queued and will start shortly
[03:41:41.075] Starting the invocation (attempt 1)
[03:41:41.115] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:41:41.115] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:41:41.115] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9088278014279740928",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"c3b0092ac75c091db3c811434662df6f7b5072fd\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9088278014279740928",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/c3b0092ac75c091db3c811434662df6f7b5072fd",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c3b0092ac75c091db3c811434662df6f7b5072fd",
"gitiles_ref:refs/heads/master"
]
}
[03:41:43.113] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1548906101193819",
"id": "8922847951993731696",
"lease_expiration_ts": "1549024902735326",
"lease_key": "1666837267",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"c3b0092ac75c091db3c811434662df6f7b5072fd\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1548906102851883",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/11781",
"builder:Windows deterministic",
"buildset:commit/git/c3b0092ac75c091db3c811434662df6f7b5072fd",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c3b0092ac75c091db3c811434662df6f7b5072fd",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9088278014279740928",
"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/11781",
"swarming_tag:buildbucket_bucket:chromium/ci",
"swarming_tag:buildbucket_build_id:8922847951993731696",
"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/c3b0092ac75c091db3c811434662df6f7b5072fd",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c3b0092ac75c091db3c811434662df6f7b5072fd",
"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/8922847951993731696/+/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:9088278014279740928",
"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:42bad1066fa8a710",
"user_agent:luci-scheduler"
],
"updated_ts": "1548906102851895",
"url": "https://ci.chromium.org/b/8922847951993731696",
"utcnow_ts": "1548906103103225"
}
}
[03:41:43.113] Task URL: https://ci.chromium.org/b/8922847951993731696
[03:41:43.113] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:2:0) after 1m0s
[03:41:44.275] Received PubSub notification, asking Buildbucket for the build status
[03:41:44.347] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:43.169] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:2:0)
[03:42:43.169] Timer tick, asking Buildbucket for the build status
[03:42:43.267] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:43.267] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:4:0) after 1m0s
[03:43:43.288] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:4:0)
[03:43:43.288] Timer tick, asking Buildbucket for the build status
[03:43:43.490] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:43.490] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:5:0) after 1m0s
[03:44:43.581] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:5:0)
[03:44:43.581] Timer tick, asking Buildbucket for the build status
[03:44:43.677] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:43.677] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:6:0) after 1m0s
[03:45:43.773] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:6:0)
[03:45:43.773] Timer tick, asking Buildbucket for the build status
[03:45:43.828] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:43.828] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:7:0) after 1m0s
[03:46:43.851] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:7:0)
[03:46:43.851] Timer tick, asking Buildbucket for the build status
[03:46:43.965] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:43.965] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:8:0) after 1m0s
[03:47:44.021] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:8:0)
[03:47:44.021] Timer tick, asking Buildbucket for the build status
[03:47:44.219] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:44.219] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:9:0) after 1m0s
[03:48:44.307] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:9:0)
[03:48:44.307] Timer tick, asking Buildbucket for the build status
[03:48:44.568] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:44.568] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:10:0) after 1m0s
[03:49:44.608] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:10:0)
[03:49:44.608] Timer tick, asking Buildbucket for the build status
[03:49:44.710] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:44.710] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:11:0) after 1m0s
[03:50:44.728] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:11:0)
[03:50:44.728] Timer tick, asking Buildbucket for the build status
[03:50:44.793] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:44.793] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:12:0) after 1m0s
[03:51:44.828] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:12:0)
[03:51:44.828] Timer tick, asking Buildbucket for the build status
[03:51:44.911] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:44.911] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:13:0) after 1m0s
[03:52:44.937] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:13:0)
[03:52:44.937] Timer tick, asking Buildbucket for the build status
[03:52:45.101] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:45.101] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:14:0) after 1m0s
[03:53:45.152] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:14:0)
[03:53:45.152] Timer tick, asking Buildbucket for the build status
[03:53:45.287] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:45.287] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:15:0) after 1m0s
[03:54:45.318] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:15:0)
[03:54:45.318] Timer tick, asking Buildbucket for the build status
[03:54:45.411] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:45.411] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:16:0) after 1m0s
[03:55:45.431] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:16:0)
[03:55:45.431] Timer tick, asking Buildbucket for the build status
[03:55:45.700] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:45.700] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:17:0) after 1m0s
[03:56:45.726] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:17:0)
[03:56:45.726] Timer tick, asking Buildbucket for the build status
[03:56:46.110] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:46.110] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:18:0) after 1m0s
[03:57:46.369] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:18:0)
[03:57:46.369] Timer tick, asking Buildbucket for the build status
[03:57:46.578] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:46.578] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:19:0) after 1m0s
[03:58:46.614] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:19:0)
[03:58:46.614] Timer tick, asking Buildbucket for the build status
[03:58:46.711] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:46.711] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:20:0) after 1m0s
[03:59:46.769] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:20:0)
[03:59:46.769] Timer tick, asking Buildbucket for the build status
[03:59:46.859] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:46.859] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:21:0) after 1m0s
[04:00:46.880] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:21:0)
[04:00:46.880] Timer tick, asking Buildbucket for the build status
[04:00:47.325] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:47.325] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:22:0) after 1m0s
[04:01:47.353] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:22:0)
[04:01:47.353] Timer tick, asking Buildbucket for the build status
[04:01:47.438] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:47.438] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:23:0) after 1m0s
[04:02:47.459] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:23:0)
[04:02:47.459] Timer tick, asking Buildbucket for the build status
[04:02:47.594] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:47.594] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:24:0) after 1m0s
[04:03:47.616] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:24:0)
[04:03:47.616] Timer tick, asking Buildbucket for the build status
[04:03:47.670] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:47.671] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:25:0) after 1m0s
[04:04:47.690] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:25:0)
[04:04:47.690] Timer tick, asking Buildbucket for the build status
[04:04:47.736] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:47.736] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:26:0) after 1m0s
[04:05:47.760] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:26:0)
[04:05:47.760] Timer tick, asking Buildbucket for the build status
[04:05:47.932] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:47.932] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:27:0) after 1m0s
[04:06:47.953] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:27:0)
[04:06:47.953] Timer tick, asking Buildbucket for the build status
[04:06:48.151] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:48.151] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:28:0) after 1m0s
[04:07:48.180] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:28:0)
[04:07:48.180] Timer tick, asking Buildbucket for the build status
[04:07:48.233] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:48.233] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:29:0) after 1m0s
[04:08:48.258] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:29:0)
[04:08:48.258] Timer tick, asking Buildbucket for the build status
[04:08:48.576] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:48.576] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:30:0) after 1m0s
[04:09:48.601] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:30:0)
[04:09:48.601] Timer tick, asking Buildbucket for the build status
[04:09:48.889] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:48.889] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:31:0) after 1m0s
[04:10:48.916] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:31:0)
[04:10:48.916] Timer tick, asking Buildbucket for the build status
[04:10:49.237] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:49.237] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:32:0) after 1m0s
[04:11:49.260] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:32:0)
[04:11:49.260] Timer tick, asking Buildbucket for the build status
[04:11:49.389] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:49.389] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:33:0) after 1m0s
[04:12:49.412] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:33:0)
[04:12:49.412] Timer tick, asking Buildbucket for the build status
[04:12:49.515] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:49.515] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:34:0) after 1m0s
[04:13:49.555] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:34:0)
[04:13:49.555] Timer tick, asking Buildbucket for the build status
[04:13:49.644] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:49.644] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:35:0) after 1m0s
[04:14:49.665] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:35:0)
[04:14:49.665] Timer tick, asking Buildbucket for the build status
[04:14:49.785] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:49.785] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:36:0) after 1m0s
[04:15:49.818] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:36:0)
[04:15:49.818] Timer tick, asking Buildbucket for the build status
[04:15:49.899] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:49.899] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:37:0) after 1m0s
[04:16:49.926] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:37:0)
[04:16:49.926] Timer tick, asking Buildbucket for the build status
[04:16:50.193] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:50.193] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:38:0) after 1m0s
[04:17:50.217] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:38:0)
[04:17:50.217] Timer tick, asking Buildbucket for the build status
[04:17:50.370] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:50.370] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:39:0) after 1m0s
[04:18:50.390] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:39:0)
[04:18:50.390] Timer tick, asking Buildbucket for the build status
[04:18:50.440] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:50.440] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:40:0) after 1m0s
[04:19:50.485] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:40:0)
[04:19:50.485] Timer tick, asking Buildbucket for the build status
[04:19:50.618] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:50.618] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:41:0) after 1m0s
[04:20:50.679] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:41:0)
[04:20:50.679] Timer tick, asking Buildbucket for the build status
[04:20:50.735] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:50.735] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:42:0) after 1m0s
[04:21:50.776] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:42:0)
[04:21:50.776] Timer tick, asking Buildbucket for the build status
[04:21:51.167] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:51.168] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:43:0) after 1m0s
[04:22:51.190] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:43:0)
[04:22:51.190] Timer tick, asking Buildbucket for the build status
[04:22:51.272] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:51.272] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:44:0) after 1m0s
[04:23:51.296] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:44:0)
[04:23:51.296] Timer tick, asking Buildbucket for the build status
[04:23:51.473] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:51.473] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:45:0) after 1m0s
[04:24:51.523] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:45:0)
[04:24:51.523] Timer tick, asking Buildbucket for the build status
[04:24:51.576] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:51.576] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:46:0) after 1m0s
[04:25:51.705] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:46:0)
[04:25:51.705] Timer tick, asking Buildbucket for the build status
[04:25:51.788] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:51.788] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:47:0) after 1m0s
[04:26:51.846] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:47:0)
[04:26:51.846] Timer tick, asking Buildbucket for the build status
[04:26:51.987] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:51.987] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:48:0) after 1m0s
[04:27:52.033] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:48:0)
[04:27:52.033] Timer tick, asking Buildbucket for the build status
[04:27:52.553] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:52.553] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:49:0) after 1m0s
[04:28:52.579] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:49:0)
[04:28:52.579] Timer tick, asking Buildbucket for the build status
[04:28:52.722] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:52.722] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:50:0) after 1m0s
[04:29:52.756] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:50:0)
[04:29:52.756] Timer tick, asking Buildbucket for the build status
[04:29:53.056] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:53.056] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:51:0) after 1m0s
[04:30:53.094] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:51:0)
[04:30:53.094] Timer tick, asking Buildbucket for the build status
[04:30:53.318] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:53.318] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:52:0) after 1m0s
[04:31:53.344] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:52:0)
[04:31:53.344] Timer tick, asking Buildbucket for the build status
[04:31:53.483] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:53.483] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:53:0) after 1m0s
[04:32:53.513] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:53:0)
[04:32:53.513] Timer tick, asking Buildbucket for the build status
[04:32:53.661] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:53.661] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:54:0) after 1m0s
[04:33:53.759] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:54:0)
[04:33:53.759] Timer tick, asking Buildbucket for the build status
[04:33:53.863] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:53.863] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:55:0) after 1m0s
[04:34:53.884] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:55:0)
[04:34:53.884] Timer tick, asking Buildbucket for the build status
[04:34:53.964] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:53.964] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:56:0) after 1m0s
[04:35:53.991] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:56:0)
[04:35:53.991] Timer tick, asking Buildbucket for the build status
[04:35:54.119] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:54.119] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:57:0) after 1m0s
[04:36:54.312] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:57:0)
[04:36:54.312] Timer tick, asking Buildbucket for the build status
[04:36:54.503] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:54.503] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:58:0) after 1m0s
[04:37:54.525] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:58:0)
[04:37:54.525] Timer tick, asking Buildbucket for the build status
[04:37:54.683] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:54.683] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:59:0) after 1m0s
[04:38:54.706] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:59:0)
[04:38:54.706] Timer tick, asking Buildbucket for the build status
[04:38:54.800] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:54.800] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:60:0) after 1m0s
[04:39:54.832] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:60:0)
[04:39:54.832] Timer tick, asking Buildbucket for the build status
[04:39:54.884] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:54.884] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:61:0) after 1m0s
[04:40:54.905] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:61:0)
[04:40:54.905] Timer tick, asking Buildbucket for the build status
[04:40:55.021] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:55.021] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:62:0) after 1m0s
[04:41:55.056] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:62:0)
[04:41:55.056] Timer tick, asking Buildbucket for the build status
[04:41:55.150] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:55.150] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:63:0) after 1m0s
[04:42:55.224] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:63:0)
[04:42:55.224] Timer tick, asking Buildbucket for the build status
[04:42:55.283] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:55.283] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:64:0) after 1m0s
[04:43:55.307] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:64:0)
[04:43:55.307] Timer tick, asking Buildbucket for the build status
[04:43:55.362] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:55.362] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:65:0) after 1m0s
[04:44:55.395] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:65:0)
[04:44:55.395] Timer tick, asking Buildbucket for the build status
[04:44:55.536] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:55.536] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:66:0) after 1m0s
[04:45:55.557] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:66:0)
[04:45:55.557] Timer tick, asking Buildbucket for the build status
[04:45:55.645] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:55.645] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:67:0) after 1m0s
[04:46:55.730] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:67:0)
[04:46:55.730] Timer tick, asking Buildbucket for the build status
[04:46:55.794] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:55.794] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:68:0) after 1m0s
[04:47:55.826] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:68:0)
[04:47:55.826] Timer tick, asking Buildbucket for the build status
[04:47:55.880] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:55.880] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:69:0) after 1m0s
[04:48:55.926] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:69:0)
[04:48:55.926] Timer tick, asking Buildbucket for the build status
[04:48:56.016] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:56.016] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:70:0) after 1m0s
[04:49:56.070] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:70:0)
[04:49:56.070] Timer tick, asking Buildbucket for the build status
[04:49:56.195] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:56.195] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:71:0) after 1m0s
[04:50:56.289] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:71:0)
[04:50:56.289] Timer tick, asking Buildbucket for the build status
[04:50:56.465] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:56.465] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:72:0) after 1m0s
[04:51:56.493] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:72:0)
[04:51:56.493] Timer tick, asking Buildbucket for the build status
[04:51:56.567] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:56.567] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:73:0) after 1m0s
[04:52:56.601] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:73:0)
[04:52:56.601] Timer tick, asking Buildbucket for the build status
[04:52:56.777] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:56.777] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:74:0) after 1m0s
[04:53:56.852] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:74:0)
[04:53:56.852] Timer tick, asking Buildbucket for the build status
[04:53:56.905] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:56.905] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:75:0) after 1m0s
[04:54:56.946] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:75:0)
[04:54:56.946] Timer tick, asking Buildbucket for the build status
[04:54:57.017] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:57.018] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:76:0) after 1m0s
[04:55:57.042] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:76:0)
[04:55:57.042] Timer tick, asking Buildbucket for the build status
[04:55:57.660] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:57.660] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:77:0) after 1m0s
[04:56:57.724] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:77:0)
[04:56:57.724] Timer tick, asking Buildbucket for the build status
[04:56:58.050] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:58.050] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:78:0) after 1m0s
[04:57:58.078] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:78:0)
[04:57:58.078] Timer tick, asking Buildbucket for the build status
[04:57:58.384] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:58.384] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:79:0) after 1m0s
[04:58:58.421] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:79:0)
[04:58:58.421] Timer tick, asking Buildbucket for the build status
[04:58:58.485] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:58.485] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:80:0) after 1m0s
[04:59:58.510] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:80:0)
[04:59:58.510] Timer tick, asking Buildbucket for the build status
[04:59:58.813] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:58.813] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:81:0) after 1m0s
[05:00:58.839] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:81:0)
[05:00:58.839] Timer tick, asking Buildbucket for the build status
[05:00:59.747] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:59.747] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:82:0) after 1m0s
[05:01:59.780] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:82:0)
[05:01:59.780] Timer tick, asking Buildbucket for the build status
[05:02:00.182] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:00.182] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:83:0) after 1m0s
[05:03:00.235] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:83:0)
[05:03:00.235] Timer tick, asking Buildbucket for the build status
[05:03:00.319] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:00.319] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:84:0) after 1m0s
[05:04:00.343] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:84:0)
[05:04:00.343] Timer tick, asking Buildbucket for the build status
[05:04:00.416] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:00.416] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:85:0) after 1m0s
[05:05:00.444] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:85:0)
[05:05:00.444] Timer tick, asking Buildbucket for the build status
[05:05:00.574] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:00.574] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:86:0) after 1m0s
[05:06:00.602] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:86:0)
[05:06:00.602] Timer tick, asking Buildbucket for the build status
[05:06:00.655] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:00.655] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:87:0) after 1m0s
[05:07:00.708] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:87:0)
[05:07:00.708] Timer tick, asking Buildbucket for the build status
[05:07:00.779] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:00.779] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:88:0) after 1m0s
[05:08:00.882] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:88:0)
[05:08:00.882] Timer tick, asking Buildbucket for the build status
[05:08:00.948] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:00.948] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:89:0) after 1m0s
[05:09:00.968] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:89:0)
[05:09:00.968] Timer tick, asking Buildbucket for the build status
[05:09:01.035] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:01.035] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:90:0) after 1m0s
[05:10:01.146] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:90:0)
[05:10:01.146] Timer tick, asking Buildbucket for the build status
[05:10:01.208] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:01.208] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:91:0) after 1m0s
[05:11:01.232] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:91:0)
[05:11:01.232] Timer tick, asking Buildbucket for the build status
[05:11:01.409] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:01.409] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:92:0) after 1m0s
[05:12:01.435] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:92:0)
[05:12:01.435] Timer tick, asking Buildbucket for the build status
[05:12:01.700] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:01.700] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:93:0) after 1m0s
[05:13:01.722] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:93:0)
[05:13:01.722] Timer tick, asking Buildbucket for the build status
[05:13:01.816] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:01.816] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:94:0) after 1m0s
[05:14:01.927] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:94:0)
[05:14:01.927] Timer tick, asking Buildbucket for the build status
[05:14:02.083] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:02.083] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:95:0) after 1m0s
[05:15:02.127] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:95:0)
[05:15:02.127] Timer tick, asking Buildbucket for the build status
[05:15:02.315] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:02.315] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:96:0) after 1m0s
[05:16:02.353] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:96:0)
[05:16:02.353] Timer tick, asking Buildbucket for the build status
[05:16:02.451] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:02.451] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:97:0) after 1m0s
[05:17:02.489] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:97:0)
[05:17:02.489] Timer tick, asking Buildbucket for the build status
[05:17:02.538] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:02.538] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:98:0) after 1m0s
[05:18:02.577] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:98:0)
[05:18:02.577] Timer tick, asking Buildbucket for the build status
[05:18:02.674] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:02.674] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:99:0) after 1m0s
[05:19:02.697] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:99:0)
[05:19:02.697] Timer tick, asking Buildbucket for the build status
[05:19:02.769] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:02.769] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:100:0) after 1m0s
[05:20:02.797] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:100:0)
[05:20:02.797] Timer tick, asking Buildbucket for the build status
[05:20:02.859] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:02.859] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:101:0) after 1m0s
[05:21:02.891] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:101:0)
[05:21:02.891] Timer tick, asking Buildbucket for the build status
[05:21:03.027] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:03.027] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:102:0) after 1m0s
[05:22:03.071] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:102:0)
[05:22:03.072] Timer tick, asking Buildbucket for the build status
[05:22:03.280] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:03.280] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:103:0) after 1m0s
[05:23:03.306] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:103:0)
[05:23:03.306] Timer tick, asking Buildbucket for the build status
[05:23:03.414] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:03.414] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:104:0) after 1m0s
[05:24:03.515] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:104:0)
[05:24:03.515] Timer tick, asking Buildbucket for the build status
[05:24:03.663] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:03.663] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:105:0) after 1m0s
[05:25:03.690] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:105:0)
[05:25:03.690] Timer tick, asking Buildbucket for the build status
[05:25:03.744] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:03.744] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:106:0) after 1m0s
[05:26:03.844] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:106:0)
[05:26:03.844] Timer tick, asking Buildbucket for the build status
[05:26:03.954] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:03.954] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:107:0) after 1m0s
[05:27:03.982] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:107:0)
[05:27:03.982] Timer tick, asking Buildbucket for the build status
[05:27:04.032] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:04.032] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:108:0) after 1m0s
[05:28:04.059] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:108:0)
[05:28:04.059] Timer tick, asking Buildbucket for the build status
[05:28:04.162] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:04.162] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:109:0) after 1m0s
[05:29:04.191] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:109:0)
[05:29:04.191] Timer tick, asking Buildbucket for the build status
[05:29:04.271] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:04.271] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:110:0) after 1m0s
[05:30:04.332] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:110:0)
[05:30:04.332] Timer tick, asking Buildbucket for the build status
[05:30:04.803] Build 8922847951993731696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:04.803] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9088278014279740928:111:0) after 1m0s
[05:30:51.813] Received PubSub notification, asking Buildbucket for the build status
[05:30:51.868] Build 8922847951993731696: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[05:30:51.868] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1548912648583433",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1548906101193819",
"id": "8922847951993731696",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"c3b0092ac75c091db3c811434662df6f7b5072fd\"}}",
"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\": \"c3b0092ac75c091db3c811434662df6f7b5072fd\", \"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@{#627770}\", \"branch\": \"refs/heads/master\", \"revision\": \"c3b0092ac75c091db3c811434662df6f7b5072fd\", \"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\": 1548906101193819.0, \"tags\": [\"build_address:luci.chromium.ci/Windows deterministic/11781\", \"builder:Windows deterministic\", \"buildset:commit/git/c3b0092ac75c091db3c811434662df6f7b5072fd\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c3b0092ac75c091db3c811434662df6f7b5072fd\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9088278014279740928\", \"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\": \"8922847951993731696\"}}, \"got_v8_revision\": \"e0899ad9ee97543e6f45961fe13435882df25b8a\", \"got_v8_revision_cp\": \"refs/heads/7.4.39@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 11781, \"$recipe_engine/buildbucket\": {\"build\": {\"tags\": [{\"key\": \"builder\", \"value\": \"Windows deterministic\"}, {\"key\": \"buildset\", \"value\": \"commit/git/c3b0092ac75c091db3c811434662df6f7b5072fd\"}, {\"value\": \"commit/gitiles/chromium.googlesource.com/chromium/src/+/c3b0092ac75c091db3c811434662df6f7b5072fd\", \"key\": \"buildset\"}, {\"key\": \"scheduler_invocation_id\", \"value\": \"9088278014279740928\"}, {\"key\": \"scheduler_job_id\", \"value\": \"chromium/Windows deterministic\"}, {\"key\": \"user_agent\", \"value\": \"luci-scheduler\"}], \"builder\": {\"project\": \"chromium\", \"builder\": \"Windows deterministic\", \"bucket\": \"ci\"}, \"number\": 11781.0, \"infra\": {\"buildbucket\": {\"serviceConfigRevision\": \"01134cf47e8a2d5d7cea5ec1232ca64ed2f16a5c\"}, \"swarming\": {\"hostname\": \"chromium-swarm.appspot.com\"}, \"recipe\": {\"name\": \"swarming/deterministic_build\", \"cipdPackage\": \"infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build\"}}, \"createdBy\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"id\": \"8922847951993731696\", \"input\": {\"gitilesCommit\": {\"project\": \"chromium/src\", \"host\": \"chromium.googlesource.com\", \"ref\": \"refs/heads/master\", \"id\": \"c3b0092ac75c091db3c811434662df6f7b5072fd\"}}, \"createTime\": \"2019-01-31T03:41:41.193819Z\"}}, \"path_config\": \"generic\", \"got_buildtools_revision\": \"6fbda1b24c1893a893b17aa219b765b9e7c801d8\", \"bot_id\": \"swarm2173-c4\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1548906102974796",
"status": "COMPLETED",
"status_changed_ts": "1548912649198589",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/11781",
"builder:Windows deterministic",
"buildset:commit/git/c3b0092ac75c091db3c811434662df6f7b5072fd",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c3b0092ac75c091db3c811434662df6f7b5072fd",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9088278014279740928",
"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/11781",
"swarming_tag:buildbucket_bucket:chromium/ci",
"swarming_tag:buildbucket_build_id:8922847951993731696",
"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/c3b0092ac75c091db3c811434662df6f7b5072fd",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c3b0092ac75c091db3c811434662df6f7b5072fd",
"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/8922847951993731696/+/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:9088278014279740928",
"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:42bad1066fa8a710",
"user_agent:luci-scheduler"
],
"updated_ts": "1548912649199671",
"url": "https://ci.chromium.org/b/8922847951993731696",
"utcnow_ts": "1548912651853945"
}
}
[05:30:51.868] Invocation finished in 1h49m12.238555595s with status SUCCEEDED