[19:48:50.089] New invocation is queued and will start shortly
[19:48:51.155] Starting the invocation (attempt 1)
[19:48:51.186] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:48:51.186] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:48:51.186] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9081694183369898624",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"980910c6cbc31900be2d9465707e9751f0ce93b2\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9081694183369898624",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/980910c6cbc31900be2d9465707e9751f0ce93b2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/980910c6cbc31900be2d9465707e9751f0ce93b2",
"gitiles_ref:refs/heads/master"
]
}
[19:48:52.023] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "project:chromium",
"created_ts": "1555184931476787",
"id": "8916264121250761200",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"980910c6cbc31900be2d9465707e9751f0ce93b2\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1555184931870990",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/12869",
"builder:Windows deterministic",
"buildset:commit/git/980910c6cbc31900be2d9465707e9751f0ce93b2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/980910c6cbc31900be2d9465707e9751f0ce93b2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9081694183369898624",
"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/8916264121250761200/+/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": "1555184931871107",
"url": "https://ci.chromium.org/b/8916264121250761200",
"utcnow_ts": "1555184932014179"
}
}
[19:48:52.023] Task URL: https://ci.chromium.org/b/8916264121250761200
[19:48:52.023] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:2:0) after 1m0s
[19:48:53.795] Received PubSub notification, asking Buildbucket for the build status
[19:48:53.914] Build 8916264121250761200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:52.042] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:2:0)
[19:49:52.042] Timer tick, asking Buildbucket for the build status
[19:49:52.253] Build 8916264121250761200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:52.253] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:4:0) after 1m0s
[19:50:52.277] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:4:0)
[19:50:52.277] Timer tick, asking Buildbucket for the build status
[19:50:52.673] Build 8916264121250761200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:52.673] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:5:0) after 1m0s
[19:51:52.697] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:5:0)
[19:51:52.697] Timer tick, asking Buildbucket for the build status
[19:51:52.837] Build 8916264121250761200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:52.837] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:6:0) after 1m0s
[19:52:52.854] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:6:0)
[19:52:52.854] Timer tick, asking Buildbucket for the build status
[19:52:52.986] Build 8916264121250761200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:52.986] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:7:0) after 1m0s
[19:53:53.009] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:7:0)
[19:53:53.009] Timer tick, asking Buildbucket for the build status
[19:53:53.420] Build 8916264121250761200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:53.420] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:8:0) after 1m0s
[19:54:53.435] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:8:0)
[19:54:53.435] Timer tick, asking Buildbucket for the build status
[19:54:53.859] Build 8916264121250761200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:53.859] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:9:0) after 1m0s
[19:55:53.876] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:9:0)
[19:55:53.876] Timer tick, asking Buildbucket for the build status
[19:55:53.999] Build 8916264121250761200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:53.999] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:10:0) after 1m0s
[19:56:54.028] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:10:0)
[19:56:54.028] Timer tick, asking Buildbucket for the build status
[19:56:54.695] Build 8916264121250761200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:54.695] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:11:0) after 1m0s
[19:57:54.714] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:11:0)
[19:57:54.714] Timer tick, asking Buildbucket for the build status
[19:57:54.881] Build 8916264121250761200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:54.881] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:12:0) after 1m0s
[19:58:54.906] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:12:0)
[19:58:54.906] Timer tick, asking Buildbucket for the build status
[19:58:55.889] Build 8916264121250761200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:55.889] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:13:0) after 1m0s
[19:59:55.907] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:13:0)
[19:59:55.907] Timer tick, asking Buildbucket for the build status
[19:59:56.014] Build 8916264121250761200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:56.014] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:14:0) after 1m0s
[20:00:56.037] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:14:0)
[20:00:56.037] Timer tick, asking Buildbucket for the build status
[20:00:56.757] Build 8916264121250761200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:56.757] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:15:0) after 1m0s
[20:01:56.823] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:15:0)
[20:01:56.823] Timer tick, asking Buildbucket for the build status
[20:01:56.949] Build 8916264121250761200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:56.949] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:16:0) after 1m0s
[20:02:56.994] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:16:0)
[20:02:56.994] Timer tick, asking Buildbucket for the build status
[20:02:57.273] Build 8916264121250761200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:57.273] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:17:0) after 1m0s
[20:03:57.297] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:17:0)
[20:03:57.297] Timer tick, asking Buildbucket for the build status
[20:03:57.457] Build 8916264121250761200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:57.457] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:18:0) after 1m0s
[20:04:57.437] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:18:0)
[20:04:57.437] Timer tick, asking Buildbucket for the build status
[20:04:57.987] Build 8916264121250761200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:57.987] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:19:0) after 1m0s
[20:05:58.021] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:19:0)
[20:05:58.021] Timer tick, asking Buildbucket for the build status
[20:05:58.134] Build 8916264121250761200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:58.134] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:20:0) after 1m0s
[20:06:58.154] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:20:0)
[20:06:58.154] Timer tick, asking Buildbucket for the build status
[20:06:58.686] Build 8916264121250761200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:58.686] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:21:0) after 1m0s
[20:07:58.713] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:21:0)
[20:07:58.713] Timer tick, asking Buildbucket for the build status
[20:07:59.184] Build 8916264121250761200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:59.184] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:22:0) after 1m0s
[20:08:59.221] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:22:0)
[20:08:59.221] Timer tick, asking Buildbucket for the build status
[20:08:59.510] Build 8916264121250761200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:59.510] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:23:0) after 1m0s
[20:09:59.529] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:23:0)
[20:09:59.529] Timer tick, asking Buildbucket for the build status
[20:10:00.290] Build 8916264121250761200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:00.290] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:24:0) after 1m0s
[20:11:00.332] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:24:0)
[20:11:00.332] Timer tick, asking Buildbucket for the build status
[20:11:01.148] Build 8916264121250761200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:01.148] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:25:0) after 1m0s
[20:12:01.182] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:25:0)
[20:12:01.182] Timer tick, asking Buildbucket for the build status
[20:12:01.361] Build 8916264121250761200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:01.361] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:26:0) after 1m0s
[20:13:01.378] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:26:0)
[20:13:01.378] Timer tick, asking Buildbucket for the build status
[20:13:02.666] Build 8916264121250761200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:02.666] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:27:0) after 1m0s
[20:14:02.697] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:27:0)
[20:14:02.697] Timer tick, asking Buildbucket for the build status
[20:14:08.339] Build 8916264121250761200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:08.339] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:28:0) after 1m0s
[20:15:08.359] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:28:0)
[20:15:08.359] Timer tick, asking Buildbucket for the build status
[20:15:08.473] Build 8916264121250761200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:08.473] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:29:0) after 1m0s
[20:16:08.491] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:29:0)
[20:16:08.491] Timer tick, asking Buildbucket for the build status
[20:16:08.585] Build 8916264121250761200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:08.585] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:30:0) after 1m0s
[20:17:08.606] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:30:0)
[20:17:08.606] Timer tick, asking Buildbucket for the build status
[20:17:08.869] Build 8916264121250761200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:08.869] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:31:0) after 1m0s
[20:18:08.858] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:31:0)
[20:18:08.858] Timer tick, asking Buildbucket for the build status
[20:18:09.179] Build 8916264121250761200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:09.179] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:32:0) after 1m0s
[20:19:09.198] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:32:0)
[20:19:09.198] Timer tick, asking Buildbucket for the build status
[20:19:09.308] Build 8916264121250761200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:09.308] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:33:0) after 1m0s
[20:20:09.316] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:33:0)
[20:20:09.316] Timer tick, asking Buildbucket for the build status
[20:20:09.460] Build 8916264121250761200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:09.460] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:34:0) after 1m0s
[20:21:09.490] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:34:0)
[20:21:09.490] Timer tick, asking Buildbucket for the build status
[20:21:09.590] Build 8916264121250761200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:09.590] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:35:0) after 1m0s
[20:22:09.610] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:35:0)
[20:22:09.610] Timer tick, asking Buildbucket for the build status
[20:22:10.011] Build 8916264121250761200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:10.012] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:36:0) after 1m0s
[20:23:10.010] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:36:0)
[20:23:10.010] Timer tick, asking Buildbucket for the build status
[20:23:10.125] Build 8916264121250761200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:10.125] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:37:0) after 1m0s
[20:24:10.167] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:37:0)
[20:24:10.167] Timer tick, asking Buildbucket for the build status
[20:24:10.685] Build 8916264121250761200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:10.685] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:38:0) after 1m0s
[20:25:10.706] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:38:0)
[20:25:10.706] Timer tick, asking Buildbucket for the build status
[20:25:11.188] Build 8916264121250761200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:11.188] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:39:0) after 1m0s
[20:26:11.239] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:39:0)
[20:26:11.239] Timer tick, asking Buildbucket for the build status
[20:26:11.344] Build 8916264121250761200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:11.344] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:40:0) after 1m0s
[20:27:11.362] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:40:0)
[20:27:11.362] Timer tick, asking Buildbucket for the build status
[20:27:11.467] Build 8916264121250761200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:11.467] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:41:0) after 1m0s
[20:28:11.475] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:41:0)
[20:28:11.475] Timer tick, asking Buildbucket for the build status
[20:28:11.759] Build 8916264121250761200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:11.759] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:42:0) after 1m0s
[20:29:11.776] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:42:0)
[20:29:11.776] Timer tick, asking Buildbucket for the build status
[20:29:12.158] Build 8916264121250761200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:12.158] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:43:0) after 1m0s
[20:30:12.271] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:43:0)
[20:30:12.271] Timer tick, asking Buildbucket for the build status
[20:30:12.483] Build 8916264121250761200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:12.483] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:44:0) after 1m0s
[20:31:12.501] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:44:0)
[20:31:12.501] Timer tick, asking Buildbucket for the build status
[20:31:12.921] Build 8916264121250761200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:12.921] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:45:0) after 1m0s
[20:32:12.990] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:45:0)
[20:32:12.990] Timer tick, asking Buildbucket for the build status
[20:32:13.117] Build 8916264121250761200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:13.117] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:46:0) after 1m0s
[20:33:13.140] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:46:0)
[20:33:13.140] Timer tick, asking Buildbucket for the build status
[20:33:13.265] Build 8916264121250761200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:13.265] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:47:0) after 1m0s
[20:34:13.282] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:47:0)
[20:34:13.282] Timer tick, asking Buildbucket for the build status
[20:34:13.378] Build 8916264121250761200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:13.378] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:48:0) after 1m0s
[20:35:13.383] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:48:0)
[20:35:13.383] Timer tick, asking Buildbucket for the build status
[20:35:14.719] Build 8916264121250761200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:14.719] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:49:0) after 1m0s
[20:36:14.741] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:49:0)
[20:36:14.741] Timer tick, asking Buildbucket for the build status
[20:36:15.284] Build 8916264121250761200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:15.284] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:50:0) after 1m0s
[20:37:15.271] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:50:0)
[20:37:15.271] Timer tick, asking Buildbucket for the build status
[20:37:15.470] Build 8916264121250761200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:15.470] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:51:0) after 1m0s
[20:38:15.494] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:51:0)
[20:38:15.494] Timer tick, asking Buildbucket for the build status
[20:38:15.880] Build 8916264121250761200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:15.880] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:52:0) after 1m0s
[20:39:15.877] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:52:0)
[20:39:15.877] Timer tick, asking Buildbucket for the build status
[20:39:16.211] Build 8916264121250761200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:16.211] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:53:0) after 1m0s
[20:40:16.233] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:53:0)
[20:40:16.233] Timer tick, asking Buildbucket for the build status
[20:40:16.363] Build 8916264121250761200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:16.363] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:54:0) after 1m0s
[20:41:16.343] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:54:0)
[20:41:16.343] Timer tick, asking Buildbucket for the build status
[20:41:16.496] Build 8916264121250761200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:16.496] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:55:0) after 1m0s
[20:42:16.517] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:55:0)
[20:42:16.517] Timer tick, asking Buildbucket for the build status
[20:42:16.632] Build 8916264121250761200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:16.632] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:56:0) after 1m0s
[20:43:16.637] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:56:0)
[20:43:16.637] Timer tick, asking Buildbucket for the build status
[20:43:17.375] Build 8916264121250761200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:17.375] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:57:0) after 1m0s
[20:44:17.428] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:57:0)
[20:44:17.428] Timer tick, asking Buildbucket for the build status
[20:44:17.583] Build 8916264121250761200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:17.584] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:58:0) after 1m0s
[20:45:17.557] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:58:0)
[20:45:17.557] Timer tick, asking Buildbucket for the build status
[20:45:17.668] Build 8916264121250761200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:17.668] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:59:0) after 1m0s
[20:46:17.692] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:59:0)
[20:46:17.692] Timer tick, asking Buildbucket for the build status
[20:46:17.825] Build 8916264121250761200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:17.825] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:60:0) after 1m0s
[20:47:17.845] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:60:0)
[20:47:17.845] Timer tick, asking Buildbucket for the build status
[20:47:17.989] Build 8916264121250761200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:17.989] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:61:0) after 1m0s
[20:48:18.014] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:61:0)
[20:48:18.014] Timer tick, asking Buildbucket for the build status
[20:48:18.165] Build 8916264121250761200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:18.165] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:62:0) after 1m0s
[20:49:18.188] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:62:0)
[20:49:18.188] Timer tick, asking Buildbucket for the build status
[20:49:18.329] Build 8916264121250761200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:18.329] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:63:0) after 1m0s
[20:50:18.350] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:63:0)
[20:50:18.350] Timer tick, asking Buildbucket for the build status
[20:50:18.768] Build 8916264121250761200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:18.793] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:64:0) after 1m0s
[20:51:18.785] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:64:0)
[20:51:18.785] Timer tick, asking Buildbucket for the build status
[20:51:19.506] Build 8916264121250761200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:19.506] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:65:0) after 1m0s
[20:52:19.529] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:65:0)
[20:52:19.530] Timer tick, asking Buildbucket for the build status
[20:52:19.804] Build 8916264121250761200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:19.804] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:66:0) after 1m0s
[20:53:19.825] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:66:0)
[20:53:19.825] Timer tick, asking Buildbucket for the build status
[20:53:21.293] Build 8916264121250761200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:21.293] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:67:0) after 1m0s
[20:54:21.313] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:67:0)
[20:54:21.313] Timer tick, asking Buildbucket for the build status
[20:54:21.435] Build 8916264121250761200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:21.435] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:68:0) after 1m0s
[20:55:21.460] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:68:0)
[20:55:21.460] Timer tick, asking Buildbucket for the build status
[20:55:21.906] Build 8916264121250761200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:21.906] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:69:0) after 1m0s
[20:56:21.928] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:69:0)
[20:56:21.928] Timer tick, asking Buildbucket for the build status
[20:56:22.748] Build 8916264121250761200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:22.748] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:70:0) after 1m0s
[20:57:22.785] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:70:0)
[20:57:22.785] Timer tick, asking Buildbucket for the build status
[20:57:22.873] Build 8916264121250761200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:22.873] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9081694183369898624:71:0) after 1m0s
[20:57:29.651] Received PubSub notification, asking Buildbucket for the build status
[20:57:30.299] Build 8916264121250761200: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[20:57:30.299] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1555189048509009",
"created_by": "project:chromium",
"created_ts": "1555184931476787",
"id": "8916264121250761200",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"980910c6cbc31900be2d9465707e9751f0ce93b2\"}}",
"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-z5tg\", \"branch\": \"refs/heads/master\", \"buildername\": \"Windows deterministic\", \"buildnumber\": 12869, \"got_angle_revision\": \"065f8dc35f9dffead39ced85a0ab1ba447ccf843\", \"got_dawn_revision\": \"bff933affcffd6d907c5356237668ba755d4e266\", \"got_nacl_revision\": \"6abc006f6760ec49350cd45e8bccbff4809725ac\", \"got_revision\": \"980910c6cbc31900be2d9465707e9751f0ce93b2\", \"got_revision_cp\": \"refs/heads/master@{#650618}\", \"got_swarming_client_revision\": \"aa60736aded9fc32a0e21a81f5fc51f6009d01f3\", \"got_v8_revision\": \"81260d20b1cfb15752913acb657113c92c6c0026\", \"got_v8_revision_cp\": \"refs/heads/7.5.269@{#1}\", \"got_webrtc_revision\": \"79e9f4b9c128963eef2c7031dd9311f86fca5535\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#27605}\", \"mastername\": \"chromium.win\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"980910c6cbc31900be2d9465707e9751f0ce93b2\"}, \"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-z5tg\"], \"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\": [\"4253-3d44409\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1555184932743839",
"status": "COMPLETED",
"status_changed_ts": "1555189049020249",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/12869",
"builder:Windows deterministic",
"buildset:commit/git/980910c6cbc31900be2d9465707e9751f0ce93b2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/980910c6cbc31900be2d9465707e9751f0ce93b2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9081694183369898624",
"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/8916264121250761200/+/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:4431105cf1d88810",
"user_agent:luci-scheduler"
],
"updated_ts": "1555189049025451",
"url": "https://ci.chromium.org/b/8916264121250761200",
"utcnow_ts": "1555189050282882"
}
}
[20:57:30.299] Invocation finished in 1h8m40.227720795s with status SUCCEEDED