[22:02:24.336] New invocation is queued and will start shortly
[22:02:25.450] Starting the invocation (attempt 1)
[22:02:25.503] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:02:25.503] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:02:25.503] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9059670716996053024",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"e9feda7dfd20ab2d94c16f3210f5807205dae3af\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9059670716996053024",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/e9feda7dfd20ab2d94c16f3210f5807205dae3af",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e9feda7dfd20ab2d94c16f3210f5807205dae3af",
"gitiles_ref:refs/heads/master"
]
}
[22:02:26.933] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1576188145703846",
"id": "8894240654889493280",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"e9feda7dfd20ab2d94c16f3210f5807205dae3af\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1576188146156375",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/166017",
"builder:Linux Builder (dbg)",
"buildset:commit/git/e9feda7dfd20ab2d94c16f3210f5807205dae3af",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e9feda7dfd20ab2d94c16f3210f5807205dae3af",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9059670716996053024",
"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/8894240654889493280/+/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": "1576188146156544",
"url": "https://ci.chromium.org/b/8894240654889493280",
"utcnow_ts": "1576188146899706"
}
}
[22:02:26.933] Task URL: https://ci.chromium.org/b/8894240654889493280
[22:02:26.933] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:2:0) after 1m0s
[22:03:26.994] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:2:0)
[22:03:26.994] Timer tick, asking Buildbucket for the build status
[22:03:27.421] Build 8894240654889493280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:03:27.422] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:3:0) after 1m0s
[22:04:27.507] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:3:0)
[22:04:27.507] Timer tick, asking Buildbucket for the build status
[22:04:27.723] Build 8894240654889493280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:04:27.723] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:4:0) after 1m0s
[22:05:27.762] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:4:0)
[22:05:27.762] Timer tick, asking Buildbucket for the build status
[22:05:28.003] Build 8894240654889493280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:05:28.003] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:5:0) after 1m0s
[22:06:28.063] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:5:0)
[22:06:28.063] Timer tick, asking Buildbucket for the build status
[22:06:28.290] Build 8894240654889493280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:06:28.290] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:6:0) after 1m0s
[22:07:05.707] Received PubSub notification, asking Buildbucket for the build status
[22:07:06.474] Build 8894240654889493280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:28.344] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:6:0)
[22:07:28.344] Timer tick, asking Buildbucket for the build status
[22:07:28.647] Build 8894240654889493280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:28.648] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:8:0) after 1m0s
[22:08:28.695] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:8:0)
[22:08:28.695] Timer tick, asking Buildbucket for the build status
[22:08:29.219] Build 8894240654889493280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:29.219] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:9:0) after 1m0s
[22:09:29.270] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:9:0)
[22:09:29.270] Timer tick, asking Buildbucket for the build status
[22:09:32.922] Build 8894240654889493280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:32.922] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:10:0) after 1m0s
[22:10:33.247] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:10:0)
[22:10:33.247] Timer tick, asking Buildbucket for the build status
[22:10:33.554] Build 8894240654889493280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:33.554] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:11:0) after 1m0s
[22:11:33.787] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:11:0)
[22:11:33.787] Timer tick, asking Buildbucket for the build status
[22:11:33.961] Build 8894240654889493280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:33.962] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:12:0) after 1m0s
[22:12:34.159] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:12:0)
[22:12:34.159] Timer tick, asking Buildbucket for the build status
[22:12:34.349] Build 8894240654889493280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:34.349] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:13:0) after 1m0s
[22:13:34.478] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:13:0)
[22:13:34.478] Timer tick, asking Buildbucket for the build status
[22:13:34.760] Build 8894240654889493280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:34.760] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:14:0) after 1m0s
[22:14:34.879] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:14:0)
[22:14:34.879] Timer tick, asking Buildbucket for the build status
[22:14:35.575] Build 8894240654889493280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:35.575] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:15:0) after 1m0s
[22:15:35.680] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:15:0)
[22:15:35.680] Timer tick, asking Buildbucket for the build status
[22:15:36.133] Build 8894240654889493280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:36.133] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:16:0) after 1m0s
[22:16:36.212] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:16:0)
[22:16:36.212] Timer tick, asking Buildbucket for the build status
[22:16:36.398] Build 8894240654889493280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:36.398] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:17:0) after 1m0s
[22:17:36.545] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:17:0)
[22:17:36.545] Timer tick, asking Buildbucket for the build status
[22:17:36.761] Build 8894240654889493280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:36.761] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:18:0) after 1m0s
[22:18:36.853] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:18:0)
[22:18:36.853] Timer tick, asking Buildbucket for the build status
[22:18:37.579] Build 8894240654889493280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:37.579] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:19:0) after 1m0s
[22:19:37.613] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:19:0)
[22:19:37.613] Timer tick, asking Buildbucket for the build status
[22:19:37.793] Build 8894240654889493280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:37.794] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:20:0) after 1m0s
[22:20:37.832] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:20:0)
[22:20:37.832] Timer tick, asking Buildbucket for the build status
[22:20:38.096] Build 8894240654889493280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:38.096] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:21:0) after 1m0s
[22:21:38.183] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:21:0)
[22:21:38.183] Timer tick, asking Buildbucket for the build status
[22:21:38.371] Build 8894240654889493280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:38.371] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:22:0) after 1m0s
[22:22:38.421] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:22:0)
[22:22:38.421] Timer tick, asking Buildbucket for the build status
[22:22:38.663] Build 8894240654889493280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:38.663] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:23:0) after 1m0s
[22:23:39.550] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:23:0)
[22:23:39.550] Timer tick, asking Buildbucket for the build status
[22:23:40.031] Build 8894240654889493280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:40.031] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:24:0) after 1m0s
[22:24:40.316] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:24:0)
[22:24:40.316] Timer tick, asking Buildbucket for the build status
[22:24:40.841] Build 8894240654889493280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:40.841] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:25:0) after 1m0s
[22:25:40.977] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:25:0)
[22:25:40.977] Timer tick, asking Buildbucket for the build status
[22:25:42.101] Build 8894240654889493280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:42.101] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:26:0) after 1m0s
[22:26:42.130] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:26:0)
[22:26:42.130] Timer tick, asking Buildbucket for the build status
[22:26:42.326] Build 8894240654889493280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:42.326] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:27:0) after 1m0s
[22:27:42.425] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:27:0)
[22:27:42.425] Timer tick, asking Buildbucket for the build status
[22:27:42.619] Build 8894240654889493280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:42.619] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:28:0) after 1m0s
[22:28:42.677] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:28:0)
[22:28:42.677] Timer tick, asking Buildbucket for the build status
[22:28:42.910] Build 8894240654889493280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:42.910] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:29:0) after 1m0s
[22:29:43.157] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:29:0)
[22:29:43.157] Timer tick, asking Buildbucket for the build status
[22:29:44.265] Build 8894240654889493280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:44.265] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:30:0) after 1m0s
[22:30:44.399] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:30:0)
[22:30:44.399] Timer tick, asking Buildbucket for the build status
[22:30:44.912] Build 8894240654889493280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:44.912] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:31:0) after 1m0s
[22:31:46.017] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:31:0)
[22:31:46.017] Timer tick, asking Buildbucket for the build status
[22:31:46.966] Build 8894240654889493280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:46.966] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:32:0) after 1m0s
[22:32:47.409] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:32:0)
[22:32:47.409] Timer tick, asking Buildbucket for the build status
[22:32:48.322] Build 8894240654889493280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:48.322] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:33:0) after 1m0s
[22:33:48.508] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:33:0)
[22:33:48.508] Timer tick, asking Buildbucket for the build status
[22:33:49.525] Build 8894240654889493280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:49.525] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:34:0) after 1m0s
[22:34:49.718] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:34:0)
[22:34:49.718] Timer tick, asking Buildbucket for the build status
[22:34:50.057] Build 8894240654889493280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:50.057] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:35:0) after 1m0s
[22:35:50.349] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:35:0)
[22:35:50.349] Timer tick, asking Buildbucket for the build status
[22:35:50.770] Build 8894240654889493280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:50.770] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:36:0) after 1m0s
[22:36:50.989] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:36:0)
[22:36:50.989] Timer tick, asking Buildbucket for the build status
[22:36:53.046] Build 8894240654889493280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:53.046] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:37:0) after 1m0s
[22:37:53.181] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:37:0)
[22:37:53.181] Timer tick, asking Buildbucket for the build status
[22:37:53.360] Build 8894240654889493280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:53.360] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:38:0) after 1m0s
[22:38:53.415] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:38:0)
[22:38:53.415] Timer tick, asking Buildbucket for the build status
[22:38:54.109] Build 8894240654889493280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:54.109] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:39:0) after 1m0s
[22:39:54.188] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:39:0)
[22:39:54.188] Timer tick, asking Buildbucket for the build status
[22:39:54.832] Build 8894240654889493280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:54.832] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:40:0) after 1m0s
[22:40:54.868] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:40:0)
[22:40:54.869] Timer tick, asking Buildbucket for the build status
[22:40:55.610] Build 8894240654889493280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:55.610] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:41:0) after 1m0s
[22:41:55.661] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:41:0)
[22:41:55.662] Timer tick, asking Buildbucket for the build status
[22:41:57.954] Build 8894240654889493280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:57.954] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:42:0) after 1m0s
[22:42:58.067] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:42:0)
[22:42:58.067] Timer tick, asking Buildbucket for the build status
[22:42:58.245] Build 8894240654889493280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:58.245] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:43:0) after 1m0s
[22:43:58.379] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:43:0)
[22:43:58.379] Timer tick, asking Buildbucket for the build status
[22:43:59.098] Build 8894240654889493280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:59.098] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:44:0) after 1m0s
[22:44:59.208] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:44:0)
[22:44:59.208] Timer tick, asking Buildbucket for the build status
[22:44:59.436] Build 8894240654889493280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:59.436] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:45:0) after 1m0s
[22:45:59.540] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:45:0)
[22:45:59.540] Timer tick, asking Buildbucket for the build status
[22:46:00.209] Build 8894240654889493280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:00.209] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:46:0) after 1m0s
[22:47:00.331] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:46:0)
[22:47:00.331] Timer tick, asking Buildbucket for the build status
[22:47:00.542] Build 8894240654889493280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:00.542] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:47:0) after 1m0s
[22:48:00.588] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:47:0)
[22:48:00.588] Timer tick, asking Buildbucket for the build status
[22:48:01.566] Build 8894240654889493280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:01.566] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:48:0) after 1m0s
[22:49:01.651] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:48:0)
[22:49:01.651] Timer tick, asking Buildbucket for the build status
[22:49:01.846] Build 8894240654889493280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:01.846] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:49:0) after 1m0s
[22:50:01.940] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:49:0)
[22:50:01.940] Timer tick, asking Buildbucket for the build status
[22:50:02.204] Build 8894240654889493280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:02.204] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:50:0) after 1m0s
[22:51:02.409] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:50:0)
[22:51:02.409] Timer tick, asking Buildbucket for the build status
[22:51:03.138] Build 8894240654889493280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:03.138] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:51:0) after 1m0s
[22:52:03.181] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:51:0)
[22:52:03.181] Timer tick, asking Buildbucket for the build status
[22:52:03.883] Build 8894240654889493280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:03.883] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:52:0) after 1m0s
[22:53:03.918] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:52:0)
[22:53:03.918] Timer tick, asking Buildbucket for the build status
[22:53:04.454] Build 8894240654889493280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:04.454] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:53:0) after 1m0s
[22:54:04.516] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:53:0)
[22:54:04.516] Timer tick, asking Buildbucket for the build status
[22:54:05.400] Build 8894240654889493280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:05.400] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:54:0) after 1m0s
[22:55:05.456] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:54:0)
[22:55:05.456] Timer tick, asking Buildbucket for the build status
[22:55:07.243] Build 8894240654889493280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:07.243] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:55:0) after 1m0s
[22:56:07.433] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:55:0)
[22:56:07.433] Timer tick, asking Buildbucket for the build status
[22:56:07.811] Build 8894240654889493280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:07.811] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:56:0) after 1m0s
[22:57:08.005] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:56:0)
[22:57:08.005] Timer tick, asking Buildbucket for the build status
[22:57:08.503] Build 8894240654889493280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:08.503] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:57:0) after 1m0s
[22:58:08.676] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:57:0)
[22:58:08.676] Timer tick, asking Buildbucket for the build status
[22:58:08.866] Build 8894240654889493280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:08.866] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:58:0) after 1m0s
[22:59:08.995] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:58:0)
[22:59:08.995] Timer tick, asking Buildbucket for the build status
[22:59:09.244] Build 8894240654889493280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:09.244] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:59:0) after 1m0s
[23:00:09.380] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:59:0)
[23:00:09.380] Timer tick, asking Buildbucket for the build status
[23:00:09.790] Build 8894240654889493280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:09.790] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:60:0) after 1m0s
[23:01:09.859] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:60:0)
[23:01:09.859] Timer tick, asking Buildbucket for the build status
[23:01:10.144] Build 8894240654889493280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:10.144] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:61:0) after 1m0s
[23:02:10.187] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:61:0)
[23:02:10.187] Timer tick, asking Buildbucket for the build status
[23:02:10.422] Build 8894240654889493280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:10.422] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:62:0) after 1m0s
[23:03:10.746] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:62:0)
[23:03:10.746] Timer tick, asking Buildbucket for the build status
[23:03:11.388] Build 8894240654889493280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:11.388] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:63:0) after 1m0s
[23:04:11.504] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:63:0)
[23:04:11.504] Timer tick, asking Buildbucket for the build status
[23:04:11.696] Build 8894240654889493280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:11.696] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059670716996053024:64:0) after 1m0s
[23:04:15.924] Received PubSub notification, asking Buildbucket for the build status
[23:04:16.083] Build 8894240654889493280: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[23:04:16.083] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1576191855259943",
"created_by": "project:chromium",
"created_ts": "1576188145703846",
"id": "8894240654889493280",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"e9feda7dfd20ab2d94c16f3210f5807205dae3af\"}}",
"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-central1-b-8-67-s6jb\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 166017, \"got_angle_revision\": \"0bf3c4b585afa0e7849df8efa38e464140da6a3e\", \"got_dawn_revision\": \"69cdaf94dfb127fadc354883d423b63b4e4c3cb1\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"e9feda7dfd20ab2d94c16f3210f5807205dae3af\", \"got_revision_cp\": \"refs/heads/master@{#724352}\", \"got_swarming_client_revision\": \"885b3febcc170a60f25795304e60927b77d1e92d\", \"got_swiftshader_revision\": \"07958d452f93b6513ebf5cbae64a1325c1755dbf\", \"got_v8_revision\": \"724fd9f1ece40deb98f111c77029733c8f735d8a\", \"got_v8_revision_cp\": \"refs/heads/8.1.27@{#1}\", \"got_webrtc_revision\": \"26fe811623627e1089a8df2c86a89dda2cd9d338\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30068}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"e9feda7dfd20ab2d94c16f3210f5807205dae3af\", \"swarm_hashes_refs/heads/master(at){#724352}_without_patch\": {\"accessibility_unittests\": \"3ee5ac0e7a7a6fd800b03cbcd1b872b25fcf82a1\", \"angle_unittests\": \"8f5aee17ef318068a40b77d878674c32f1ebf72e\", \"app_shell_unittests\": \"9ca05975d24f7377484dd2aa35313224afb7c901\", \"aura_unittests\": \"87ebddc43e256d8453f5ca3faa7731d133bc55e6\", \"base_unittests\": \"0a286f6393247c7e1c4b356a1e38bb2a1e3cb681\", \"base_util_unittests\": \"3f4a8ddc9768b5cdcfb93fa532814fde425da7ca\", \"blink_common_unittests\": \"88c62e288262378a2a38d6769b35bae1f31813c3\", \"blink_fuzzer_unittests\": \"12c27b5a0b058cac21579906fc21d5ab443dd665\", \"blink_heap_unittests\": \"576a768eab4cba804358b3cc78cfc6f4bb1ec2e8\", \"blink_platform_unittests\": \"cf28f376726e8e97563e3e6c2ab1b82850e7ee83\", \"blink_python_tests\": \"4f9ec9f8b259545c4c13f1c64a6406b5f6a959fd\", \"blink_unittests\": \"d2831c3d4f71cfa05355a49ecc60b553ad083fdd\", \"blink_web_tests\": \"ac657cb1f8525af8f8a73f40497abf73b139ef82\", \"boringssl_crypto_tests\": \"e4b14083ab2bb9f72b26a68f3ca839a979e7b9f9\", \"boringssl_ssl_tests\": \"586f4cdca1cc15fdbc971d930c1ce420b45ab5b8\", \"browser_tests\": \"d70afe58831d0d18831db7a1cf8cbb5ac96f0047\", \"cacheinvalidation_unittests\": \"3c959a661153aca6c9f34f06350ce47cc0eb1003\", \"capture_unittests\": \"f2c5281be2cf1ef762bac65c18fb3297645f8b81\", \"cast_unittests\": \"2c0d3628ef1a2e3d25cde62e0d070129fee38f9e\", \"cc_unittests\": \"8cd6fd7063d80542fa1edb442829c4ff88fdf6d5\", \"chrome_app_unittests\": \"51b156579823908e2e90a94214012968c70185d2\", \"chromedriver_unittests\": \"5626debcac69915cad99adf9165b6f176a4c814d\", \"components_browsertests\": \"226ae8917e694167fa572f663ad6bce4eabd4adc\", \"components_unittests\": \"c45d805b55994d3181af23d4a82576993cae8048\", \"compositor_unittests\": \"3c989fc5afdf941b0d7898b8a82015c23c7c898d\", \"content_browsertests\": \"ef8545bcf0b504992c089d5cba4df319f707d711\", \"content_shell_crash_test\": \"37a9beca9b9f1c2f8645303e94d0e27d3b89a807\", \"content_unittests\": \"477b2c98586559a17a8c54e8daa8dd14eae281d2\", \"crashpad_tests\": \"28c9ddeb4fb5ea7b13bdcccfd0662c8bbdb90dd8\", \"cronet_tests\": \"c4bfb3af8f7f2463775ab631f28b42e8f735e47e\", \"cronet_unittests\": \"b74329ffd45065b6826985557c1f2f6671793bb3\", \"crypto_unittests\": \"8d4386b722cc9f7712b771fe7d7aa821a78770c6\", \"dbus_unittests\": \"f8f0e29bd851455c47be21313782e78fcc0d3c0f\", \"device_unittests\": \"89afa955192b5ee99b8bcaecd71119f38b75fdaa\", \"devtools_lint_check\": \"6c6a97f3c5dbcb352e8c4122493c6e2554ec0eac\", \"devtools_type_check\": \"c0d5907f6347abe0be6686402e71990f45936158\", \"display_unittests\": \"704143e488dbc68d21430b197e9ba75bd6cab2f0\", \"events_unittests\": \"80a30289ea94a67d240549724f2ac71d3aad72c2\", \"extensions_browsertests\": \"452d13bb871c5ddd4a1c19a8f1bbda01817a3252\", \"extensions_unittests\": \"765cc0d157421360b3b9003ecf9394aba834e735\", \"filesystem_service_unittests\": \"59742c74b67704ad678412a8b9d7b99f04ac18ec\", \"flatbuffers_unittests\": \"034d1c63a8199a680c9bbf29b5866b1c851e28ee\", \"gcm_unit_tests\": \"3cc25a1daa1b4f3b71eae85ada5c8d64847f6058\", \"gfx_unittests\": \"69088fafd7da15ccb787c4719bccfff1c1a964c0\", \"gin_unittests\": \"6b322f109bcbd2682ebac164cd960c5814ab672b\", \"google_apis_unittests\": \"6092c88b0a92150dbaf1f82ee8a22402658e202c\", \"gpu_unittests\": \"7b3f0df0b441f2e392b56daca499e7ebcd736722\", \"grit_python_unittests\": \"25dff9ef6327c596609d376409bc1d0e605b6d43\", \"gwp_asan_unittests\": \"8f8d888dffdeb1fd43b38593fcc979621931a896\", \"headless_browsertests\": \"f9b9f9bb1813fa25019788496c414651fe8ffd83\", \"headless_unittests\": \"dcdbbb7d77227e8f55a2c3bddcff2de609e73a70\", \"interactive_ui_tests\": \"a352c634ecaec257c7c3d2f2ee994a7d3b20860d\", \"ipc_tests\": \"1a878348d9beb3b11d76779b59d266e1b352ba71\", \"jingle_unittests\": \"9df69c1d1bf39d53799380cb4a3e1646243ede2c\", \"latency_unittests\": \"1611ecb32c30ed4fdcf98a35ef6602bef52b182b\", \"libjingle_xmpp_unittests\": \"2b45ad08a8834741da563f57cac8ea41735910e0\", \"media_blink_unittests\": \"93f6849a98c3093ef554c08ec55de32bbe6ee9ec\", \"media_service_unittests\": \"a4174ac5e632f50a36100654ca6a51e4ea8aa11e\", \"media_unittests\": \"b7b2ad7e0f535256a647243804934f65b9268168\", \"message_center_unittests\": \"5c8e3208a59ade22e81d6811282e63c871f3e3a4\", \"metrics_python_tests\": \"0a9a5a66531301d15c9ba1e60a8c2e6971d50442\", \"midi_unittests\": \"8055be88af67ed7a881c28b580b55be4e276b762\", \"mojo_core_unittests\": \"7bfdaa1f062463fcac794719732d0ebb96126d86\", \"mojo_unittests\": \"f67c02c84a662a8fb56a77f7ae528608ab492400\", \"nacl_helper_nonsfi_unittests\": \"ddec84fc9ec0bcc0c34b9a26e4efbc6e128666b9\", \"nacl_loader_unittests\": \"29cbde097156a98cd4a0ee74d6f5ad9b6cf29fda\", \"native_theme_unittests\": \"698d70856ea42a5cad1c5c758ab065b3c42f3c05\", \"net_unittests\": \"5f8af3ddf6d9ac5d85943c5462995509f9a3d70c\", \"pdf_unittests\": \"0f4baeb59ad5f6a97d7fb9f8f3eba4ae9798c348\", \"perfetto_unittests\": \"90c522fd6e1ee1b394b5e3d062866691b667f067\", \"ppapi_unittests\": \"27454709f0c0c7628530be380960aa1bb84910d7\", \"printing_unittests\": \"652cf87664ecffd897d07f1e729fb8b04d8456a3\", \"remoting_unittests\": \"8bd4747059b62657ed28ea0eb07c7d1186f3d293\", \"sandbox_linux_unittests\": \"5e3aac58544fbcb14043f6d58ae6d99c763d763f\", \"service_manager_unittests\": \"3bc6df7b5b4224d24ff2a680c8450ef2b870fe61\", \"services_unittests\": \"9022c253978d067a9386f06048256218814f2631\", \"shell_dialogs_unittests\": \"79694f62f1b5f4873be98991e98c694c891955dd\", \"skia_unittests\": \"295eb6a24c2136521d423994befb984586abaada\", \"snapshot_unittests\": \"89fe12152a84b612ad43a10305df129c993bd7c3\", \"sql_unittests\": \"908b75195a407a10497bc30c768a8227fd17fdeb\", \"storage_unittests\": \"10eac51441db9b4c5e76c3a1c2155f0fce1ff914\", \"sync_integration_tests\": \"916e78110bc6df26963d71e365e906dd49ede460\", \"telemetry_gpu_unittests\": \"9695e7fe1a153ccef4fee9daafaf87333ddc603a\", \"telemetry_perf_unittests\": \"6c9584754caa448ff8ed94c29bb9095eefe87f9d\", \"telemetry_unittests\": \"a2f0fa525a7aa58d9759a3be71822209b2aa5b65\", \"traffic_annotation_auditor_unittests\": \"b7ccfe94b7e4fe30fcbaecd6c307b00584947df7\", \"ui_base_unittests\": \"7f3bb48ec8e8171bb12099abdcfdcfe10abb1a5a\", \"ui_touch_selection_unittests\": \"c6403188b9d3cd7ffebed8fda380303a4db4d3a6\", \"unit_tests\": \"07fb7de0653b64131549347574500476c5110764\", \"url_unittests\": \"14260a12a03e1abc564f3715c9c45b9b66167bfe\", \"views_perftests\": \"939117ee29ef1a198c3397a825a4bf4101f36725\", \"views_unittests\": \"3a0e4791ef413586f4d80481ffdc566f242079d7\", \"viz_unittests\": \"2c0c985a12e5cb6cbea513360b67cbbd0374603f\", \"vr_common_unittests\": \"0b99eb389a73ea725d3f77904d18713bcfc41884\", \"vr_pixeltests\": \"2d72f7803b4cc5a295cfaee60b0b4d5a0fd5a823\", \"webdriver_wpt_tests\": \"6909c2b22a5c3b7f98ff76c3f6b14536ed5f3122\", \"weblayer_browsertests\": \"5c504bc605ac7dba539d7a75ecacf2d2f05790e2\", \"wm_unittests\": \"5404724ead829d3d083022347ebb4a40b6d783a8\", \"wtf_unittests\": \"9f45f854d2c8bc5050cf77641395eb540fe2ebb9\", \"xr_browser_tests\": \"06823919f368072fd43152c6baf356f67bccc75d\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_093e8a6336a6e6d4670d6f5128e7c73730afadf3bd7f9284d26d8f79182f8ce5_v2\", \"builder_349392596e0d17d3422c55529f4e230875f36ec061130c176581539a4c7cd365_v2\", \"builder_4735eb974b804f74efeb4253a3690e62eb4b060f1d311c87e54f3b58924ad1a2_v2\", \"builder_562e40d9a0016a631bf2d4b73416a93abb069b959f020aa1a03e04f54d5ec620_v2\", \"builder_9abb531f9fa28fcfd3ce68b2370bf6d67201ddb083beddbd2671f7c5fe25be9e_v2\", \"builder_e961d9d7e9bca679490a4aaa8599bd975f9b7e0b713a988ed6895a059d04d2ac_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-central1-b-8-67-s6jb\"], \"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\": [\"4713-a14a20b\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1576188424735049",
"status": "COMPLETED",
"status_changed_ts": "1576191855640890",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/166017",
"builder:Linux Builder (dbg)",
"buildset:commit/git/e9feda7dfd20ab2d94c16f3210f5807205dae3af",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e9feda7dfd20ab2d94c16f3210f5807205dae3af",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9059670716996053024",
"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/8894240654889493280/+/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:4914f4380dcc5910",
"user_agent:luci-scheduler"
],
"updated_ts": "1576191855644848",
"url": "https://ci.chromium.org/b/8894240654889493280",
"utcnow_ts": "1576191856063670"
}
}
[23:04:16.083] Invocation finished in 1h1m51.772854816s with status SUCCEEDED