[13:55:55.159] New invocation is queued and will start shortly
[13:55:56.423] Starting the invocation (attempt 1)
[13:55:56.455] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:55:56.455] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:55:56.455] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9077730120371618528",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"99eda448d87790565597730a4b6829eadb3c74f0\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9077730120371618528",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/99eda448d87790565597730a4b6829eadb3c74f0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/99eda448d87790565597730a4b6829eadb3c74f0",
"gitiles_ref:refs/heads/master"
]
}
[13:55:57.615] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "AUTO",
"created_by": "project:chromium",
"created_ts": "1558965356785750",
"id": "8912300058001976176",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"99eda448d87790565597730a4b6829eadb3c74f0\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1558965357465323",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/13570",
"builder:Windows deterministic",
"buildset:commit/git/99eda448d87790565597730a4b6829eadb3c74f0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/99eda448d87790565597730a4b6829eadb3c74f0",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9077730120371618528",
"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/8912300058001976176/+/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:",
"user_agent:luci-scheduler"
],
"updated_ts": "1558965357465447",
"url": "https://ci.chromium.org/b/8912300058001976176",
"utcnow_ts": "1558965357604418"
}
}
[13:55:57.615] Task URL: https://ci.chromium.org/b/8912300058001976176
[13:55:57.615] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:2:0) after 1m0s
[13:56:01.062] Received PubSub notification, asking Buildbucket for the build status
[13:56:01.718] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:57.640] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:2:0)
[13:56:57.640] Timer tick, asking Buildbucket for the build status
[13:56:58.248] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:58.248] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:4:0) after 1m0s
[13:57:58.290] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:4:0)
[13:57:58.290] Timer tick, asking Buildbucket for the build status
[13:57:59.149] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:59.149] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:5:0) after 1m0s
[13:58:59.202] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:5:0)
[13:58:59.202] Timer tick, asking Buildbucket for the build status
[13:58:59.995] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:59.995] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:6:0) after 1m0s
[14:00:00.183] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:6:0)
[14:00:00.183] Timer tick, asking Buildbucket for the build status
[14:00:03.526] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:03.526] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:7:0) after 1m0s
[14:01:03.547] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:7:0)
[14:01:03.547] Timer tick, asking Buildbucket for the build status
[14:01:05.568] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:05.568] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:8:0) after 1m0s
[14:02:05.583] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:8:0)
[14:02:05.583] Timer tick, asking Buildbucket for the build status
[14:02:05.739] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:05.739] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:9:0) after 1m0s
[14:03:05.759] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:9:0)
[14:03:05.759] Timer tick, asking Buildbucket for the build status
[14:03:06.243] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:06.243] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:10:0) after 1m0s
[14:04:06.268] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:10:0)
[14:04:06.268] Timer tick, asking Buildbucket for the build status
[14:04:06.565] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:06.565] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:11:0) after 1m0s
[14:05:06.600] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:11:0)
[14:05:06.600] Timer tick, asking Buildbucket for the build status
[14:05:06.805] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:06.805] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:12:0) after 1m0s
[14:06:06.869] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:12:0)
[14:06:06.869] Timer tick, asking Buildbucket for the build status
[14:06:18.772] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:18.772] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:13:0) after 1m0s
[14:07:19.080] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:13:0)
[14:07:19.080] Timer tick, asking Buildbucket for the build status
[14:07:19.223] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:19.223] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:14:0) after 1m0s
[14:08:19.439] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:14:0)
[14:08:19.440] Timer tick, asking Buildbucket for the build status
[14:08:19.571] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:19.571] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:15:0) after 1m0s
[14:09:19.683] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:15:0)
[14:09:19.683] Timer tick, asking Buildbucket for the build status
[14:09:19.797] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:19.797] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:16:0) after 1m0s
[14:10:19.918] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:16:0)
[14:10:19.918] Timer tick, asking Buildbucket for the build status
[14:10:21.392] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:21.392] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:17:0) after 1m0s
[14:11:21.477] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:17:0)
[14:11:21.477] Timer tick, asking Buildbucket for the build status
[14:11:21.653] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:21.653] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:18:0) after 1m0s
[14:12:22.029] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:18:0)
[14:12:22.029] Timer tick, asking Buildbucket for the build status
[14:12:22.134] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:22.134] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:19:0) after 1m0s
[14:13:22.146] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:19:0)
[14:13:22.146] Timer tick, asking Buildbucket for the build status
[14:13:22.235] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:22.235] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:20:0) after 1m0s
[14:14:22.252] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:20:0)
[14:14:22.252] Timer tick, asking Buildbucket for the build status
[14:14:22.620] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:22.620] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:21:0) after 1m0s
[14:15:22.716] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:21:0)
[14:15:22.716] Timer tick, asking Buildbucket for the build status
[14:15:22.818] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:22.818] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:22:0) after 1m0s
[14:16:22.844] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:22:0)
[14:16:22.844] Timer tick, asking Buildbucket for the build status
[14:16:23.026] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:23.026] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:23:0) after 1m0s
[14:17:23.050] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:23:0)
[14:17:23.050] Timer tick, asking Buildbucket for the build status
[14:17:23.663] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:23.663] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:24:0) after 1m0s
[14:18:23.855] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:24:0)
[14:18:23.855] Timer tick, asking Buildbucket for the build status
[14:18:23.986] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:23.986] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:25:0) after 1m0s
[14:19:24.241] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:25:0)
[14:19:24.241] Timer tick, asking Buildbucket for the build status
[14:19:24.614] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:24.614] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:26:0) after 1m0s
[14:20:24.657] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:26:0)
[14:20:24.657] Timer tick, asking Buildbucket for the build status
[14:20:24.767] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:24.767] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:27:0) after 1m0s
[14:21:24.822] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:27:0)
[14:21:24.822] Timer tick, asking Buildbucket for the build status
[14:21:25.064] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:25.064] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:28:0) after 1m0s
[14:22:25.154] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:28:0)
[14:22:25.154] Timer tick, asking Buildbucket for the build status
[14:22:25.751] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:25.751] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:29:0) after 1m0s
[14:23:25.879] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:29:0)
[14:23:25.879] Timer tick, asking Buildbucket for the build status
[14:23:27.606] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:27.606] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:30:0) after 1m0s
[14:24:27.849] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:30:0)
[14:24:27.849] Timer tick, asking Buildbucket for the build status
[14:24:28.044] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:28.044] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:31:0) after 1m0s
[14:25:28.073] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:31:0)
[14:25:28.073] Timer tick, asking Buildbucket for the build status
[14:25:28.850] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:28.850] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:32:0) after 1m0s
[14:26:28.883] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:32:0)
[14:26:28.883] Timer tick, asking Buildbucket for the build status
[14:26:28.994] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:28.994] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:33:0) after 1m0s
[14:27:29.006] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:33:0)
[14:27:29.006] Timer tick, asking Buildbucket for the build status
[14:27:29.189] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:29.189] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:34:0) after 1m0s
[14:28:29.194] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:34:0)
[14:28:29.194] Timer tick, asking Buildbucket for the build status
[14:28:29.689] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:29.689] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:35:0) after 1m0s
[14:29:29.756] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:35:0)
[14:29:29.756] Timer tick, asking Buildbucket for the build status
[14:29:29.998] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:29.998] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:36:0) after 1m0s
[14:30:30.024] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:36:0)
[14:30:30.024] Timer tick, asking Buildbucket for the build status
[14:30:30.290] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:30.290] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:37:0) after 1m0s
[14:31:30.317] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:37:0)
[14:31:30.317] Timer tick, asking Buildbucket for the build status
[14:31:30.426] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:30.426] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:38:0) after 1m0s
[14:32:30.492] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:38:0)
[14:32:30.492] Timer tick, asking Buildbucket for the build status
[14:32:30.619] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:30.619] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:39:0) after 1m0s
[14:33:30.665] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:39:0)
[14:33:30.665] Timer tick, asking Buildbucket for the build status
[14:33:30.791] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:30.791] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:40:0) after 1m0s
[14:34:53.749] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:40:0)
[14:34:53.749] Timer tick, asking Buildbucket for the build status
[14:34:54.015] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:54.015] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:41:0) after 1m0s
[14:35:54.431] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:41:0)
[14:35:54.431] Timer tick, asking Buildbucket for the build status
[14:35:54.871] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:54.871] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:42:0) after 1m0s
[14:36:55.051] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:42:0)
[14:36:55.051] Timer tick, asking Buildbucket for the build status
[14:36:57.199] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:57.199] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:43:0) after 1m0s
[14:37:57.221] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:43:0)
[14:37:57.221] Timer tick, asking Buildbucket for the build status
[14:37:57.369] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:57.369] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:44:0) after 1m0s
[14:38:57.391] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:44:0)
[14:38:57.391] Timer tick, asking Buildbucket for the build status
[14:38:57.482] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:57.482] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:45:0) after 1m0s
[14:39:57.507] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:45:0)
[14:39:57.507] Timer tick, asking Buildbucket for the build status
[14:39:58.062] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:58.062] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:46:0) after 1m0s
[14:40:58.185] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:46:0)
[14:40:58.185] Timer tick, asking Buildbucket for the build status
[14:40:58.473] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:58.473] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:47:0) after 1m0s
[14:41:58.572] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:47:0)
[14:41:58.572] Timer tick, asking Buildbucket for the build status
[14:41:58.960] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:58.960] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:48:0) after 1m0s
[14:42:59.913] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:48:0)
[14:42:59.913] Timer tick, asking Buildbucket for the build status
[14:43:00.299] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:00.299] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:49:0) after 1m0s
[14:44:00.486] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:49:0)
[14:44:00.486] Timer tick, asking Buildbucket for the build status
[14:44:02.934] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:02.934] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:50:0) after 1m0s
[14:45:02.940] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:50:0)
[14:45:02.940] Timer tick, asking Buildbucket for the build status
[14:45:03.118] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:03.118] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:51:0) after 1m0s
[14:46:03.168] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:51:0)
[14:46:03.168] Timer tick, asking Buildbucket for the build status
[14:46:04.413] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:04.413] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:52:0) after 1m0s
[14:47:04.546] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:52:0)
[14:47:04.546] Timer tick, asking Buildbucket for the build status
[14:47:04.644] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:04.644] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:53:0) after 1m0s
[14:48:08.046] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:53:0)
[14:48:08.046] Timer tick, asking Buildbucket for the build status
[14:48:08.326] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:08.326] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:54:0) after 1m0s
[14:49:08.381] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:54:0)
[14:49:08.381] Timer tick, asking Buildbucket for the build status
[14:49:11.028] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:11.028] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:55:0) after 1m0s
[14:50:11.315] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:55:0)
[14:50:11.315] Timer tick, asking Buildbucket for the build status
[14:50:11.462] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:11.462] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:56:0) after 1m0s
[14:51:11.484] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:56:0)
[14:51:11.484] Timer tick, asking Buildbucket for the build status
[14:51:11.681] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:11.681] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:57:0) after 1m0s
[14:52:12.854] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:57:0)
[14:52:12.854] Timer tick, asking Buildbucket for the build status
[14:52:17.076] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:17.076] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:58:0) after 1m0s
[14:53:17.175] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:58:0)
[14:53:17.175] Timer tick, asking Buildbucket for the build status
[14:53:17.547] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:17.547] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:59:0) after 1m0s
[14:54:17.710] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:59:0)
[14:54:17.710] Timer tick, asking Buildbucket for the build status
[14:54:19.528] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:19.528] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:60:0) after 1m0s
[14:55:19.564] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:60:0)
[14:55:19.564] Timer tick, asking Buildbucket for the build status
[14:55:21.330] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:21.330] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:61:0) after 1m0s
[14:56:22.126] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:61:0)
[14:56:22.126] Timer tick, asking Buildbucket for the build status
[14:56:22.375] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:22.375] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:62:0) after 1m0s
[14:57:22.415] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:62:0)
[14:57:22.415] Timer tick, asking Buildbucket for the build status
[14:57:22.651] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:22.651] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:63:0) after 1m0s
[14:58:23.445] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:63:0)
[14:58:23.445] Timer tick, asking Buildbucket for the build status
[14:58:23.582] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:23.582] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:64:0) after 1m0s
[14:59:23.842] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:64:0)
[14:59:23.842] Timer tick, asking Buildbucket for the build status
[14:59:23.954] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:23.954] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:65:0) after 1m0s
[15:00:24.087] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:65:0)
[15:00:24.087] Timer tick, asking Buildbucket for the build status
[15:00:24.636] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:24.636] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:66:0) after 1m0s
[15:01:24.885] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:66:0)
[15:01:24.885] Timer tick, asking Buildbucket for the build status
[15:01:27.743] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:27.743] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:67:0) after 1m0s
[15:02:27.803] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:67:0)
[15:02:27.803] Timer tick, asking Buildbucket for the build status
[15:02:27.913] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:27.913] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:68:0) after 1m0s
[15:03:27.966] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:68:0)
[15:03:27.966] Timer tick, asking Buildbucket for the build status
[15:03:29.604] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:29.604] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:69:0) after 1m0s
[15:04:29.626] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:69:0)
[15:04:29.626] Timer tick, asking Buildbucket for the build status
[15:04:29.777] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:29.777] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:70:0) after 1m0s
[15:05:29.908] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:70:0)
[15:05:29.908] Timer tick, asking Buildbucket for the build status
[15:05:30.400] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:30.400] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:71:0) after 1m0s
[15:06:30.403] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:71:0)
[15:06:30.403] Timer tick, asking Buildbucket for the build status
[15:06:30.517] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:30.517] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:72:0) after 1m0s
[15:07:30.562] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:72:0)
[15:07:30.562] Timer tick, asking Buildbucket for the build status
[15:07:31.314] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:31.314] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:73:0) after 1m0s
[15:08:31.297] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:73:0)
[15:08:31.297] Timer tick, asking Buildbucket for the build status
[15:08:31.484] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:31.484] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:74:0) after 1m0s
[15:09:31.551] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:74:0)
[15:09:31.551] Timer tick, asking Buildbucket for the build status
[15:09:31.906] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:31.906] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:75:0) after 1m0s
[15:10:31.929] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:75:0)
[15:10:31.929] Timer tick, asking Buildbucket for the build status
[15:10:32.065] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:32.066] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:76:0) after 1m0s
[15:11:32.122] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:76:0)
[15:11:32.122] Timer tick, asking Buildbucket for the build status
[15:11:33.014] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:33.014] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:77:0) after 1m0s
[15:12:33.068] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:77:0)
[15:12:33.068] Timer tick, asking Buildbucket for the build status
[15:12:33.291] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:33.291] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:78:0) after 1m0s
[15:13:33.338] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:78:0)
[15:13:33.338] Timer tick, asking Buildbucket for the build status
[15:13:33.617] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:33.617] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:79:0) after 1m0s
[15:14:33.645] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:79:0)
[15:14:33.645] Timer tick, asking Buildbucket for the build status
[15:14:34.718] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:34.718] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:80:0) after 1m0s
[15:15:34.717] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:80:0)
[15:15:34.717] Timer tick, asking Buildbucket for the build status
[15:15:34.828] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:34.828] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:81:0) after 1m0s
[15:16:34.873] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:81:0)
[15:16:34.873] Timer tick, asking Buildbucket for the build status
[15:16:35.596] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:35.596] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:82:0) after 1m0s
[15:17:35.736] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:82:0)
[15:17:35.736] Timer tick, asking Buildbucket for the build status
[15:17:35.908] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:35.908] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:83:0) after 1m0s
[15:18:35.956] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:83:0)
[15:18:35.956] Timer tick, asking Buildbucket for the build status
[15:18:36.562] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:36.562] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:84:0) after 1m0s
[15:19:36.566] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:84:0)
[15:19:36.566] Timer tick, asking Buildbucket for the build status
[15:19:36.759] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:36.760] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:85:0) after 1m0s
[15:20:36.844] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:85:0)
[15:20:36.844] Timer tick, asking Buildbucket for the build status
[15:20:36.930] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:36.930] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:86:0) after 1m0s
[15:21:36.949] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:86:0)
[15:21:36.949] Timer tick, asking Buildbucket for the build status
[15:21:37.376] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:37.376] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:87:0) after 1m0s
[15:22:37.395] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:87:0)
[15:22:37.395] Timer tick, asking Buildbucket for the build status
[15:22:38.153] Build 8912300058001976176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:38.153] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9077730120371618528:88:0) after 1m0s
[15:22:51.219] Received PubSub notification, asking Buildbucket for the build status
[15:22:52.831] Build 8912300058001976176: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[15:22:52.831] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "AUTO",
"completed_ts": "1558970570345408",
"created_by": "project:chromium",
"created_ts": "1558965356785750",
"id": "8912300058001976176",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"99eda448d87790565597730a4b6829eadb3c74f0\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"windows-deterministic-0-7052\", \"branch\": \"refs/heads/master\", \"buildername\": \"Windows deterministic\", \"buildnumber\": 13570, \"got_angle_revision\": \"8ae3f2c533276552b60a492ce85966aca60070ce\", \"got_dawn_revision\": \"3dd6153eb73e967ee99917879bf4aba237a98f0b\", \"got_nacl_revision\": \"0ddc033406886a709b901e0c312872529f9705e8\", \"got_revision\": \"99eda448d87790565597730a4b6829eadb3c74f0\", \"got_revision_cp\": \"refs/heads/master@{#663534}\", \"got_swarming_client_revision\": \"779c4f0f8488c64587b75dbb001d18c3c0c4cda9\", \"got_v8_revision\": \"d86f751324b548ebddd0210f29358cc005f259f7\", \"got_v8_revision_cp\": \"refs/heads/7.6.278@{#1}\", \"got_webrtc_revision\": \"ad44b75a7ca1c806abfae64e6418849b8dd5dbaf\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#28068}\", \"mastername\": \"chromium.win\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"99eda448d87790565597730a4b6829eadb3c74f0\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"Windows deterministic\"], \"caches\": [\"builder_e8fffaf9fac16164b1ec16ebaa1f377b792d39a582f7640830518d8ff7303d0c_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"windows-deterministic-0-7052\"], \"image\": [\"chrome-win10-1703-2018-04-20-61acaf8983f\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4303-bae210e\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1558965358796086",
"status": "COMPLETED",
"status_changed_ts": "1558970570950952",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/13570",
"builder:Windows deterministic",
"buildset:commit/git/99eda448d87790565597730a4b6829eadb3c74f0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/99eda448d87790565597730a4b6829eadb3c74f0",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9077730120371618528",
"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/8912300058001976176/+/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:45126516fc49e010",
"user_agent:luci-scheduler"
],
"updated_ts": "1558970570956816",
"url": "https://ci.chromium.org/b/8912300058001976176",
"utcnow_ts": "1558970572767824"
}
}
[15:22:52.831] Invocation finished in 1h26m57.689249444s with status SUCCEEDED