[11:17:54.180] New invocation is queued and will start shortly
[11:17:55.541] Starting the invocation (attempt 1)
[11:17:55.576] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:17:55.576] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:17:55.576] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9055996789963051920",
"parameters_json": "{\"builder_name\":\"Deterministic Linux (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"0195b499c85ff51fd886cb862e4ce820f3b1bc6f\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Deterministic Linux (dbg)",
"scheduler_invocation_id:9055996789963051920",
"scheduler_job_id:chromium/Deterministic Linux (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/0195b499c85ff51fd886cb862e4ce820f3b1bc6f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0195b499c85ff51fd886cb862e4ce820f3b1bc6f",
"gitiles_ref:refs/heads/master"
]
}
[11:17:57.537] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1579691876151448",
"id": "8890566727231034192",
"parameters_json": "{\"builder_name\": \"Deterministic Linux (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0195b499c85ff51fd886cb862e4ce820f3b1bc6f\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1579691876781254",
"tags": [
"build_address:luci.chromium.ci/Deterministic Linux (dbg)/14445",
"builder:Deterministic Linux (dbg)",
"buildset:commit/git/0195b499c85ff51fd886cb862e4ce820f3b1bc6f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0195b499c85ff51fd886cb862e4ce820f3b1bc6f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9055996789963051920",
"scheduler_job_id:chromium/Deterministic Linux (dbg)",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8890566727231034192/+/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": "1579691876781353",
"url": "https://ci.chromium.org/b/8890566727231034192",
"utcnow_ts": "1579691877525855"
}
}
[11:17:57.537] Task URL: https://ci.chromium.org/b/8890566727231034192
[11:17:57.537] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:2:0) after 1m0s
[11:17:59.599] Received PubSub notification, asking Buildbucket for the build status
[11:17:59.965] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:57.670] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:2:0)
[11:18:57.670] Timer tick, asking Buildbucket for the build status
[11:18:57.945] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:57.945] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:4:0) after 1m0s
[11:19:58.008] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:4:0)
[11:19:58.008] Timer tick, asking Buildbucket for the build status
[11:19:58.987] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:58.987] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:5:0) after 1m0s
[11:20:59.048] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:5:0)
[11:20:59.048] Timer tick, asking Buildbucket for the build status
[11:20:59.226] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:59.226] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:6:0) after 1m0s
[11:21:59.302] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:6:0)
[11:21:59.302] Timer tick, asking Buildbucket for the build status
[11:21:59.546] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:59.546] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:7:0) after 1m0s
[11:22:59.919] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:7:0)
[11:22:59.919] Timer tick, asking Buildbucket for the build status
[11:23:00.504] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:00.504] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:8:0) after 1m0s
[11:24:00.593] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:8:0)
[11:24:00.593] Timer tick, asking Buildbucket for the build status
[11:24:01.068] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:01.068] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:9:0) after 1m0s
[11:25:01.329] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:9:0)
[11:25:01.329] Timer tick, asking Buildbucket for the build status
[11:25:01.852] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:01.852] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:10:0) after 1m0s
[11:26:01.939] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:10:0)
[11:26:01.939] Timer tick, asking Buildbucket for the build status
[11:26:02.566] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:02.566] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:11:0) after 1m0s
[11:27:02.629] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:11:0)
[11:27:02.629] Timer tick, asking Buildbucket for the build status
[11:27:02.888] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:02.888] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:12:0) after 1m0s
[11:28:02.964] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:12:0)
[11:28:02.964] Timer tick, asking Buildbucket for the build status
[11:28:03.139] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:03.139] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:13:0) after 1m0s
[11:29:03.278] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:13:0)
[11:29:03.278] Timer tick, asking Buildbucket for the build status
[11:29:03.959] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:03.959] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:14:0) after 1m0s
[11:30:03.999] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:14:0)
[11:30:03.999] Timer tick, asking Buildbucket for the build status
[11:30:04.612] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:04.612] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:15:0) after 1m0s
[11:31:04.875] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:15:0)
[11:31:04.875] Timer tick, asking Buildbucket for the build status
[11:31:05.685] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:05.685] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:16:0) after 1m0s
[11:32:05.945] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:16:0)
[11:32:05.945] Timer tick, asking Buildbucket for the build status
[11:32:06.372] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:06.372] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:17:0) after 1m0s
[11:33:06.416] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:17:0)
[11:33:06.416] Timer tick, asking Buildbucket for the build status
[11:33:07.412] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:07.412] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:18:0) after 1m0s
[11:34:07.480] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:18:0)
[11:34:07.480] Timer tick, asking Buildbucket for the build status
[11:34:07.629] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:07.629] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:19:0) after 1m0s
[11:35:07.632] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:19:0)
[11:35:07.632] Timer tick, asking Buildbucket for the build status
[11:35:07.885] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:07.885] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:20:0) after 1m0s
[11:36:08.058] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:20:0)
[11:36:08.058] Timer tick, asking Buildbucket for the build status
[11:36:08.265] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:08.265] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:21:0) after 1m0s
[11:37:08.382] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:21:0)
[11:37:08.382] Timer tick, asking Buildbucket for the build status
[11:37:09.014] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:09.014] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:22:0) after 1m0s
[11:38:09.155] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:22:0)
[11:38:09.155] Timer tick, asking Buildbucket for the build status
[11:38:09.321] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:09.321] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:23:0) after 1m0s
[11:39:09.470] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:23:0)
[11:39:09.470] Timer tick, asking Buildbucket for the build status
[11:39:10.049] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:10.049] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:24:0) after 1m0s
[11:40:10.115] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:24:0)
[11:40:10.115] Timer tick, asking Buildbucket for the build status
[11:40:11.042] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:11.042] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:25:0) after 1m0s
[11:41:11.238] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:25:0)
[11:41:11.238] Timer tick, asking Buildbucket for the build status
[11:41:12.143] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:12.143] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:26:0) after 1m0s
[11:42:12.191] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:26:0)
[11:42:12.191] Timer tick, asking Buildbucket for the build status
[11:42:12.369] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:12.369] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:27:0) after 1m0s
[11:43:12.422] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:27:0)
[11:43:12.422] Timer tick, asking Buildbucket for the build status
[11:43:12.726] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:12.726] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:28:0) after 1m0s
[11:44:12.747] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:28:0)
[11:44:12.747] Timer tick, asking Buildbucket for the build status
[11:44:13.613] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:13.613] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:29:0) after 1m0s
[11:45:13.677] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:29:0)
[11:45:13.677] Timer tick, asking Buildbucket for the build status
[11:45:13.894] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:13.894] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:30:0) after 1m0s
[11:46:14.043] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:30:0)
[11:46:14.043] Timer tick, asking Buildbucket for the build status
[11:46:15.102] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:15.102] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:31:0) after 1m0s
[11:47:15.178] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:31:0)
[11:47:15.178] Timer tick, asking Buildbucket for the build status
[11:47:15.800] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:15.800] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:32:0) after 1m0s
[11:48:15.858] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:32:0)
[11:48:15.858] Timer tick, asking Buildbucket for the build status
[11:48:16.026] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:16.026] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:33:0) after 1m0s
[11:49:16.081] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:33:0)
[11:49:16.081] Timer tick, asking Buildbucket for the build status
[11:49:16.638] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:16.638] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:34:0) after 1m0s
[11:50:16.720] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:34:0)
[11:50:16.720] Timer tick, asking Buildbucket for the build status
[11:50:16.906] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:16.906] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:35:0) after 1m0s
[11:51:16.934] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:35:0)
[11:51:16.934] Timer tick, asking Buildbucket for the build status
[11:51:17.373] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:17.373] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:36:0) after 1m0s
[11:52:17.440] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:36:0)
[11:52:17.440] Timer tick, asking Buildbucket for the build status
[11:52:17.734] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:17.735] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:37:0) after 1m0s
[11:53:17.864] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:37:0)
[11:53:17.864] Timer tick, asking Buildbucket for the build status
[11:53:18.092] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:18.092] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:38:0) after 1m0s
[11:54:18.153] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:38:0)
[11:54:18.153] Timer tick, asking Buildbucket for the build status
[11:54:18.568] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:18.568] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:39:0) after 1m0s
[11:55:18.601] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:39:0)
[11:55:18.601] Timer tick, asking Buildbucket for the build status
[11:55:18.842] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:18.842] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:40:0) after 1m0s
[11:56:18.863] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:40:0)
[11:56:18.863] Timer tick, asking Buildbucket for the build status
[11:56:19.182] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:19.182] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:41:0) after 1m0s
[11:57:19.207] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:41:0)
[11:57:19.207] Timer tick, asking Buildbucket for the build status
[11:57:19.366] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:19.366] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:42:0) after 1m0s
[11:58:19.391] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:42:0)
[11:58:19.391] Timer tick, asking Buildbucket for the build status
[11:58:19.589] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:19.589] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:43:0) after 1m0s
[11:59:19.645] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:43:0)
[11:59:19.645] Timer tick, asking Buildbucket for the build status
[11:59:20.243] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:20.243] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:44:0) after 1m0s
[12:00:20.364] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:44:0)
[12:00:20.364] Timer tick, asking Buildbucket for the build status
[12:00:20.527] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:20.527] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:45:0) after 1m0s
[12:01:20.584] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:45:0)
[12:01:20.584] Timer tick, asking Buildbucket for the build status
[12:01:20.716] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:20.716] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:46:0) after 1m0s
[12:02:20.817] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:46:0)
[12:02:20.817] Timer tick, asking Buildbucket for the build status
[12:02:21.171] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:21.171] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:47:0) after 1m0s
[12:03:21.214] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:47:0)
[12:03:21.214] Timer tick, asking Buildbucket for the build status
[12:03:21.520] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:21.520] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:48:0) after 1m0s
[12:04:21.558] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:48:0)
[12:04:21.558] Timer tick, asking Buildbucket for the build status
[12:04:21.827] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:21.827] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:49:0) after 1m0s
[12:05:21.884] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:49:0)
[12:05:21.884] Timer tick, asking Buildbucket for the build status
[12:05:22.092] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:22.092] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:50:0) after 1m0s
[12:06:22.116] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:50:0)
[12:06:22.116] Timer tick, asking Buildbucket for the build status
[12:06:22.727] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:22.727] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:51:0) after 1m0s
[12:07:22.797] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:51:0)
[12:07:22.797] Timer tick, asking Buildbucket for the build status
[12:07:23.077] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:23.077] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:52:0) after 1m0s
[12:08:23.115] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:52:0)
[12:08:23.115] Timer tick, asking Buildbucket for the build status
[12:08:23.315] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:23.315] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:53:0) after 1m0s
[12:09:23.349] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:53:0)
[12:09:23.349] Timer tick, asking Buildbucket for the build status
[12:09:24.261] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:24.261] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:54:0) after 1m0s
[12:10:24.287] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:54:0)
[12:10:24.287] Timer tick, asking Buildbucket for the build status
[12:10:24.526] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:24.526] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:55:0) after 1m0s
[12:11:24.560] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:55:0)
[12:11:24.560] Timer tick, asking Buildbucket for the build status
[12:11:24.876] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:24.876] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:56:0) after 1m0s
[12:12:24.935] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:56:0)
[12:12:24.935] Timer tick, asking Buildbucket for the build status
[12:12:25.575] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:25.575] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:57:0) after 1m0s
[12:13:25.704] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:57:0)
[12:13:25.704] Timer tick, asking Buildbucket for the build status
[12:13:25.860] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:25.860] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:58:0) after 1m0s
[12:14:25.881] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:58:0)
[12:14:25.881] Timer tick, asking Buildbucket for the build status
[12:14:26.364] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:26.364] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:59:0) after 1m0s
[12:15:26.408] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:59:0)
[12:15:26.408] Timer tick, asking Buildbucket for the build status
[12:15:26.596] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:26.596] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:60:0) after 1m0s
[12:16:26.727] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:60:0)
[12:16:26.727] Timer tick, asking Buildbucket for the build status
[12:16:27.276] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:27.276] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:61:0) after 1m0s
[12:17:27.358] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:61:0)
[12:17:27.358] Timer tick, asking Buildbucket for the build status
[12:17:27.596] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:27.596] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:62:0) after 1m0s
[12:18:27.654] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:62:0)
[12:18:27.654] Timer tick, asking Buildbucket for the build status
[12:18:28.445] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:28.445] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:63:0) after 1m0s
[12:19:28.471] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:63:0)
[12:19:28.471] Timer tick, asking Buildbucket for the build status
[12:19:28.681] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:28.681] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:64:0) after 1m0s
[12:20:28.736] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:64:0)
[12:20:28.736] Timer tick, asking Buildbucket for the build status
[12:20:28.995] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:28.995] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:65:0) after 1m0s
[12:21:29.025] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:65:0)
[12:21:29.025] Timer tick, asking Buildbucket for the build status
[12:21:29.194] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:29.194] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:66:0) after 1m0s
[12:22:29.220] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:66:0)
[12:22:29.220] Timer tick, asking Buildbucket for the build status
[12:22:29.722] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:29.722] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:67:0) after 1m0s
[12:23:29.773] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:67:0)
[12:23:29.773] Timer tick, asking Buildbucket for the build status
[12:23:32.139] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:32.139] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:68:0) after 1m0s
[12:24:32.148] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:68:0)
[12:24:32.148] Timer tick, asking Buildbucket for the build status
[12:24:32.700] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:32.700] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:69:0) after 1m0s
[12:25:32.721] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:69:0)
[12:25:32.721] Timer tick, asking Buildbucket for the build status
[12:25:32.972] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:32.972] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:70:0) after 1m0s
[12:26:33.034] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:70:0)
[12:26:33.034] Timer tick, asking Buildbucket for the build status
[12:26:33.678] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:33.678] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:71:0) after 1m0s
[12:27:33.735] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:71:0)
[12:27:33.735] Timer tick, asking Buildbucket for the build status
[12:27:34.554] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:34.554] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:72:0) after 1m0s
[12:28:34.635] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:72:0)
[12:28:34.635] Timer tick, asking Buildbucket for the build status
[12:28:35.563] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:35.563] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:73:0) after 1m0s
[12:29:35.590] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:73:0)
[12:29:35.590] Timer tick, asking Buildbucket for the build status
[12:29:35.980] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:35.980] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:74:0) after 1m0s
[12:30:36.009] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:74:0)
[12:30:36.009] Timer tick, asking Buildbucket for the build status
[12:30:36.914] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:36.914] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:75:0) after 1m0s
[12:31:37.061] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:75:0)
[12:31:37.061] Timer tick, asking Buildbucket for the build status
[12:31:37.238] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:37.238] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:76:0) after 1m0s
[12:32:37.297] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:76:0)
[12:32:37.297] Timer tick, asking Buildbucket for the build status
[12:32:37.480] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:37.480] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:77:0) after 1m0s
[12:33:37.758] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:77:0)
[12:33:37.758] Timer tick, asking Buildbucket for the build status
[12:33:37.995] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:37.995] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:78:0) after 1m0s
[12:34:38.061] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:78:0)
[12:34:38.061] Timer tick, asking Buildbucket for the build status
[12:34:38.215] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:38.215] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:79:0) after 1m0s
[12:35:38.318] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:79:0)
[12:35:38.318] Timer tick, asking Buildbucket for the build status
[12:35:38.955] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:38.955] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:80:0) after 1m0s
[12:36:39.162] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:80:0)
[12:36:39.162] Timer tick, asking Buildbucket for the build status
[12:36:39.403] Build 8890566727231034192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:39.403] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9055996789963051920:81:0) after 1m0s
[12:37:13.961] Received PubSub notification, asking Buildbucket for the build status
[12:37:14.150] Build 8890566727231034192: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[12:37:14.150] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1579696633115467",
"created_by": "project:chromium",
"created_ts": "1579691876151448",
"id": "8890566727231034192",
"parameters_json": "{\"builder_name\": \"Deterministic Linux (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0195b499c85ff51fd886cb862e4ce820f3b1bc6f\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"jobs\": 500.0, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-central1-b-32-0-tt25\", \"branch\": \"refs/heads/master\", \"buildername\": \"Deterministic Linux (dbg)\", \"buildnumber\": 14445, \"got_angle_revision\": \"8c396a9cc4a83eaf8828dac7f0a39ed30714c58a\", \"got_dawn_revision\": \"80880ee9985484d39355d2827ce5cb9343a07e91\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"0195b499c85ff51fd886cb862e4ce820f3b1bc6f\", \"got_revision_cp\": \"refs/heads/master@{#733937}\", \"got_swarming_client_revision\": \"885b3febcc170a60f25795304e60927b77d1e92d\", \"got_swiftshader_revision\": \"b766e5e7fbf42e48be36afc80e2c448e339c599e\", \"got_v8_revision\": \"4cde67a85fd4c29f78551e5aebca1fc8912876e8\", \"got_v8_revision_cp\": \"refs/heads/8.1.244@{#1}\", \"got_webrtc_revision\": \"06df1e1c4655a1048bfeb30e090ab52005f1e841\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30339}\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0195b499c85ff51fd886cb862e4ce820f3b1bc6f\"}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_6a7ea7a29847c5e3c31f9e7f29ef0a1aa1bd0d542498088304c0bb7ba89c3242_v2\", \"builder_940b8dd4638ac05ec7d44839e381a2baf02720b3eac518443ac04ffe6b1809c0_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-32-0-tt25\"], \"image\": [\"chrome-xenial-20011200-47b90f1b63b\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-32\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"4792-2014df3\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1579691878537397",
"status": "COMPLETED",
"status_changed_ts": "1579696633661816",
"tags": [
"build_address:luci.chromium.ci/Deterministic Linux (dbg)/14445",
"builder:Deterministic Linux (dbg)",
"buildset:commit/git/0195b499c85ff51fd886cb862e4ce820f3b1bc6f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0195b499c85ff51fd886cb862e4ce820f3b1bc6f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9055996789963051920",
"scheduler_job_id:chromium/Deterministic Linux (dbg)",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8890566727231034192/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Linux",
"swarming_tag:recipe_name:swarming/deterministic_build",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:49e5caad75872e10",
"user_agent:luci-scheduler"
],
"updated_ts": "1579696633666166",
"url": "https://ci.chromium.org/b/8890566727231034192",
"utcnow_ts": "1579696634136283"
}
}
[12:37:14.150] Invocation finished in 1h19m19.987253083s with status SUCCEEDED