[13:08:18.220] New invocation is queued and will start shortly
[13:08:19.412] Starting the invocation (attempt 1)
[13:08:19.455] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:08:19.455] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:08:19.455] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9059794916742226768",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"680008773c1e3f19020a3cdf9fe387fc821acb9b\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9059794916742226768",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/680008773c1e3f19020a3cdf9fe387fc821acb9b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/680008773c1e3f19020a3cdf9fe387fc821acb9b",
"gitiles_ref:refs/heads/master"
]
}
[13:08:20.883] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1576069699682599",
"id": "8894364854544367056",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"680008773c1e3f19020a3cdf9fe387fc821acb9b\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1576069700167679",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/165951",
"builder:Linux Builder (dbg)",
"buildset:commit/git/680008773c1e3f19020a3cdf9fe387fc821acb9b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/680008773c1e3f19020a3cdf9fe387fc821acb9b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9059794916742226768",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8894364854544367056/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1576069700167791",
"url": "https://ci.chromium.org/b/8894364854544367056",
"utcnow_ts": "1576069700871651"
}
}
[13:08:20.883] Task URL: https://ci.chromium.org/b/8894364854544367056
[13:08:20.883] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:2:0) after 1m0s
[13:09:20.953] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:2:0)
[13:09:20.953] Timer tick, asking Buildbucket for the build status
[13:09:21.580] Build 8894364854544367056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:09:21.580] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:3:0) after 1m0s
[13:10:21.770] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:3:0)
[13:10:21.770] Timer tick, asking Buildbucket for the build status
[13:10:21.993] Build 8894364854544367056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:10:21.993] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:4:0) after 1m0s
[13:11:22.349] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:4:0)
[13:11:22.349] Timer tick, asking Buildbucket for the build status
[13:11:22.807] Build 8894364854544367056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:11:22.807] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:5:0) after 1m0s
[13:12:23.135] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:5:0)
[13:12:23.135] Timer tick, asking Buildbucket for the build status
[13:12:23.525] Build 8894364854544367056: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:12:23.525] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:6:0) after 1m0s
[13:13:04.471] Received PubSub notification, asking Buildbucket for the build status
[13:13:04.755] Build 8894364854544367056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:23.551] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:6:0)
[13:13:23.551] Timer tick, asking Buildbucket for the build status
[13:13:23.988] Build 8894364854544367056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:23.988] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:8:0) after 1m0s
[13:14:24.157] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:8:0)
[13:14:24.157] Timer tick, asking Buildbucket for the build status
[13:14:24.406] Build 8894364854544367056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:24.406] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:9:0) after 1m0s
[13:15:24.497] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:9:0)
[13:15:24.497] Timer tick, asking Buildbucket for the build status
[13:15:24.715] Build 8894364854544367056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:24.715] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:10:0) after 1m0s
[13:16:24.881] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:10:0)
[13:16:24.881] Timer tick, asking Buildbucket for the build status
[13:16:25.034] Build 8894364854544367056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:25.034] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:11:0) after 1m0s
[13:17:25.116] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:11:0)
[13:17:25.116] Timer tick, asking Buildbucket for the build status
[13:17:25.352] Build 8894364854544367056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:25.352] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:12:0) after 1m0s
[13:18:25.667] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:12:0)
[13:18:25.667] Timer tick, asking Buildbucket for the build status
[13:18:25.891] Build 8894364854544367056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:25.891] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:13:0) after 1m0s
[13:19:25.971] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:13:0)
[13:19:25.971] Timer tick, asking Buildbucket for the build status
[13:19:26.176] Build 8894364854544367056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:26.176] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:14:0) after 1m0s
[13:20:26.343] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:14:0)
[13:20:26.343] Timer tick, asking Buildbucket for the build status
[13:20:27.269] Build 8894364854544367056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:27.269] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:15:0) after 1m0s
[13:21:27.416] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:15:0)
[13:21:27.416] Timer tick, asking Buildbucket for the build status
[13:21:29.279] Build 8894364854544367056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:29.279] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:16:0) after 1m0s
[13:22:29.332] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:16:0)
[13:22:29.332] Timer tick, asking Buildbucket for the build status
[13:22:29.763] Build 8894364854544367056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:29.763] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:17:0) after 1m0s
[13:23:29.807] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:17:0)
[13:23:29.807] Timer tick, asking Buildbucket for the build status
[13:23:29.992] Build 8894364854544367056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:29.992] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:18:0) after 1m0s
[13:24:30.067] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:18:0)
[13:24:30.067] Timer tick, asking Buildbucket for the build status
[13:24:30.348] Build 8894364854544367056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:30.348] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:19:0) after 1m0s
[13:25:30.622] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:19:0)
[13:25:30.622] Timer tick, asking Buildbucket for the build status
[13:25:30.911] Build 8894364854544367056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:30.911] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:20:0) after 1m0s
[13:26:30.958] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:20:0)
[13:26:30.959] Timer tick, asking Buildbucket for the build status
[13:26:31.168] Build 8894364854544367056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:31.168] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:21:0) after 1m0s
[13:27:31.298] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:21:0)
[13:27:31.298] Timer tick, asking Buildbucket for the build status
[13:27:33.154] Build 8894364854544367056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:33.154] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:22:0) after 1m0s
[13:28:33.216] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:22:0)
[13:28:33.216] Timer tick, asking Buildbucket for the build status
[13:28:33.583] Build 8894364854544367056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:33.583] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:23:0) after 1m0s
[13:29:33.610] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:23:0)
[13:29:33.610] Timer tick, asking Buildbucket for the build status
[13:29:33.930] Build 8894364854544367056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:33.930] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:24:0) after 1m0s
[13:30:34.163] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:24:0)
[13:30:34.163] Timer tick, asking Buildbucket for the build status
[13:30:34.934] Build 8894364854544367056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:34.934] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:25:0) after 1m0s
[13:31:35.108] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:25:0)
[13:31:35.108] Timer tick, asking Buildbucket for the build status
[13:31:35.291] Build 8894364854544367056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:35.291] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:26:0) after 1m0s
[13:32:35.447] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:26:0)
[13:32:35.447] Timer tick, asking Buildbucket for the build status
[13:32:36.123] Build 8894364854544367056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:36.123] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:27:0) after 1m0s
[13:33:36.213] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:27:0)
[13:33:36.213] Timer tick, asking Buildbucket for the build status
[13:33:36.420] Build 8894364854544367056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:36.420] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:28:0) after 1m0s
[13:34:36.577] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:28:0)
[13:34:36.577] Timer tick, asking Buildbucket for the build status
[13:34:37.823] Build 8894364854544367056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:37.823] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:29:0) after 1m0s
[13:35:37.975] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:29:0)
[13:35:37.975] Timer tick, asking Buildbucket for the build status
[13:35:38.493] Build 8894364854544367056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:38.493] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:30:0) after 1m0s
[13:36:38.621] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:30:0)
[13:36:38.621] Timer tick, asking Buildbucket for the build status
[13:36:38.783] Build 8894364854544367056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:38.783] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:31:0) after 1m0s
[13:37:38.994] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:31:0)
[13:37:38.994] Timer tick, asking Buildbucket for the build status
[13:37:39.204] Build 8894364854544367056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:39.204] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:32:0) after 1m0s
[13:38:39.320] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:32:0)
[13:38:39.320] Timer tick, asking Buildbucket for the build status
[13:38:39.507] Build 8894364854544367056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:39.507] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:33:0) after 1m0s
[13:39:39.595] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:33:0)
[13:39:39.595] Timer tick, asking Buildbucket for the build status
[13:39:39.797] Build 8894364854544367056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:39.797] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:34:0) after 1m0s
[13:40:39.792] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:34:0)
[13:40:39.792] Timer tick, asking Buildbucket for the build status
[13:40:40.050] Build 8894364854544367056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:40.050] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:35:0) after 1m0s
[13:41:40.247] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:35:0)
[13:41:40.247] Timer tick, asking Buildbucket for the build status
[13:41:40.506] Build 8894364854544367056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:40.506] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:36:0) after 1m0s
[13:42:40.550] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:36:0)
[13:42:40.550] Timer tick, asking Buildbucket for the build status
[13:42:40.784] Build 8894364854544367056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:40.784] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:37:0) after 1m0s
[13:43:41.024] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:37:0)
[13:43:41.024] Timer tick, asking Buildbucket for the build status
[13:43:41.676] Build 8894364854544367056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:41.676] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:38:0) after 1m0s
[13:44:41.805] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:38:0)
[13:44:41.805] Timer tick, asking Buildbucket for the build status
[13:44:42.682] Build 8894364854544367056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:42.682] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:39:0) after 1m0s
[13:45:42.731] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:39:0)
[13:45:42.731] Timer tick, asking Buildbucket for the build status
[13:45:42.891] Build 8894364854544367056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:42.891] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:40:0) after 1m0s
[13:46:43.194] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:40:0)
[13:46:43.194] Timer tick, asking Buildbucket for the build status
[13:46:43.354] Build 8894364854544367056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:43.354] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:41:0) after 1m0s
[13:47:43.500] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:41:0)
[13:47:43.500] Timer tick, asking Buildbucket for the build status
[13:47:44.302] Build 8894364854544367056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:44.302] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:42:0) after 1m0s
[13:48:44.388] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:42:0)
[13:48:44.388] Timer tick, asking Buildbucket for the build status
[13:48:45.777] Build 8894364854544367056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:45.777] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:43:0) after 1m0s
[13:49:45.843] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:43:0)
[13:49:45.843] Timer tick, asking Buildbucket for the build status
[13:49:45.995] Build 8894364854544367056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:45.995] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:44:0) after 1m0s
[13:50:46.197] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:44:0)
[13:50:46.197] Timer tick, asking Buildbucket for the build status
[13:50:47.681] Build 8894364854544367056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:47.681] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:45:0) after 1m0s
[13:51:47.708] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:45:0)
[13:51:47.708] Timer tick, asking Buildbucket for the build status
[13:51:47.951] Build 8894364854544367056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:47.951] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:46:0) after 1m0s
[13:52:48.010] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:46:0)
[13:52:48.010] Timer tick, asking Buildbucket for the build status
[13:52:48.757] Build 8894364854544367056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:48.757] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:47:0) after 1m0s
[13:53:48.818] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:47:0)
[13:53:48.818] Timer tick, asking Buildbucket for the build status
[13:53:49.058] Build 8894364854544367056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:49.058] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:48:0) after 1m0s
[13:54:49.130] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:48:0)
[13:54:49.130] Timer tick, asking Buildbucket for the build status
[13:54:49.686] Build 8894364854544367056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:49.686] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:49:0) after 1m0s
[13:55:49.893] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:49:0)
[13:55:49.893] Timer tick, asking Buildbucket for the build status
[13:55:50.090] Build 8894364854544367056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:50.090] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:50:0) after 1m0s
[13:56:50.159] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:50:0)
[13:56:50.159] Timer tick, asking Buildbucket for the build status
[13:56:52.546] Build 8894364854544367056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:52.546] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:51:0) after 1m0s
[13:57:52.654] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:51:0)
[13:57:52.654] Timer tick, asking Buildbucket for the build status
[13:57:52.892] Build 8894364854544367056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:52.892] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:52:0) after 1m0s
[13:58:52.894] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:52:0)
[13:58:52.894] Timer tick, asking Buildbucket for the build status
[13:58:53.127] Build 8894364854544367056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:53.127] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:53:0) after 1m0s
[13:59:53.237] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:53:0)
[13:59:53.237] Timer tick, asking Buildbucket for the build status
[13:59:53.446] Build 8894364854544367056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:53.446] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:54:0) after 1m0s
[14:00:53.531] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:54:0)
[14:00:53.531] Timer tick, asking Buildbucket for the build status
[14:00:53.691] Build 8894364854544367056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:53.691] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:55:0) after 1m0s
[14:01:53.785] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:55:0)
[14:01:53.785] Timer tick, asking Buildbucket for the build status
[14:01:54.377] Build 8894364854544367056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:54.377] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:56:0) after 1m0s
[14:02:54.422] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:56:0)
[14:02:54.422] Timer tick, asking Buildbucket for the build status
[14:02:54.636] Build 8894364854544367056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:54.636] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:57:0) after 1m0s
[14:03:54.853] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:57:0)
[14:03:54.855] Timer tick, asking Buildbucket for the build status
[14:03:55.188] Build 8894364854544367056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:55.188] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059794916742226768:58:0) after 1m0s
[14:04:18.504] Received PubSub notification, asking Buildbucket for the build status
[14:04:18.970] Build 8894364854544367056: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[14:04:18.970] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1576073057280198",
"created_by": "project:chromium",
"created_ts": "1576069699682599",
"id": "8894364854544367056",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"680008773c1e3f19020a3cdf9fe387fc821acb9b\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"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-east1-d-8-91-4hgi\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 165951, \"got_angle_revision\": \"e5c2089fc52ae7eb3be58e24737824a2cb85216c\", \"got_dawn_revision\": \"e8e9d5daf59fcf19f1a2e1b36e8da7437e0264f5\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"680008773c1e3f19020a3cdf9fe387fc821acb9b\", \"got_revision_cp\": \"refs/heads/master@{#723797}\", \"got_swarming_client_revision\": \"885b3febcc170a60f25795304e60927b77d1e92d\", \"got_swiftshader_revision\": \"72c398f5632fbefa1e4f32468cc0a1074276c088\", \"got_v8_revision\": \"4547e0be7faffbc4b81702219226b67e833b8635\", \"got_v8_revision_cp\": \"refs/heads/8.1.13@{#1}\", \"got_webrtc_revision\": \"3c4fda2ed8140a330634c0a2704a3cf0311a89de\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30057}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"680008773c1e3f19020a3cdf9fe387fc821acb9b\", \"swarm_hashes_refs/heads/master(at){#723797}_without_patch\": {\"accessibility_unittests\": \"91586d85bd70338a179fd1c6ebbda3e468bf41ff\", \"angle_unittests\": \"a0fa5d97357b77ea287cdcc8c014e95a5ea4f7dc\", \"app_shell_unittests\": \"e43cb666035d19459a6905f88e0ea17834568175\", \"aura_unittests\": \"6121faaf0cc3bd04a5ae0f984dd145fdc3418fee\", \"base_unittests\": \"b24b3b14fe40218cc25d48ff3aa0c333cc0ca9b8\", \"base_util_unittests\": \"485c91b0a97c1d80af2d66165bb2223657b077f3\", \"blink_common_unittests\": \"a3f0b6d6bf2182212ac7f7a4c615a803a2aec0e4\", \"blink_fuzzer_unittests\": \"1527c75494375e01360d0181ce255d98bb99f679\", \"blink_heap_unittests\": \"5be7b8feca541079e1f64c48a9c3cce6bf67678b\", \"blink_platform_unittests\": \"e3b9c605f5051dbb4d793c55399e06de48ecc4fb\", \"blink_python_tests\": \"0c5be512440e7b3172c054b69fc6c96a3404de53\", \"blink_unittests\": \"0d1bfbbe635e9cf0e840d9b19484577e7e906b82\", \"blink_web_tests\": \"b2ef616388d74a7fcf8fb96b2e0ec8c627c7ef5e\", \"boringssl_crypto_tests\": \"11f1a077fa0155406b0c8d3bef04b839391a6e2d\", \"boringssl_ssl_tests\": \"3da0a7fbee1468544df83d41017ded0ac30bc959\", \"browser_tests\": \"70675d7ed4ed2ee1f01fb0dda5c400d8becf5d89\", \"cacheinvalidation_unittests\": \"c6155d973b1ded198a26e03827ebb7c8c38128d9\", \"capture_unittests\": \"46e3180efc450fc5d880f928122dff0cd86a3c06\", \"cast_unittests\": \"b3c99fa03ce679ad3d34e1cb8da6e82aa5eaa388\", \"cc_unittests\": \"daff1afdda20c8869fac4cf00e42e26f09f58775\", \"chrome_app_unittests\": \"9c9683e5d96792cbb23e4c71e152f125793a6218\", \"chromedriver_unittests\": \"577989409b32dfcca55a8a34d8b005d83ad895dc\", \"components_browsertests\": \"b61ac6e0139ea4e6b908956b819f8f8da3f13430\", \"components_unittests\": \"d715edecc2920bdb63af781a3635511352703719\", \"compositor_unittests\": \"7cfa2362de2f845a7e7bd6dfb7571713ba62c42a\", \"content_browsertests\": \"cb0e4e3a5ee3ee2268d770f9156ba01fa870e674\", \"content_shell_crash_test\": \"3549e5d8724968f04770b0fab220e80501dbe52d\", \"content_unittests\": \"322bc325d844b3e7eea1e90e515c685bc3ce3671\", \"crashpad_tests\": \"a0bd57225e265c9c255f49c6a9577d071b8397ed\", \"cronet_tests\": \"31ece7d3c50c67a650a1f17eca3318339aaaa339\", \"cronet_unittests\": \"0e4327e626e185242ba219951a04333abd6ef7f1\", \"crypto_unittests\": \"d80790968ca92932e10de78677ade7089acad773\", \"dbus_unittests\": \"6a2855af41cd183e5d170fcd65e88bae8a26dede\", \"device_unittests\": \"a1a88349298ac56790c0f9836d63a5758cb268eb\", \"devtools_lint_check\": \"17afeea451e503777274f62cf843cc7513e53c4f\", \"devtools_type_check\": \"76e687f6a547cde49dee1f0ff61039e8d30f1e70\", \"display_unittests\": \"696d53256e478c65f51ed495928894b1da7427ec\", \"events_unittests\": \"ad5365e168f998132d5df0d2210e1e6329fbaa98\", \"extensions_browsertests\": \"4f4fee41694a3ceb5feb8fa0e4394de561123110\", \"extensions_unittests\": \"d87d3b4a367252275283fd3462466dc73670f120\", \"filesystem_service_unittests\": \"08abc8f3a5ab91412d49d490d51b53d4bee71c4d\", \"flatbuffers_unittests\": \"034d1c63a8199a680c9bbf29b5866b1c851e28ee\", \"gcm_unit_tests\": \"1164bb8723770a086a2ba42022c769a3cf1b65c0\", \"gfx_unittests\": \"292444e7149cc47e1fd170ebeae08fea59401aa8\", \"gin_unittests\": \"bb483c4f25f9aa714d8aea69e98e7ecbf61f19c4\", \"google_apis_unittests\": \"27dc38f86668912a9bf8a06a68deb9c7e6813de1\", \"gpu_unittests\": \"727a023eee5e1b5ec8b0ab08951495cf652bd5c0\", \"grit_python_unittests\": \"c949091fb2717b913781a9a7cb15efe1537c4608\", \"gwp_asan_unittests\": \"b2939cd5fd508c3da068939b0cdf718613826a50\", \"headless_browsertests\": \"c948e724a92d8d710c294dc0a9f7b99d68f4e298\", \"headless_unittests\": \"306cdd679acd94a9133cb7ce2200d61b6177968e\", \"interactive_ui_tests\": \"8d343d64a301cf5354fd2340774881b8cb15750c\", \"ipc_tests\": \"7dfbc62764178e3a7846ee7a0de24d3b06b3a4e1\", \"jingle_unittests\": \"7a1400de249713e8679e9b3d2cfceb6301f724d8\", \"latency_unittests\": \"1e6ac5b5fc5bd7d818f4d44c3a824871ea4017a2\", \"libjingle_xmpp_unittests\": \"ff55923dfa91fc3e712a2e784fa2e6c8d1df00d4\", \"media_blink_unittests\": \"f3fcc556d6c1398145739f76e76f19b035dc2161\", \"media_service_unittests\": \"5b48d66a04a5a68ebac9c513a83ef9624a64c13c\", \"media_unittests\": \"55c29f8b55432fa13268fa7a970c29fcd2fc69e0\", \"message_center_unittests\": \"8bf3ebb7a85b5c0fbf7fbb2e16a9fe91f46eaecd\", \"metrics_python_tests\": \"4182e83085af2f5cbf85869eb0e6e48ddc805826\", \"midi_unittests\": \"656d8b45a0c954ffecc112a792f1078692cfa50b\", \"mojo_core_unittests\": \"7b73240ca727f3e980fb9795ce869c16fac4099d\", \"mojo_unittests\": \"d03ee1425cf2bb7ab8b2428cf3ee85361363b50e\", \"nacl_helper_nonsfi_unittests\": \"c2d453b20530d77066a37ad40698718617fb458f\", \"nacl_loader_unittests\": \"0a8cc4af3f2cb9a114b24f30419a5224e2e0e271\", \"native_theme_unittests\": \"af3a95c57d6b8312ca754ee867f031a0f59bf3ed\", \"net_unittests\": \"7e0ffd0046e04a5fd7c79013efba7c86b1bdb02a\", \"pdf_unittests\": \"5397d624e23a3ae6ef6a0e4982ad3b07db9056ee\", \"perfetto_unittests\": \"2469bcaf7a1e1bb170dcf2b30f3e4465f00a0a54\", \"ppapi_unittests\": \"2e93ecd06ef842db081d2673b86ce81f33fcfdac\", \"printing_unittests\": \"d6bdb5036cd9dce110c9bb96aabf52f10bcab583\", \"remoting_unittests\": \"fa7861455d8367e43e9447490079cbd01e113098\", \"sandbox_linux_unittests\": \"0caa77dd588ea291810a44b1ca72eb99fc894e81\", \"service_manager_unittests\": \"f125da82536a10f22b05baaee700d37ee294e158\", \"services_unittests\": \"7ccce809bdb23c6c601c189bb02fd8f0486eae43\", \"shell_dialogs_unittests\": \"9ce2d5ed9f11b8789ca11bc80cf7b7c87e052140\", \"skia_unittests\": \"52d83b1a27b83918152676ae2afc251db4e5aa69\", \"snapshot_unittests\": \"8f9b04f443559e9f7ea4492c6dadb409acbbaf0e\", \"sql_unittests\": \"d52ef03a4d997eb7f9bbf468b9c2325083842dfc\", \"storage_unittests\": \"c285776029f8fb477d222e42495e3f091d6ccacc\", \"sync_integration_tests\": \"e8d83e50129cd8059303d6c254c3191e53523018\", \"telemetry_gpu_unittests\": \"4534a4e5a1460b465215d921b48e2fe0cc285ddb\", \"telemetry_perf_unittests\": \"00a068e64eaa03689befd79a8404e1e3ad0e91a3\", \"telemetry_unittests\": \"0c30eeb519e7f05167ebb28a8bf6263eaac0bdca\", \"traffic_annotation_auditor_unittests\": \"f29250601fc3defbb0d199ff0f56ab40c9b60722\", \"ui_base_unittests\": \"e40be816e4c1cc7ef3549f321797f87aaf937cac\", \"ui_touch_selection_unittests\": \"8282138e061b7a27f9262fbabbfdef2661011d32\", \"unit_tests\": \"1425afd81561ccb2eabc9be0d3f30d3abbd6744b\", \"url_unittests\": \"a94d03792bc2852ff36cb1ac1f884dfa1540f907\", \"views_perftests\": \"26fa6ce6491a57b03db443c1b4d067cf5f3c59d2\", \"views_unittests\": \"34182e430d40f7034b617ba1b5377fffad1b53c1\", \"viz_unittests\": \"958a3b4f37556a7005d5f7189eac4086a7633701\", \"vr_common_unittests\": \"f2d8862af7da4e0499a2f4a1b43ea7ef11c619fe\", \"vr_pixeltests\": \"7c74cb9d764dbee48c31a93d60e793060586b488\", \"webdriver_wpt_tests\": \"41d162ac94b8f075a5921a1ace111fced426d313\", \"weblayer_browsertests\": \"fbd83ac89ebde6d7b97dbae762afae2fb46b7245\", \"wm_unittests\": \"2b8081edee9caf2bcd1d3d811b21c79ddc557f18\", \"wtf_unittests\": \"43019f5a04b08fe499e79b7a3810716593d054ba\", \"xr_browser_tests\": \"b0b35e017d2ae4f1853f08fb73311fba977494e5\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_5315cf154f9c1ae6b2621094599bc107171a849e1c0f232ec66a504ee800a364_v2\", \"builder_578c225c2c50d5653cce8ddfaab266952737b31d66b6113fbb64cc6f5229aebd_v2\", \"builder_957c0f1a1c74c3ada6665996f2ff614223d5da32e2cc2316ff7cc4c9c4beae5c_v2\", \"builder_97b15bccca8d35086c1bc50eecf0b53d98289cdc65be75a287efcc384d1f34ed_v2\", \"builder_a79c4eaa042f1e6bc9c911abe5d62abb39090016fd865bb5d4738a03724242e0_v2\", \"builder_c38832248eaa59248ebe0e5d5ae62a6bc955f53f6a99a9c0e5cded93d679f9bc_v2\", \"builder_d2010e0a6a440fb89731b061caf7d596c92723d209ffefd62489cc037db4f7a7_v2\", \"builder_fac2959e29eeee13eb43de2791ff2fcb7aa7ab15952f0beca21f4615b55fa2a2_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-east1-d-8-91-4hgi\"], \"image\": [\"chrome-xenial-19120100-f3a09baa543\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"4709-f2c88b6\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1576069982999730",
"status": "COMPLETED",
"status_changed_ts": "1576073057910390",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/165951",
"builder:Linux Builder (dbg)",
"buildset:commit/git/680008773c1e3f19020a3cdf9fe387fc821acb9b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/680008773c1e3f19020a3cdf9fe387fc821acb9b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9059794916742226768",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8894364854544367056/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Linux",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:490de4a5ca602410",
"user_agent:luci-scheduler"
],
"updated_ts": "1576073057976680",
"url": "https://ci.chromium.org/b/8894364854544367056",
"utcnow_ts": "1576073058954956"
}
}
[14:04:18.970] Invocation finished in 56m0.766807073s with status SUCCEEDED