[15:38:58.912] New invocation is queued and will start shortly
[15:39:00.143] Starting the invocation (attempt 1)
[15:39:00.248] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:39:00.248] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:39:00.248] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9106805262532726448",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"40ff4bde48062ed7342580ccaf95f36a71461141\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9106805262532726448",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/40ff4bde48062ed7342580ccaf95f36a71461141",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/40ff4bde48062ed7342580ccaf95f36a71461141",
"gitiles_ref:refs/heads/master"
]
}
[15:39:01.371] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1531237140454650",
"id": "8941375200169294832",
"lease_expiration_ts": "1531298341056660",
"lease_key": "1949749431",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"40ff4bde48062ed7342580ccaf95f36a71461141\"}}",
"project": "chromium",
"result_details_json": "null",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1531237141265430",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/9459",
"builder:Windows deterministic",
"buildset:commit/git/40ff4bde48062ed7342580ccaf95f36a71461141",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/40ff4bde48062ed7342580ccaf95f36a71461141",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9106805262532726448",
"scheduler_job_id:chromium/Windows deterministic",
"swarming_dimension:builder:Windows deterministic",
"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/9459",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8941375200169294832",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:4e8cd6081d2622e2214b4bbd0cdf5e2ba5406635",
"swarming_tag:builder:Windows deterministic",
"swarming_tag:buildset:commit/git/40ff4bde48062ed7342580ccaf95f36a71461141",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/40ff4bde48062ed7342580ccaf95f36a71461141",
"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/8941375200169294832/+/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:9106805262532726448",
"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:e141d0ff36040c8de44bf7c9c5bc8e9e7c30eefc",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:3e9daa1190a9a110",
"user_agent:luci-scheduler"
],
"updated_ts": "1531237141265500",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/9459",
"utcnow_ts": "1531237141359060"
}
}
[15:39:01.371] Task URL: https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/9459
[15:39:01.371] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:2:0) after 1m0s
[15:40:01.389] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:2:0)
[15:40:01.389] Timer tick, asking Buildbucket for the build status
[15:40:01.538] Build 8941375200169294832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:40:01.538] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:3:0) after 1m0s
[15:40:33.701] Received PubSub notification, asking Buildbucket for the build status
[15:40:33.822] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:01.616] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:3:0)
[15:41:01.616] Timer tick, asking Buildbucket for the build status
[15:41:01.742] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:01.742] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:5:0) after 1m0s
[15:42:01.762] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:5:0)
[15:42:01.762] Timer tick, asking Buildbucket for the build status
[15:42:01.806] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:01.806] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:6:0) after 1m0s
[15:43:01.824] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:6:0)
[15:43:01.824] Timer tick, asking Buildbucket for the build status
[15:43:02.347] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:02.347] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:7:0) after 1m0s
[15:44:02.377] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:7:0)
[15:44:02.377] Timer tick, asking Buildbucket for the build status
[15:44:02.413] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:02.413] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:8:0) after 1m0s
[15:45:02.451] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:8:0)
[15:45:02.451] Timer tick, asking Buildbucket for the build status
[15:45:02.510] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:02.510] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:9:0) after 1m0s
[15:46:02.531] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:9:0)
[15:46:02.531] Timer tick, asking Buildbucket for the build status
[15:46:02.806] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:02.806] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:10:0) after 1m0s
[15:47:02.828] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:10:0)
[15:47:02.828] Timer tick, asking Buildbucket for the build status
[15:47:08.613] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:08.613] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:11:0) after 1m0s
[15:48:08.635] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:11:0)
[15:48:08.635] Timer tick, asking Buildbucket for the build status
[15:48:08.795] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:08.795] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:12:0) after 1m0s
[15:49:08.822] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:12:0)
[15:49:08.822] Timer tick, asking Buildbucket for the build status
[15:49:08.887] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:08.887] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:13:0) after 1m0s
[15:50:08.917] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:13:0)
[15:50:08.917] Timer tick, asking Buildbucket for the build status
[15:50:09.026] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:09.026] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:14:0) after 1m0s
[15:51:09.044] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:14:0)
[15:51:09.044] Timer tick, asking Buildbucket for the build status
[15:51:09.083] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:09.083] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:15:0) after 1m0s
[15:52:09.101] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:15:0)
[15:52:09.101] Timer tick, asking Buildbucket for the build status
[15:52:09.141] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:09.141] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:16:0) after 1m0s
[15:53:09.175] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:16:0)
[15:53:09.175] Timer tick, asking Buildbucket for the build status
[15:53:09.729] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:09.730] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:17:0) after 1m0s
[15:54:09.883] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:17:0)
[15:54:09.883] Timer tick, asking Buildbucket for the build status
[15:54:09.917] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:09.917] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:18:0) after 1m0s
[15:55:09.954] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:18:0)
[15:55:09.954] Timer tick, asking Buildbucket for the build status
[15:55:10.212] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:10.212] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:19:0) after 1m0s
[15:56:10.230] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:19:0)
[15:56:10.230] Timer tick, asking Buildbucket for the build status
[15:56:10.554] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:10.554] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:20:0) after 1m0s
[15:57:10.574] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:20:0)
[15:57:10.574] Timer tick, asking Buildbucket for the build status
[15:57:10.642] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:10.642] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:21:0) after 1m0s
[15:58:10.699] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:21:0)
[15:58:10.699] Timer tick, asking Buildbucket for the build status
[15:58:10.781] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:10.781] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:22:0) after 1m0s
[15:59:10.802] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:22:0)
[15:59:10.802] Timer tick, asking Buildbucket for the build status
[15:59:10.951] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:10.951] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:23:0) after 1m0s
[16:00:10.974] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:23:0)
[16:00:10.974] Timer tick, asking Buildbucket for the build status
[16:00:11.108] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:11.108] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:24:0) after 1m0s
[16:01:11.129] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:24:0)
[16:01:11.129] Timer tick, asking Buildbucket for the build status
[16:01:11.388] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:11.388] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:25:0) after 1m0s
[16:02:11.413] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:25:0)
[16:02:11.413] Timer tick, asking Buildbucket for the build status
[16:02:11.466] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:11.466] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:26:0) after 1m0s
[16:03:11.489] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:26:0)
[16:03:11.489] Timer tick, asking Buildbucket for the build status
[16:03:11.561] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:11.561] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:27:0) after 1m0s
[16:04:11.580] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:27:0)
[16:04:11.580] Timer tick, asking Buildbucket for the build status
[16:04:11.670] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:11.670] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:28:0) after 1m0s
[16:05:11.714] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:28:0)
[16:05:11.714] Timer tick, asking Buildbucket for the build status
[16:05:11.814] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:11.814] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:29:0) after 1m0s
[16:06:11.833] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:29:0)
[16:06:11.833] Timer tick, asking Buildbucket for the build status
[16:06:11.876] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:11.876] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:30:0) after 1m0s
[16:07:11.896] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:30:0)
[16:07:11.896] Timer tick, asking Buildbucket for the build status
[16:07:11.980] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:11.980] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:31:0) after 1m0s
[16:08:12.137] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:31:0)
[16:08:12.137] Timer tick, asking Buildbucket for the build status
[16:08:12.177] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:12.177] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:32:0) after 1m0s
[16:09:12.230] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:32:0)
[16:09:12.230] Timer tick, asking Buildbucket for the build status
[16:09:12.311] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:12.311] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:33:0) after 1m0s
[16:10:12.331] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:33:0)
[16:10:12.331] Timer tick, asking Buildbucket for the build status
[16:10:12.493] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:12.493] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:34:0) after 1m0s
[16:11:12.513] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:34:0)
[16:11:12.513] Timer tick, asking Buildbucket for the build status
[16:11:12.605] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:12.605] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:35:0) after 1m0s
[16:12:12.626] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:35:0)
[16:12:12.626] Timer tick, asking Buildbucket for the build status
[16:12:13.482] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:13.482] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:36:0) after 1m0s
[16:13:13.504] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:36:0)
[16:13:13.504] Timer tick, asking Buildbucket for the build status
[16:13:13.554] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:13.555] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:37:0) after 1m0s
[16:14:13.572] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:37:0)
[16:14:13.572] Timer tick, asking Buildbucket for the build status
[16:14:13.628] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:13.628] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:38:0) after 1m0s
[16:15:13.647] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:38:0)
[16:15:13.647] Timer tick, asking Buildbucket for the build status
[16:15:13.764] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:13.764] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:39:0) after 1m0s
[16:16:13.794] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:39:0)
[16:16:13.794] Timer tick, asking Buildbucket for the build status
[16:16:13.867] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:13.867] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:40:0) after 1m0s
[16:17:13.886] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:40:0)
[16:17:13.886] Timer tick, asking Buildbucket for the build status
[16:17:13.985] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:13.986] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:41:0) after 1m0s
[16:18:14.005] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:41:0)
[16:18:14.005] Timer tick, asking Buildbucket for the build status
[16:18:14.135] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:14.135] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:42:0) after 1m0s
[16:19:14.156] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:42:0)
[16:19:14.156] Timer tick, asking Buildbucket for the build status
[16:19:14.190] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:14.190] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:43:0) after 1m0s
[16:20:14.209] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:43:0)
[16:20:14.209] Timer tick, asking Buildbucket for the build status
[16:20:14.258] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:14.258] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:44:0) after 1m0s
[16:21:14.278] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:44:0)
[16:21:14.278] Timer tick, asking Buildbucket for the build status
[16:21:14.328] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:14.328] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:45:0) after 1m0s
[16:22:14.347] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:45:0)
[16:22:14.347] Timer tick, asking Buildbucket for the build status
[16:22:14.381] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:14.381] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:46:0) after 1m0s
[16:23:14.448] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:46:0)
[16:23:14.448] Timer tick, asking Buildbucket for the build status
[16:23:14.495] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:14.495] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:47:0) after 1m0s
[16:24:14.514] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:47:0)
[16:24:14.514] Timer tick, asking Buildbucket for the build status
[16:24:14.549] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:14.549] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:48:0) after 1m0s
[16:25:14.576] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:48:0)
[16:25:14.576] Timer tick, asking Buildbucket for the build status
[16:25:14.613] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:14.613] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:49:0) after 1m0s
[16:26:14.631] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:49:0)
[16:26:14.631] Timer tick, asking Buildbucket for the build status
[16:26:14.673] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:14.673] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:50:0) after 1m0s
[16:27:14.695] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:50:0)
[16:27:14.695] Timer tick, asking Buildbucket for the build status
[16:27:14.750] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:14.750] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:51:0) after 1m0s
[16:28:14.770] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:51:0)
[16:28:14.770] Timer tick, asking Buildbucket for the build status
[16:28:14.881] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:14.881] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:52:0) after 1m0s
[16:29:14.912] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:52:0)
[16:29:14.912] Timer tick, asking Buildbucket for the build status
[16:29:15.062] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:15.062] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:53:0) after 1m0s
[16:30:15.144] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:53:0)
[16:30:15.144] Timer tick, asking Buildbucket for the build status
[16:30:15.253] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:15.253] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:54:0) after 1m0s
[16:31:15.274] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:54:0)
[16:31:15.274] Timer tick, asking Buildbucket for the build status
[16:31:15.376] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:15.376] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:55:0) after 1m0s
[16:32:15.412] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:55:0)
[16:32:15.412] Timer tick, asking Buildbucket for the build status
[16:32:15.507] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:15.507] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:56:0) after 1m0s
[16:33:15.541] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:56:0)
[16:33:15.541] Timer tick, asking Buildbucket for the build status
[16:33:15.584] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:15.584] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:57:0) after 1m0s
[16:34:15.605] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:57:0)
[16:34:15.605] Timer tick, asking Buildbucket for the build status
[16:34:15.763] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:15.763] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:58:0) after 1m0s
[16:35:15.783] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:58:0)
[16:35:15.783] Timer tick, asking Buildbucket for the build status
[16:35:15.867] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:15.867] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:59:0) after 1m0s
[16:36:15.886] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:59:0)
[16:36:15.886] Timer tick, asking Buildbucket for the build status
[16:36:16.328] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:16.328] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:60:0) after 1m0s
[16:37:16.354] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:60:0)
[16:37:16.354] Timer tick, asking Buildbucket for the build status
[16:37:16.396] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:16.396] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:61:0) after 1m0s
[16:38:16.422] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:61:0)
[16:38:16.422] Timer tick, asking Buildbucket for the build status
[16:38:16.483] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:16.483] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:62:0) after 1m0s
[16:39:16.587] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:62:0)
[16:39:16.587] Timer tick, asking Buildbucket for the build status
[16:39:16.738] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:16.738] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:63:0) after 1m0s
[16:40:16.759] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:63:0)
[16:40:16.759] Timer tick, asking Buildbucket for the build status
[16:40:16.817] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:16.817] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:64:0) after 1m0s
[16:41:16.838] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:64:0)
[16:41:16.838] Timer tick, asking Buildbucket for the build status
[16:41:16.944] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:16.944] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:65:0) after 1m0s
[16:42:16.965] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:65:0)
[16:42:16.965] Timer tick, asking Buildbucket for the build status
[16:42:17.108] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:17.108] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:66:0) after 1m0s
[16:43:17.129] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:66:0)
[16:43:17.129] Timer tick, asking Buildbucket for the build status
[16:43:17.187] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:17.187] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:67:0) after 1m0s
[16:44:17.211] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:67:0)
[16:44:17.211] Timer tick, asking Buildbucket for the build status
[16:44:17.382] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:17.382] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:68:0) after 1m0s
[16:45:17.406] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:68:0)
[16:45:17.406] Timer tick, asking Buildbucket for the build status
[16:45:17.489] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:17.489] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:69:0) after 1m0s
[16:46:17.512] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:69:0)
[16:46:17.512] Timer tick, asking Buildbucket for the build status
[16:46:17.545] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:17.545] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:70:0) after 1m0s
[16:47:17.577] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:70:0)
[16:47:17.577] Timer tick, asking Buildbucket for the build status
[16:47:17.614] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:17.614] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:71:0) after 1m0s
[16:48:17.834] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:71:0)
[16:48:17.834] Timer tick, asking Buildbucket for the build status
[16:48:17.994] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:17.994] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:72:0) after 1m0s
[16:49:18.034] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:72:0)
[16:49:18.034] Timer tick, asking Buildbucket for the build status
[16:49:18.110] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:18.110] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:73:0) after 1m0s
[16:50:18.174] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:73:0)
[16:50:18.174] Timer tick, asking Buildbucket for the build status
[16:50:18.210] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:18.210] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:74:0) after 1m0s
[16:51:18.303] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:74:0)
[16:51:18.303] Timer tick, asking Buildbucket for the build status
[16:51:18.504] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:18.504] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:75:0) after 1m0s
[16:52:18.595] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:75:0)
[16:52:18.595] Timer tick, asking Buildbucket for the build status
[16:52:18.735] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:18.735] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:76:0) after 1m0s
[16:53:18.755] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:76:0)
[16:53:18.755] Timer tick, asking Buildbucket for the build status
[16:53:18.845] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:18.845] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:77:0) after 1m0s
[16:54:18.866] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:77:0)
[16:54:18.866] Timer tick, asking Buildbucket for the build status
[16:54:18.972] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:18.972] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:78:0) after 1m0s
[16:55:18.994] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:78:0)
[16:55:18.994] Timer tick, asking Buildbucket for the build status
[16:55:19.069] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:19.069] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:79:0) after 1m0s
[16:56:19.088] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:79:0)
[16:56:19.088] Timer tick, asking Buildbucket for the build status
[16:56:19.130] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:19.130] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:80:0) after 1m0s
[16:57:19.151] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:80:0)
[16:57:19.151] Timer tick, asking Buildbucket for the build status
[16:57:19.323] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:19.323] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:81:0) after 1m0s
[16:58:19.344] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:81:0)
[16:58:19.344] Timer tick, asking Buildbucket for the build status
[16:58:19.390] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:19.390] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:82:0) after 1m0s
[16:59:19.420] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:82:0)
[16:59:19.420] Timer tick, asking Buildbucket for the build status
[16:59:19.469] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:19.469] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:83:0) after 1m0s
[17:00:19.575] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:83:0)
[17:00:19.575] Timer tick, asking Buildbucket for the build status
[17:00:19.662] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:19.662] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:84:0) after 1m0s
[17:01:19.689] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:84:0)
[17:01:19.690] Timer tick, asking Buildbucket for the build status
[17:01:19.775] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:19.775] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:85:0) after 1m0s
[17:02:19.803] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:85:0)
[17:02:19.803] Timer tick, asking Buildbucket for the build status
[17:02:19.894] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:19.894] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:86:0) after 1m0s
[17:03:19.924] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:86:0)
[17:03:19.924] Timer tick, asking Buildbucket for the build status
[17:03:20.428] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:20.428] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:87:0) after 1m0s
[17:04:20.451] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:87:0)
[17:04:20.451] Timer tick, asking Buildbucket for the build status
[17:04:20.496] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:20.496] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:88:0) after 1m0s
[17:05:20.518] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:88:0)
[17:05:20.518] Timer tick, asking Buildbucket for the build status
[17:05:29.535] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:29.535] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:89:0) after 1m0s
[17:06:29.574] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:89:0)
[17:06:29.574] Timer tick, asking Buildbucket for the build status
[17:06:29.614] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:29.614] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:90:0) after 1m0s
[17:07:29.647] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:90:0)
[17:07:29.647] Timer tick, asking Buildbucket for the build status
[17:07:29.711] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:29.711] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:91:0) after 1m0s
[17:08:29.737] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:91:0)
[17:08:29.737] Timer tick, asking Buildbucket for the build status
[17:08:29.815] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:29.815] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:92:0) after 1m0s
[17:09:29.857] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:92:0)
[17:09:29.857] Timer tick, asking Buildbucket for the build status
[17:09:29.964] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:29.964] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:93:0) after 1m0s
[17:10:29.998] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:93:0)
[17:10:29.998] Timer tick, asking Buildbucket for the build status
[17:10:30.080] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:30.080] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:94:0) after 1m0s
[17:11:30.149] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:94:0)
[17:11:30.149] Timer tick, asking Buildbucket for the build status
[17:11:30.304] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:30.304] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:95:0) after 1m0s
[17:12:30.329] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:95:0)
[17:12:30.329] Timer tick, asking Buildbucket for the build status
[17:12:30.367] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:30.368] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:96:0) after 1m0s
[17:13:30.442] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:96:0)
[17:13:30.442] Timer tick, asking Buildbucket for the build status
[17:13:30.534] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:30.534] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:97:0) after 1m0s
[17:14:30.558] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:97:0)
[17:14:30.558] Timer tick, asking Buildbucket for the build status
[17:14:30.668] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:30.668] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:98:0) after 1m0s
[17:15:30.834] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:98:0)
[17:15:30.834] Timer tick, asking Buildbucket for the build status
[17:15:30.955] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:30.955] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:99:0) after 1m0s
[17:16:30.980] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:99:0)
[17:16:30.980] Timer tick, asking Buildbucket for the build status
[17:16:31.050] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:31.050] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:100:0) after 1m0s
[17:17:31.114] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:100:0)
[17:17:31.114] Timer tick, asking Buildbucket for the build status
[17:17:31.160] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:31.160] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:101:0) after 1m0s
[17:18:31.232] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:101:0)
[17:18:31.232] Timer tick, asking Buildbucket for the build status
[17:18:31.297] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:31.297] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:102:0) after 1m0s
[17:19:31.319] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:102:0)
[17:19:31.319] Timer tick, asking Buildbucket for the build status
[17:19:31.348] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:31.348] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:103:0) after 1m0s
[17:20:31.383] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:103:0)
[17:20:31.383] Timer tick, asking Buildbucket for the build status
[17:20:31.484] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:31.484] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:104:0) after 1m0s
[17:21:31.507] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:104:0)
[17:21:31.507] Timer tick, asking Buildbucket for the build status
[17:21:31.736] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:31.736] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:105:0) after 1m0s
[17:22:31.762] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:105:0)
[17:22:31.762] Timer tick, asking Buildbucket for the build status
[17:22:31.891] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:31.891] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:106:0) after 1m0s
[17:23:32.403] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:106:0)
[17:23:32.403] Timer tick, asking Buildbucket for the build status
[17:23:32.482] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:32.482] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:107:0) after 1m0s
[17:24:32.505] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:107:0)
[17:24:32.505] Timer tick, asking Buildbucket for the build status
[17:24:32.575] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:32.575] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:108:0) after 1m0s
[17:25:32.617] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:108:0)
[17:25:32.617] Timer tick, asking Buildbucket for the build status
[17:25:32.651] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:32.651] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:109:0) after 1m0s
[17:26:32.674] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:109:0)
[17:26:32.674] Timer tick, asking Buildbucket for the build status
[17:26:32.708] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:32.708] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:110:0) after 1m0s
[17:27:32.732] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:110:0)
[17:27:32.732] Timer tick, asking Buildbucket for the build status
[17:27:32.802] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:32.802] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:111:0) after 1m0s
[17:28:32.827] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:111:0)
[17:28:32.827] Timer tick, asking Buildbucket for the build status
[17:28:33.053] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:33.053] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:112:0) after 1m0s
[17:29:33.079] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:112:0)
[17:29:33.079] Timer tick, asking Buildbucket for the build status
[17:29:33.133] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:33.133] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:113:0) after 1m0s
[17:30:33.237] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:113:0)
[17:30:33.237] Timer tick, asking Buildbucket for the build status
[17:30:33.352] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:33.352] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:114:0) after 1m0s
[17:31:33.529] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:114:0)
[17:31:33.529] Timer tick, asking Buildbucket for the build status
[17:31:33.586] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:33.586] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:115:0) after 1m0s
[17:32:33.619] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:115:0)
[17:32:33.619] Timer tick, asking Buildbucket for the build status
[17:32:33.691] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:33.691] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:116:0) after 1m0s
[17:33:33.726] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:116:0)
[17:33:33.726] Timer tick, asking Buildbucket for the build status
[17:33:33.790] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:33.790] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:117:0) after 1m0s
[17:34:33.865] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:117:0)
[17:34:33.865] Timer tick, asking Buildbucket for the build status
[17:34:34.069] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:34.069] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:118:0) after 1m0s
[17:35:34.144] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:118:0)
[17:35:34.144] Timer tick, asking Buildbucket for the build status
[17:35:34.245] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:34.245] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:119:0) after 1m0s
[17:36:34.269] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:119:0)
[17:36:34.269] Timer tick, asking Buildbucket for the build status
[17:36:34.307] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:34.307] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:120:0) after 1m0s
[17:37:34.329] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:120:0)
[17:37:34.329] Timer tick, asking Buildbucket for the build status
[17:37:34.412] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:34.412] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:121:0) after 1m0s
[17:38:34.553] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:121:0)
[17:38:34.553] Timer tick, asking Buildbucket for the build status
[17:38:34.658] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:34.658] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:122:0) after 1m0s
[17:39:34.681] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:122:0)
[17:39:34.681] Timer tick, asking Buildbucket for the build status
[17:39:34.833] Build 8941375200169294832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:34.833] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9106805262532726448:123:0) after 1m0s
[17:39:48.441] Received PubSub notification, asking Buildbucket for the build status
[17:39:48.565] Build 8941375200169294832: status "COMPLETED", result "FAILURE", failure_reason "INFRA_FAILURE", cancelation_reason ""
[17:39:48.565] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1531244386996990",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1531237140454650",
"failure_reason": "INFRA_FAILURE",
"id": "8941375200169294832",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"40ff4bde48062ed7342580ccaf95f36a71461141\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"ui\": {\"info\": \"Failure compare_build_artifacts\\nFailure Uncaught Exception\"}, \"build_run_result\": {\"infraFailure\": {\"text\": \"recipe infra failure: Uncaught Exception: AttributeError(\\\"'NoneType' object has no attribute 'itervalues'\\\",)\", \"bootstrapperCallStack\": [], \"type\": \"RECIPE_INFRA_FAILURE\"}, \"recipeResult\": {\"failure\": {\"exception\": {\"traceback\": [\"Traceback (most recent call last):\", \" File \\\"C:\\\\b\\\\swarming\\\\w\\\\ir\\\\kitchen-checkout\\\\recipe_engine\\\\recipe_engine\\\\run.py\\\", line 287, in _new_run\", \" recipe_result = recipe_script.run(api, self.properties, self.environ)\", \" File \\\"C:\\\\b\\\\swarming\\\\w\\\\ir\\\\kitchen-checkout\\\\recipe_engine\\\\recipe_engine\\\\loader.py\\\", line 108, in run\", \" self.run_steps, properties, environ, self.PROPERTIES, api=api)\", \" File \\\"C:\\\\b\\\\swarming\\\\w\\\\ir\\\\kitchen-checkout\\\\recipe_engine\\\\recipe_engine\\\\loader.py\\\", line 636, in invoke_with_properties\", \" arg_names, **additional_args)\", \" File \\\"C:\\\\b\\\\swarming\\\\w\\\\ir\\\\kitchen-checkout\\\\recipe_engine\\\\recipe_engine\\\\loader.py\\\", line 599, in _invoke_with_properties\", \" return callable_obj(*props, **additional_args)\", \" File \\\"C:\\\\b\\\\swarming\\\\w\\\\ir\\\\kitchen-checkout\\\\build\\\\scripts/slave\\\\recipes\\\\swarming/deterministic_build.py\\\", line 194, in RunSteps\", \" str(api.chromium.output_dir).rstrip('\\\\\\\\/') + '.2')\", \" File \\\"C:\\\\b\\\\swarming\\\\w\\\\ir\\\\kitchen-checkout\\\\recipe_engine\\\\recipe_engine\\\\recipe_api.py\\\", line 759, in _inner\", \" return func(*a, **kw)\", \" File \\\"C:\\\\b\\\\swarming\\\\w\\\\ir\\\\kitchen-checkout\\\\build\\\\scripts/slave\\\\recipe_modules\\\\isolate\\\\api.py\\\", line 350, in compare_build_artifacts\", \" self.archive_differences(first_dir, second_dir, step_result.json.output)\", \" File \\\"C:\\\\b\\\\swarming\\\\w\\\\ir\\\\kitchen-checkout\\\\recipe_engine\\\\recipe_engine\\\\recipe_api.py\\\", line 759, in _inner\", \" return func(*a, **kw)\", \" File \\\"C:\\\\b\\\\swarming\\\\w\\\\ir\\\\kitchen-checkout\\\\build\\\\scripts/slave\\\\recipe_modules\\\\isolate\\\\api.py\\\", line 302, in archive_differences\", \" diffs = list(itertools.chain.from_iterable(values.itervalues()))\", \"AttributeError: 'NoneType' object has no attribute 'itervalues'\"]}, \"humanReason\": \"Uncaught Exception: AttributeError(\\\"'NoneType' object has no attribute 'itervalues'\\\",)\"}}, \"recipeExitCode\": {\"value\": 1}, \"recipe\": {\"name\": \"swarming/deterministic_build\", \"repository\": \"\", \"revision\": \"\"}, \"annotationUrl\": \"logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8941375200169294832/+/annotations\"}, \"swarming\": {\"bot_dimensions\": {\"machine_type\": [\"n1-standard-8\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"], \"locale\": [\"en_US.cp1252\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"builder\": [\"Windows deterministic\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\"], \"python\": [\"2.7.6\"], \"server_version\": [\"3675-21ffa58\"], \"image\": [\"chrome-win10-1703-2018-04-20-61acaf8983f\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"integrity\": [\"high\"], \"id\": [\"swarm2173-c4\"], \"pool\": [\"luci.chromium.ci\"], \"caches\": [\"git\", \"goma_v2\", \"vpython\"]}}, \"properties\": {\"got_nacl_revision\": \"030b88a205c9ce9a82d68798bfaa059fd55216cd\", \"got_swarming_client_revision\": \"9a518d097dca20b7b00ce3bdfc5d418ccc79893a\", \"got_revision\": \"40ff4bde48062ed7342580ccaf95f36a71461141\", \"recipe\": \"swarming/deterministic_build\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#23896}\", \"$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@{#573744}\", \"branch\": \"refs/heads/master\", \"revision\": \"40ff4bde48062ed7342580ccaf95f36a71461141\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"ef43aafcf5953845b71840e14c94942d60c541c1\", \"mastername\": \"chromium.fyi\", \"got_angle_revision\": \"26c61b24e667bee1eb69ace84787ad62accf3f7a\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1531237140454650, \"tags\": [\"builder:Windows deterministic\", \"buildset:commit/git/40ff4bde48062ed7342580ccaf95f36a71461141\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/40ff4bde48062ed7342580ccaf95f36a71461141\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9106805262532726448\", \"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\": \"8941375200169294832\"}}, \"got_v8_revision\": \"3b7d3975e62a34aa37e5cb83d6b2785f4dd2a2d3\", \"got_v8_revision_cp\": \"refs/heads/6.9.333@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 9459, \"path_config\": \"generic\", \"bot_id\": \"swarm2173-c4\", \"got_buildtools_revision\": \"0dd5c6f980d22be96b728155249df2da355989d9\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1531237232671690",
"status": "COMPLETED",
"status_changed_ts": "1531244387868490",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/9459",
"builder:Windows deterministic",
"buildset:commit/git/40ff4bde48062ed7342580ccaf95f36a71461141",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/40ff4bde48062ed7342580ccaf95f36a71461141",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9106805262532726448",
"scheduler_job_id:chromium/Windows deterministic",
"swarming_dimension:builder:Windows deterministic",
"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/9459",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8941375200169294832",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:4e8cd6081d2622e2214b4bbd0cdf5e2ba5406635",
"swarming_tag:builder:Windows deterministic",
"swarming_tag:buildset:commit/git/40ff4bde48062ed7342580ccaf95f36a71461141",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/40ff4bde48062ed7342580ccaf95f36a71461141",
"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/8941375200169294832/+/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:9106805262532726448",
"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:e141d0ff36040c8de44bf7c9c5bc8e9e7c30eefc",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:3e9daa1190a9a110",
"user_agent:luci-scheduler"
],
"updated_ts": "1531244387870400",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/9459",
"utcnow_ts": "1531244388485850"
}
}
[17:39:48.565] Invocation finished in 2h0m49.748399969s with status FAILED