[16:52:40.134] New invocation is queued and will start shortly
[16:52:41.275] Starting the invocation (attempt 1)
[16:52:41.329] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:52:41.330] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:52:41.330] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9083879593965562688",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"a9a53534a9812550c40555ae775490ccfa6f79e2\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9083879593965562688",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/a9a53534a9812550c40555ae775490ccfa6f79e2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a9a53534a9812550c40555ae775490ccfa6f79e2",
"gitiles_ref:refs/heads/master"
]
}
[16:52:43.528] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "project:chromium",
"created_ts": "1553100761439214",
"id": "8918449531932029152",
"lease_expiration_ts": "1553219562186893",
"lease_key": "1014814414",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"a9a53534a9812550c40555ae775490ccfa6f79e2\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1553100762398514",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/12507",
"builder:Windows deterministic",
"buildset:commit/git/a9a53534a9812550c40555ae775490ccfa6f79e2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a9a53534a9812550c40555ae775490ccfa6f79e2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9083879593965562688",
"scheduler_job_id:chromium/Windows deterministic",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8918449531932029152/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:recipe_name:swarming/deterministic_build",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:43b4d67332146310",
"user_agent:luci-scheduler"
],
"updated_ts": "1553100762398620",
"url": "https://ci.chromium.org/b/8918449531932029152",
"utcnow_ts": "1553100763516830"
}
}
[16:52:43.528] Task URL: https://ci.chromium.org/b/8918449531932029152
[16:52:43.528] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:2:0) after 1m0s
[16:53:43.547] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:2:0)
[16:53:43.547] Timer tick, asking Buildbucket for the build status
[16:53:43.703] Build 8918449531932029152: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:53:43.703] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:3:0) after 1m0s
[16:54:43.724] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:3:0)
[16:54:43.724] Timer tick, asking Buildbucket for the build status
[16:54:44.422] Build 8918449531932029152: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:54:44.422] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:4:0) after 1m0s
[16:54:46.351] Received PubSub notification, asking Buildbucket for the build status
[16:54:46.451] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:44.435] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:4:0)
[16:55:44.435] Timer tick, asking Buildbucket for the build status
[16:55:45.272] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:45.272] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:6:0) after 1m0s
[16:56:45.294] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:6:0)
[16:56:45.294] Timer tick, asking Buildbucket for the build status
[16:56:45.715] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:45.715] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:7:0) after 1m0s
[16:57:45.722] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:7:0)
[16:57:45.722] Timer tick, asking Buildbucket for the build status
[16:57:45.884] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:45.884] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:8:0) after 1m0s
[16:58:45.886] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:8:0)
[16:58:45.886] Timer tick, asking Buildbucket for the build status
[16:58:46.050] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:46.050] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:9:0) after 1m0s
[16:59:46.070] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:9:0)
[16:59:46.070] Timer tick, asking Buildbucket for the build status
[16:59:46.446] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:46.446] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:10:0) after 1m0s
[17:00:46.429] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:10:0)
[17:00:46.429] Timer tick, asking Buildbucket for the build status
[17:00:46.615] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:46.615] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:11:0) after 1m0s
[17:01:46.627] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:11:0)
[17:01:46.627] Timer tick, asking Buildbucket for the build status
[17:01:46.775] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:46.775] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:12:0) after 1m0s
[17:02:46.802] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:12:0)
[17:02:46.802] Timer tick, asking Buildbucket for the build status
[17:02:47.471] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:47.471] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:13:0) after 1m0s
[17:03:47.496] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:13:0)
[17:03:47.496] Timer tick, asking Buildbucket for the build status
[17:03:47.633] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:47.634] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:14:0) after 1m0s
[17:04:47.653] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:14:0)
[17:04:47.653] Timer tick, asking Buildbucket for the build status
[17:04:47.796] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:47.796] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:15:0) after 1m0s
[17:05:47.819] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:15:0)
[17:05:47.819] Timer tick, asking Buildbucket for the build status
[17:05:47.914] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:47.914] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:16:0) after 1m0s
[17:06:47.934] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:16:0)
[17:06:47.934] Timer tick, asking Buildbucket for the build status
[17:06:48.056] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:48.056] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:17:0) after 1m0s
[17:07:48.087] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:17:0)
[17:07:48.087] Timer tick, asking Buildbucket for the build status
[17:07:48.854] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:48.854] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:18:0) after 1m0s
[17:08:48.872] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:18:0)
[17:08:48.872] Timer tick, asking Buildbucket for the build status
[17:08:48.997] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:48.997] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:19:0) after 1m0s
[17:09:48.989] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:19:0)
[17:09:48.989] Timer tick, asking Buildbucket for the build status
[17:09:49.153] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:49.153] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:20:0) after 1m0s
[17:10:49.135] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:20:0)
[17:10:49.135] Timer tick, asking Buildbucket for the build status
[17:10:50.094] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:50.094] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:21:0) after 1m0s
[17:11:50.114] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:21:0)
[17:11:50.114] Timer tick, asking Buildbucket for the build status
[17:11:50.254] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:50.254] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:22:0) after 1m0s
[17:12:50.260] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:22:0)
[17:12:50.260] Timer tick, asking Buildbucket for the build status
[17:12:50.464] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:50.464] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:23:0) after 1m0s
[17:13:50.483] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:23:0)
[17:13:50.483] Timer tick, asking Buildbucket for the build status
[17:13:50.994] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:50.994] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:24:0) after 1m0s
[17:14:51.035] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:24:0)
[17:14:51.035] Timer tick, asking Buildbucket for the build status
[17:14:51.135] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:51.135] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:25:0) after 1m0s
[17:15:51.163] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:25:0)
[17:15:51.163] Timer tick, asking Buildbucket for the build status
[17:15:51.871] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:51.871] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:26:0) after 1m0s
[17:16:51.903] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:26:0)
[17:16:51.903] Timer tick, asking Buildbucket for the build status
[17:16:52.062] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:52.062] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:27:0) after 1m0s
[17:17:52.196] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:27:0)
[17:17:52.196] Timer tick, asking Buildbucket for the build status
[17:17:52.980] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:52.980] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:28:0) after 1m0s
[17:18:53.006] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:28:0)
[17:18:53.006] Timer tick, asking Buildbucket for the build status
[17:18:53.152] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:53.152] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:29:0) after 1m0s
[17:19:53.217] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:29:0)
[17:19:53.217] Timer tick, asking Buildbucket for the build status
[17:19:54.488] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:54.488] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:30:0) after 1m0s
[17:20:54.527] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:30:0)
[17:20:54.527] Timer tick, asking Buildbucket for the build status
[17:20:54.611] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:54.611] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:31:0) after 1m0s
[17:21:54.605] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:31:0)
[17:21:54.605] Timer tick, asking Buildbucket for the build status
[17:21:54.993] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:54.993] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:32:0) after 1m0s
[17:22:54.980] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:32:0)
[17:22:54.980] Timer tick, asking Buildbucket for the build status
[17:22:55.074] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:55.074] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:33:0) after 1m0s
[17:23:55.104] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:33:0)
[17:23:55.104] Timer tick, asking Buildbucket for the build status
[17:23:55.361] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:55.361] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:34:0) after 1m0s
[17:24:55.387] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:34:0)
[17:24:55.387] Timer tick, asking Buildbucket for the build status
[17:24:55.889] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:55.889] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:35:0) after 1m0s
[17:25:55.936] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:35:0)
[17:25:55.936] Timer tick, asking Buildbucket for the build status
[17:25:56.389] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:56.389] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:36:0) after 1m0s
[17:26:56.392] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:36:0)
[17:26:56.392] Timer tick, asking Buildbucket for the build status
[17:26:56.504] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:56.504] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:37:0) after 1m0s
[17:27:56.529] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:37:0)
[17:27:56.529] Timer tick, asking Buildbucket for the build status
[17:27:56.648] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:56.648] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:38:0) after 1m0s
[17:28:56.686] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:38:0)
[17:28:56.686] Timer tick, asking Buildbucket for the build status
[17:28:56.786] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:56.786] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:39:0) after 1m0s
[17:29:56.829] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:39:0)
[17:29:56.829] Timer tick, asking Buildbucket for the build status
[17:29:57.088] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:57.088] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:40:0) after 1m0s
[17:30:57.093] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:40:0)
[17:30:57.093] Timer tick, asking Buildbucket for the build status
[17:30:57.907] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:57.907] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:41:0) after 1m0s
[17:31:57.928] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:41:0)
[17:31:57.928] Timer tick, asking Buildbucket for the build status
[17:31:58.770] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:58.770] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:42:0) after 1m0s
[17:32:58.795] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:42:0)
[17:32:58.795] Timer tick, asking Buildbucket for the build status
[17:32:58.920] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:58.920] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:43:0) after 1m0s
[17:33:58.939] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:43:0)
[17:33:58.939] Timer tick, asking Buildbucket for the build status
[17:33:59.108] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:59.108] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:44:0) after 1m0s
[17:34:59.135] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:44:0)
[17:34:59.135] Timer tick, asking Buildbucket for the build status
[17:34:59.297] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:59.297] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:45:0) after 1m0s
[17:35:59.384] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:45:0)
[17:35:59.384] Timer tick, asking Buildbucket for the build status
[17:35:59.510] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:59.510] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:46:0) after 1m0s
[17:36:59.565] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:46:0)
[17:36:59.565] Timer tick, asking Buildbucket for the build status
[17:36:59.972] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:59.972] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:47:0) after 1m0s
[17:37:59.979] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:47:0)
[17:37:59.979] Timer tick, asking Buildbucket for the build status
[17:38:00.369] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:00.369] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:48:0) after 1m0s
[17:39:00.348] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:48:0)
[17:39:00.348] Timer tick, asking Buildbucket for the build status
[17:39:00.696] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:00.696] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:49:0) after 1m0s
[17:40:00.713] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:49:0)
[17:40:00.713] Timer tick, asking Buildbucket for the build status
[17:40:01.539] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:01.539] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:50:0) after 1m0s
[17:41:01.561] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:50:0)
[17:41:01.562] Timer tick, asking Buildbucket for the build status
[17:41:02.219] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:02.219] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:51:0) after 1m0s
[17:42:02.228] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:51:0)
[17:42:02.228] Timer tick, asking Buildbucket for the build status
[17:42:02.340] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:02.340] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:52:0) after 1m0s
[17:43:02.361] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:52:0)
[17:43:02.361] Timer tick, asking Buildbucket for the build status
[17:43:02.469] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:02.469] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:53:0) after 1m0s
[17:44:02.505] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:53:0)
[17:44:02.505] Timer tick, asking Buildbucket for the build status
[17:44:02.682] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:02.682] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:54:0) after 1m0s
[17:45:03.200] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:54:0)
[17:45:03.200] Timer tick, asking Buildbucket for the build status
[17:45:03.568] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:03.568] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:55:0) after 1m0s
[17:46:25.758] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:55:0)
[17:46:25.758] Timer tick, asking Buildbucket for the build status
[17:46:26.627] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:26.627] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:56:0) after 1m0s
[17:55:30.119] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:56:0)
[17:55:30.119] Timer tick, asking Buildbucket for the build status
[17:55:30.575] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:30.575] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:57:0) after 1m0s
[17:56:31.614] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:57:0)
[17:56:31.614] Timer tick, asking Buildbucket for the build status
[17:56:32.441] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:32.441] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:58:0) after 1m0s
[17:59:19.382] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:58:0)
[17:59:19.382] Timer tick, asking Buildbucket for the build status
[17:59:19.650] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:19.650] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:59:0) after 1m0s
[18:00:19.960] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:59:0)
[18:00:19.960] Timer tick, asking Buildbucket for the build status
[18:00:20.299] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:20.299] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:60:0) after 1m0s
[18:01:20.332] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:60:0)
[18:01:20.332] Timer tick, asking Buildbucket for the build status
[18:01:20.520] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:20.520] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:61:0) after 1m0s
[18:02:20.556] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:61:0)
[18:02:20.556] Timer tick, asking Buildbucket for the build status
[18:02:21.215] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:21.215] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:62:0) after 1m0s
[18:03:28.272] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:62:0)
[18:03:28.272] Timer tick, asking Buildbucket for the build status
[18:03:28.465] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:28.465] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:63:0) after 1m0s
[18:04:34.766] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:63:0)
[18:04:34.766] Timer tick, asking Buildbucket for the build status
[18:04:34.938] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:34.938] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:64:0) after 1m0s
[18:05:35.104] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:64:0)
[18:05:35.104] Timer tick, asking Buildbucket for the build status
[18:05:35.450] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:35.450] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:65:0) after 1m0s
[18:06:35.534] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:65:0)
[18:06:35.534] Timer tick, asking Buildbucket for the build status
[18:06:35.713] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:35.713] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:66:0) after 1m0s
[18:07:36.461] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:66:0)
[18:07:36.461] Timer tick, asking Buildbucket for the build status
[18:07:37.225] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:37.225] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:67:0) after 1m0s
[18:08:37.966] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:67:0)
[18:08:37.967] Timer tick, asking Buildbucket for the build status
[18:08:38.147] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:38.147] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:68:0) after 1m0s
[18:09:38.336] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:68:0)
[18:09:38.336] Timer tick, asking Buildbucket for the build status
[18:09:39.212] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:39.212] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:69:0) after 1m0s
[18:10:51.954] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:69:0)
[18:10:51.954] Timer tick, asking Buildbucket for the build status
[18:10:52.217] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:52.217] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:70:0) after 1m0s
[18:11:52.510] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:70:0)
[18:11:52.510] Timer tick, asking Buildbucket for the build status
[18:11:52.697] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:52.697] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:71:0) after 1m0s
[18:12:59.092] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:71:0)
[18:12:59.092] Timer tick, asking Buildbucket for the build status
[18:12:59.351] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:59.351] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:72:0) after 1m0s
[18:13:59.649] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:72:0)
[18:13:59.649] Timer tick, asking Buildbucket for the build status
[18:14:00.321] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:00.321] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:73:0) after 1m0s
[18:15:00.347] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:73:0)
[18:15:00.347] Timer tick, asking Buildbucket for the build status
[18:15:00.893] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:00.893] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:74:0) after 1m0s
[18:16:01.687] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:74:0)
[18:16:01.687] Timer tick, asking Buildbucket for the build status
[18:16:02.037] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:02.037] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:75:0) after 1m0s
[18:17:02.061] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:75:0)
[18:17:02.061] Timer tick, asking Buildbucket for the build status
[18:17:02.257] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:02.257] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:76:0) after 1m0s
[18:45:20.580] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:76:0)
[18:45:20.580] Timer tick, asking Buildbucket for the build status
[18:45:20.703] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:20.703] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:77:0) after 1m0s
[18:46:20.721] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:77:0)
[18:46:20.721] Timer tick, asking Buildbucket for the build status
[18:46:21.046] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:21.046] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:78:0) after 1m0s
[18:47:21.067] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:78:0)
[18:47:21.068] Timer tick, asking Buildbucket for the build status
[18:47:22.073] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:22.073] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:79:0) after 1m0s
[18:48:22.077] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:79:0)
[18:48:22.077] Timer tick, asking Buildbucket for the build status
[18:48:22.499] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:22.499] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:80:0) after 1m0s
[18:49:22.492] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:80:0)
[18:49:22.492] Timer tick, asking Buildbucket for the build status
[18:49:23.181] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:23.182] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:81:0) after 1m0s
[18:50:23.205] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:81:0)
[18:50:23.205] Timer tick, asking Buildbucket for the build status
[18:50:23.489] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:23.489] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:82:0) after 1m0s
[18:51:23.536] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:82:0)
[18:51:23.536] Timer tick, asking Buildbucket for the build status
[18:51:23.923] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:23.923] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:83:0) after 1m0s
[18:52:23.944] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:83:0)
[18:52:23.944] Timer tick, asking Buildbucket for the build status
[18:52:25.336] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:25.336] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:84:0) after 1m0s
[18:53:25.328] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:84:0)
[18:53:25.328] Timer tick, asking Buildbucket for the build status
[18:53:25.427] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:25.427] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:85:0) after 1m0s
[18:54:26.611] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:85:0)
[18:54:26.611] Timer tick, asking Buildbucket for the build status
[18:54:26.734] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:26.734] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:86:0) after 1m0s
[18:55:26.885] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:86:0)
[18:55:26.885] Timer tick, asking Buildbucket for the build status
[18:55:27.193] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:27.193] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:87:0) after 1m0s
[18:56:27.220] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:87:0)
[18:56:27.220] Timer tick, asking Buildbucket for the build status
[18:56:27.414] Build 8918449531932029152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:27.414] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9083879593965562688:88:0) after 1m0s
[18:57:15.462] Received PubSub notification, asking Buildbucket for the build status
[18:57:15.580] Build 8918449531932029152: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[18:57:15.580] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1553108234425118",
"created_by": "project:chromium",
"created_ts": "1553100761439214",
"id": "8918449531932029152",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"a9a53534a9812550c40555ae775490ccfa6f79e2\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"swarming\": {\"bot_dimensions\": {\"gce\": [\"1\"], \"machine_type\": [\"n1-standard-8\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"], \"locale\": [\"en_US.cp1252\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"builder\": [\"Windows deterministic\"], \"id\": [\"windows-deterministic-0-v3qq\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4211-90dafba\"], \"image\": [\"chrome-win10-1703-2018-04-20-61acaf8983f\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"integrity\": [\"high\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"pool\": [\"luci.chromium.ci\"], \"caches\": [\"builder_e8fffaf9fac16164b1ec16ebaa1f377b792d39a582f7640830518d8ff7303d0c_v2\", \"git\", \"goma_v2\", \"vpython\"]}}, \"ui\": {\"info\": \"\"}, \"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"got_angle_revision\": \"a11db2ac7c5e9ea594a4d2a39aae130d533fe95b\", \"got_nacl_revision\": \"7d24fb15d81f95ed30dde0c04b68584749709657\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#27160}\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"7dbc0eb2efa4708da5e3e44d2a15bfdee4ceb474\", \"got_revision\": \"a9a53534a9812550c40555ae775490ccfa6f79e2\", \"got_swarming_client_revision\": \"aa60736aded9fc32a0e21a81f5fc51f6009d01f3\", \"recipe\": \"swarming/deterministic_build\", \"mastername\": \"chromium.win\", \"buildnumber\": 12507, \"got_v8_revision\": \"93306f1d7b2c10824e1e8876e5b8a3ab37c42b96\", \"got_revision_cp\": \"refs/heads/master@{#642556}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"branch\": \"refs/heads/master\", \"path_config\": \"generic\", \"got_dawn_revision\": \"54e4d47db4910ebd1ffce0247b60d4e6f984774f\", \"bot_id\": \"windows-deterministic-0-v3qq\", \"got_v8_revision_cp\": \"refs/heads/7.5.72@{#1}\", \"revision\": \"a9a53534a9812550c40555ae775490ccfa6f79e2\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1553100885770707",
"status": "COMPLETED",
"status_changed_ts": "1553108235190225",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/12507",
"builder:Windows deterministic",
"buildset:commit/git/a9a53534a9812550c40555ae775490ccfa6f79e2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a9a53534a9812550c40555ae775490ccfa6f79e2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9083879593965562688",
"scheduler_job_id:chromium/Windows deterministic",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8918449531932029152/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Windows",
"swarming_tag:recipe_name:swarming/deterministic_build",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:43b4d67332146310",
"user_agent:luci-scheduler"
],
"updated_ts": "1553108235193288",
"url": "https://ci.chromium.org/b/8918449531932029152",
"utcnow_ts": "1553108235565550"
}
}
[18:57:15.580] Invocation finished in 2h4m35.463808851s with status SUCCEEDED