[05:05:24.206] New invocation is queued and will start shortly
[05:05:25.298] Starting the invocation (attempt 1)
[05:05:25.339] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:05:25.340] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:05:25.340] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9079756630816660336",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"2819e92e9709edf2a43e0477ed81cb75177ae422\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9079756630816660336",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/2819e92e9709edf2a43e0477ed81cb75177ae422",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2819e92e9709edf2a43e0477ed81cb75177ae422",
"gitiles_ref:refs/heads/master"
]
}
[05:05:26.396] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "project:chromium",
"created_ts": "1557032725538717",
"id": "8914326568744191520",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"2819e92e9709edf2a43e0477ed81cb75177ae422\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1557032726212721",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/13221",
"builder:Windows deterministic",
"buildset:commit/git/2819e92e9709edf2a43e0477ed81cb75177ae422",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2819e92e9709edf2a43e0477ed81cb75177ae422",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9079756630816660336",
"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/8914326568744191520/+/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": "1557032726212849",
"url": "https://ci.chromium.org/b/8914326568744191520",
"utcnow_ts": "1557032726386742"
}
}
[05:05:26.396] Task URL: https://ci.chromium.org/b/8914326568744191520
[05:05:26.396] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:2:0) after 1m0s
[05:05:33.195] Received PubSub notification, asking Buildbucket for the build status
[05:05:33.448] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:26.389] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:2:0)
[05:06:26.389] Timer tick, asking Buildbucket for the build status
[05:06:28.434] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:28.434] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:4:0) after 1m0s
[05:07:28.456] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:4:0)
[05:07:28.456] Timer tick, asking Buildbucket for the build status
[05:07:28.652] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:28.652] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:5:0) after 1m0s
[05:08:28.654] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:5:0)
[05:08:28.654] Timer tick, asking Buildbucket for the build status
[05:08:29.596] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:29.596] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:6:0) after 1m0s
[05:09:29.592] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:6:0)
[05:09:29.592] Timer tick, asking Buildbucket for the build status
[05:09:29.686] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:29.686] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:7:0) after 1m0s
[05:10:29.698] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:7:0)
[05:10:29.698] Timer tick, asking Buildbucket for the build status
[05:10:29.958] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:29.958] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:8:0) after 1m0s
[05:11:29.988] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:8:0)
[05:11:29.988] Timer tick, asking Buildbucket for the build status
[05:11:30.585] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:30.585] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:9:0) after 1m0s
[05:12:30.565] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:9:0)
[05:12:30.565] Timer tick, asking Buildbucket for the build status
[05:12:31.206] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:31.206] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:10:0) after 1m0s
[05:13:31.222] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:10:0)
[05:13:31.222] Timer tick, asking Buildbucket for the build status
[05:13:34.335] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:34.335] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:11:0) after 1m0s
[05:14:34.452] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:11:0)
[05:14:34.452] Timer tick, asking Buildbucket for the build status
[05:14:34.804] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:34.804] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:12:0) after 1m0s
[05:15:34.888] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:12:0)
[05:15:34.888] Timer tick, asking Buildbucket for the build status
[05:15:35.194] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:35.194] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:13:0) after 1m0s
[05:16:35.215] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:13:0)
[05:16:35.215] Timer tick, asking Buildbucket for the build status
[05:16:35.373] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:35.373] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:14:0) after 1m0s
[05:17:35.393] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:14:0)
[05:17:35.393] Timer tick, asking Buildbucket for the build status
[05:17:35.618] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:35.618] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:15:0) after 1m0s
[05:18:35.634] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:15:0)
[05:18:35.634] Timer tick, asking Buildbucket for the build status
[05:18:35.784] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:35.784] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:16:0) after 1m0s
[05:19:35.790] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:16:0)
[05:19:35.790] Timer tick, asking Buildbucket for the build status
[05:19:36.359] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:36.359] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:17:0) after 1m0s
[05:20:36.376] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:17:0)
[05:20:36.376] Timer tick, asking Buildbucket for the build status
[05:20:37.028] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:37.028] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:18:0) after 1m0s
[05:21:37.066] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:18:0)
[05:21:37.066] Timer tick, asking Buildbucket for the build status
[05:21:37.537] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:37.537] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:19:0) after 1m0s
[05:22:37.533] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:19:0)
[05:22:37.533] Timer tick, asking Buildbucket for the build status
[05:22:38.265] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:38.265] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:20:0) after 1m0s
[05:23:38.284] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:20:0)
[05:23:38.284] Timer tick, asking Buildbucket for the build status
[05:23:38.712] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:38.712] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:21:0) after 1m0s
[05:24:38.791] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:21:0)
[05:24:38.791] Timer tick, asking Buildbucket for the build status
[05:24:39.677] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:39.677] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:22:0) after 1m0s
[05:25:39.695] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:22:0)
[05:25:39.695] Timer tick, asking Buildbucket for the build status
[05:25:40.774] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:40.774] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:23:0) after 1m0s
[05:26:40.792] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:23:0)
[05:26:40.792] Timer tick, asking Buildbucket for the build status
[05:26:41.146] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:41.146] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:24:0) after 1m0s
[05:27:41.202] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:24:0)
[05:27:41.202] Timer tick, asking Buildbucket for the build status
[05:27:41.408] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:41.408] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:25:0) after 1m0s
[05:28:41.442] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:25:0)
[05:28:41.442] Timer tick, asking Buildbucket for the build status
[05:28:41.555] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:41.555] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:26:0) after 1m0s
[05:29:41.566] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:26:0)
[05:29:41.566] Timer tick, asking Buildbucket for the build status
[05:29:41.704] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:41.704] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:27:0) after 1m0s
[05:30:41.723] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:27:0)
[05:30:41.723] Timer tick, asking Buildbucket for the build status
[05:30:42.406] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:42.406] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:28:0) after 1m0s
[05:31:42.408] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:28:0)
[05:31:42.408] Timer tick, asking Buildbucket for the build status
[05:31:42.511] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:42.511] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:29:0) after 1m0s
[05:32:42.521] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:29:0)
[05:32:42.521] Timer tick, asking Buildbucket for the build status
[05:32:42.988] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:42.988] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:30:0) after 1m0s
[05:33:43.028] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:30:0)
[05:33:43.028] Timer tick, asking Buildbucket for the build status
[05:33:43.784] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:43.784] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:31:0) after 1m0s
[05:34:43.801] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:31:0)
[05:34:43.801] Timer tick, asking Buildbucket for the build status
[05:34:44.001] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:44.001] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:32:0) after 1m0s
[05:35:44.045] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:32:0)
[05:35:44.045] Timer tick, asking Buildbucket for the build status
[05:35:44.229] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:44.229] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:33:0) after 1m0s
[05:36:44.300] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:33:0)
[05:36:44.300] Timer tick, asking Buildbucket for the build status
[05:36:44.978] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:44.978] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:34:0) after 1m0s
[05:37:44.996] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:34:0)
[05:37:44.996] Timer tick, asking Buildbucket for the build status
[05:37:46.614] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:46.614] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:35:0) after 1m0s
[05:38:46.633] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:35:0)
[05:38:46.633] Timer tick, asking Buildbucket for the build status
[05:38:46.778] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:46.778] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:36:0) after 1m0s
[05:39:46.804] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:36:0)
[05:39:46.804] Timer tick, asking Buildbucket for the build status
[05:39:46.959] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:46.959] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:37:0) after 1m0s
[05:40:47.072] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:37:0)
[05:40:47.072] Timer tick, asking Buildbucket for the build status
[05:40:47.202] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:47.202] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:38:0) after 1m0s
[05:41:47.236] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:38:0)
[05:41:47.236] Timer tick, asking Buildbucket for the build status
[05:41:47.341] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:47.341] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:39:0) after 1m0s
[05:42:47.364] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:39:0)
[05:42:47.364] Timer tick, asking Buildbucket for the build status
[05:42:47.481] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:47.481] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:40:0) after 1m0s
[05:43:47.532] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:40:0)
[05:43:47.532] Timer tick, asking Buildbucket for the build status
[05:43:48.095] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:43:48.095] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:41:0) after 1m0s
[05:44:48.122] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:41:0)
[05:44:48.122] Timer tick, asking Buildbucket for the build status
[05:44:48.598] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:44:48.598] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:42:0) after 1m0s
[05:45:48.616] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:42:0)
[05:45:48.616] Timer tick, asking Buildbucket for the build status
[05:45:49.297] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:45:49.297] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:43:0) after 1m0s
[05:46:49.318] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:43:0)
[05:46:49.318] Timer tick, asking Buildbucket for the build status
[05:46:49.465] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:46:49.466] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:44:0) after 1m0s
[05:47:49.486] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:44:0)
[05:47:49.486] Timer tick, asking Buildbucket for the build status
[05:47:50.166] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:47:50.166] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:45:0) after 1m0s
[05:48:50.188] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:45:0)
[05:48:50.188] Timer tick, asking Buildbucket for the build status
[05:48:50.417] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:48:50.417] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:46:0) after 1m0s
[05:49:50.452] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:46:0)
[05:49:50.452] Timer tick, asking Buildbucket for the build status
[05:49:50.544] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:49:50.544] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:47:0) after 1m0s
[05:50:50.542] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:47:0)
[05:50:50.542] Timer tick, asking Buildbucket for the build status
[05:50:50.907] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:50:50.907] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:48:0) after 1m0s
[05:51:50.920] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:48:0)
[05:51:50.920] Timer tick, asking Buildbucket for the build status
[05:51:51.193] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:51:51.193] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:49:0) after 1m0s
[05:52:51.216] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:49:0)
[05:52:51.216] Timer tick, asking Buildbucket for the build status
[05:52:51.364] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:52:51.364] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:50:0) after 1m0s
[05:53:51.400] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:50:0)
[05:53:51.400] Timer tick, asking Buildbucket for the build status
[05:53:51.517] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:53:51.517] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:51:0) after 1m0s
[05:54:51.547] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:51:0)
[05:54:51.547] Timer tick, asking Buildbucket for the build status
[05:54:51.785] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:54:51.785] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:52:0) after 1m0s
[05:55:51.780] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:52:0)
[05:55:51.780] Timer tick, asking Buildbucket for the build status
[05:55:52.786] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:55:52.786] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:53:0) after 1m0s
[05:56:52.804] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:53:0)
[05:56:52.804] Timer tick, asking Buildbucket for the build status
[05:56:52.957] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:56:52.957] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:54:0) after 1m0s
[05:57:52.979] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:54:0)
[05:57:52.979] Timer tick, asking Buildbucket for the build status
[05:57:53.121] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:57:53.121] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:55:0) after 1m0s
[05:58:53.193] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:55:0)
[05:58:53.193] Timer tick, asking Buildbucket for the build status
[05:58:53.309] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:58:53.309] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:56:0) after 1m0s
[05:59:53.328] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:56:0)
[05:59:53.328] Timer tick, asking Buildbucket for the build status
[05:59:55.023] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:59:55.023] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:57:0) after 1m0s
[06:00:55.045] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:57:0)
[06:00:55.045] Timer tick, asking Buildbucket for the build status
[06:00:55.173] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:00:55.173] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:58:0) after 1m0s
[06:01:55.188] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:58:0)
[06:01:55.188] Timer tick, asking Buildbucket for the build status
[06:01:55.368] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:55.368] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:59:0) after 1m0s
[06:02:55.389] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:59:0)
[06:02:55.389] Timer tick, asking Buildbucket for the build status
[06:02:55.587] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:02:55.587] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:60:0) after 1m0s
[06:03:55.607] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:60:0)
[06:03:55.607] Timer tick, asking Buildbucket for the build status
[06:03:56.732] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:03:56.732] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:61:0) after 1m0s
[06:04:56.753] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:61:0)
[06:04:56.753] Timer tick, asking Buildbucket for the build status
[06:04:56.895] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:04:56.895] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:62:0) after 1m0s
[06:05:56.915] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:62:0)
[06:05:56.915] Timer tick, asking Buildbucket for the build status
[06:05:57.015] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:05:57.015] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:63:0) after 1m0s
[06:06:57.035] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:63:0)
[06:06:57.035] Timer tick, asking Buildbucket for the build status
[06:06:57.474] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:06:57.474] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:64:0) after 1m0s
[06:07:57.502] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:64:0)
[06:07:57.502] Timer tick, asking Buildbucket for the build status
[06:07:57.903] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:07:57.903] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:65:0) after 1m0s
[06:08:57.924] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:65:0)
[06:08:57.924] Timer tick, asking Buildbucket for the build status
[06:08:59.783] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:08:59.783] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:66:0) after 1m0s
[06:09:59.803] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:66:0)
[06:09:59.803] Timer tick, asking Buildbucket for the build status
[06:10:01.473] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:10:01.473] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:67:0) after 1m0s
[06:11:01.497] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:67:0)
[06:11:01.497] Timer tick, asking Buildbucket for the build status
[06:11:01.689] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:01.689] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:68:0) after 1m0s
[06:12:01.709] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:68:0)
[06:12:01.709] Timer tick, asking Buildbucket for the build status
[06:12:03.105] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:03.105] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:69:0) after 1m0s
[06:13:03.131] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:69:0)
[06:13:03.131] Timer tick, asking Buildbucket for the build status
[06:13:03.259] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:03.259] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:70:0) after 1m0s
[06:14:03.279] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:70:0)
[06:14:03.279] Timer tick, asking Buildbucket for the build status
[06:14:03.667] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:03.667] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:71:0) after 1m0s
[06:15:03.691] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:71:0)
[06:15:03.691] Timer tick, asking Buildbucket for the build status
[06:15:06.827] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:06.827] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:72:0) after 1m0s
[06:16:06.892] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:72:0)
[06:16:06.892] Timer tick, asking Buildbucket for the build status
[06:16:07.400] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:16:07.400] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:73:0) after 1m0s
[06:17:07.419] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:73:0)
[06:17:07.419] Timer tick, asking Buildbucket for the build status
[06:17:07.564] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:07.564] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:74:0) after 1m0s
[06:18:07.591] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:74:0)
[06:18:07.591] Timer tick, asking Buildbucket for the build status
[06:18:08.302] Build 8914326568744191520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:08.302] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9079756630816660336:75:0) after 1m0s
[06:18:27.214] Received PubSub notification, asking Buildbucket for the build status
[06:18:27.435] Build 8914326568744191520: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[06:18:27.435] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1557037106306001",
"created_by": "project:chromium",
"created_ts": "1557032725538717",
"id": "8914326568744191520",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"2819e92e9709edf2a43e0477ed81cb75177ae422\"}}",
"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-2l8d\", \"branch\": \"refs/heads/master\", \"buildername\": \"Windows deterministic\", \"buildnumber\": 13221, \"got_angle_revision\": \"6f0c5b8dd5f2647131e345edea353419d5f35ed2\", \"got_dawn_revision\": \"f54bb68f47ec718ab9175a9956e44959d2976301\", \"got_nacl_revision\": \"ca374f5112fca6b36fc5d73e117a60b82f394993\", \"got_revision\": \"2819e92e9709edf2a43e0477ed81cb75177ae422\", \"got_revision_cp\": \"refs/heads/master@{#656684}\", \"got_swarming_client_revision\": \"1b65f4e862045f3ba430a4cbd0643f5b1b66c230\", \"got_v8_revision\": \"13ec1c7990bd4ba2a72e3f5e3c7225840b81fe74\", \"got_v8_revision_cp\": \"refs/heads/7.6.97@{#1}\", \"got_webrtc_revision\": \"4fb12b0caec9faa57cfbceb0f86b0e10c32a0cc2\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#27828}\", \"mastername\": \"chromium.win\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"2819e92e9709edf2a43e0477ed81cb75177ae422\"}, \"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-2l8d\"], \"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\": [\"4284-fd5e65b\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1557032731744396",
"status": "COMPLETED",
"status_changed_ts": "1557037106692888",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/13221",
"builder:Windows deterministic",
"buildset:commit/git/2819e92e9709edf2a43e0477ed81cb75177ae422",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2819e92e9709edf2a43e0477ed81cb75177ae422",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9079756630816660336",
"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/8914326568744191520/+/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:449f337c42bc9a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1557037106704803",
"url": "https://ci.chromium.org/b/8914326568744191520",
"utcnow_ts": "1557037107429378"
}
}
[06:18:27.435] Invocation finished in 1h13m3.256852947s with status SUCCEEDED