[12:57:58.311] New invocation is queued and will start shortly
[12:57:59.475] Starting the invocation (attempt 1)
[12:57:59.529] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:57:59.529] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:57:59.529] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9050282885289903968",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"7f817a42a203920f3857d2b1ff6606743776b94e\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9050282885289903968",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/7f817a42a203920f3857d2b1ff6606743776b94e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7f817a42a203920f3857d2b1ff6606743776b94e",
"gitiles_ref:refs/heads/master"
]
}
[12:58:00.336] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1585141079641452",
"id": "8884852823233132032",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"7f817a42a203920f3857d2b1ff6606743776b94e\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1585141080187010",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/170168",
"builder:Linux Builder (dbg)",
"buildset:commit/git/7f817a42a203920f3857d2b1ff6606743776b94e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7f817a42a203920f3857d2b1ff6606743776b94e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9050282885289903968",
"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/8884852823233132032/+/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": "1585141080187123",
"url": "https://ci.chromium.org/b/8884852823233132032",
"utcnow_ts": "1585141080329726"
}
}
[12:58:00.336] Task URL: https://ci.chromium.org/b/8884852823233132032
[12:58:00.336] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:2:0) after 1m0s
[12:59:00.353] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:2:0)
[12:59:00.353] Timer tick, asking Buildbucket for the build status
[12:59:00.696] Build 8884852823233132032: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:59:00.696] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:3:0) after 1m0s
[13:00:00.820] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:3:0)
[13:00:00.820] Timer tick, asking Buildbucket for the build status
[13:00:00.921] Build 8884852823233132032: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:00:00.921] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:4:0) after 1m0s
[13:01:00.964] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:4:0)
[13:01:00.964] Timer tick, asking Buildbucket for the build status
[13:01:01.113] Build 8884852823233132032: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:01:01.113] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:5:0) after 1m0s
[13:02:01.173] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:5:0)
[13:02:01.173] Timer tick, asking Buildbucket for the build status
[13:02:02.867] Build 8884852823233132032: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:02:02.867] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:6:0) after 1m0s
[13:03:03.028] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:6:0)
[13:03:03.028] Timer tick, asking Buildbucket for the build status
[13:03:03.214] Build 8884852823233132032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:03.214] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:7:0) after 1m0s
[13:03:04.166] Received PubSub notification, asking Buildbucket for the build status
[13:03:04.269] Build 8884852823233132032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:03.373] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:7:0)
[13:04:03.373] Timer tick, asking Buildbucket for the build status
[13:04:03.502] Build 8884852823233132032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:03.502] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:9:0) after 1m0s
[13:05:03.643] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:9:0)
[13:05:03.643] Timer tick, asking Buildbucket for the build status
[13:05:03.770] Build 8884852823233132032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:03.770] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:10:0) after 1m0s
[13:06:03.904] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:10:0)
[13:06:03.904] Timer tick, asking Buildbucket for the build status
[13:06:04.103] Build 8884852823233132032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:04.103] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:11:0) after 1m0s
[13:07:04.134] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:11:0)
[13:07:04.134] Timer tick, asking Buildbucket for the build status
[13:07:04.260] Build 8884852823233132032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:04.260] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:12:0) after 1m0s
[13:08:04.284] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:12:0)
[13:08:04.284] Timer tick, asking Buildbucket for the build status
[13:08:04.475] Build 8884852823233132032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:04.475] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:13:0) after 1m0s
[13:09:04.498] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:13:0)
[13:09:04.498] Timer tick, asking Buildbucket for the build status
[13:09:04.702] Build 8884852823233132032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:04.702] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:14:0) after 1m0s
[13:10:04.757] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:14:0)
[13:10:04.757] Timer tick, asking Buildbucket for the build status
[13:10:04.870] Build 8884852823233132032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:04.870] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:15:0) after 1m0s
[13:11:04.921] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:15:0)
[13:11:04.921] Timer tick, asking Buildbucket for the build status
[13:11:05.046] Build 8884852823233132032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:05.046] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:16:0) after 1m0s
[13:12:05.087] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:16:0)
[13:12:05.087] Timer tick, asking Buildbucket for the build status
[13:12:05.469] Build 8884852823233132032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:05.469] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:17:0) after 1m0s
[13:13:05.493] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:17:0)
[13:13:05.493] Timer tick, asking Buildbucket for the build status
[13:13:05.656] Build 8884852823233132032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:05.656] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:18:0) after 1m0s
[13:14:05.685] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:18:0)
[13:14:05.685] Timer tick, asking Buildbucket for the build status
[13:14:05.872] Build 8884852823233132032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:05.872] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:19:0) after 1m0s
[13:15:05.915] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:19:0)
[13:15:05.915] Timer tick, asking Buildbucket for the build status
[13:15:06.137] Build 8884852823233132032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:06.137] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:20:0) after 1m0s
[13:16:06.303] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:20:0)
[13:16:06.303] Timer tick, asking Buildbucket for the build status
[13:16:06.444] Build 8884852823233132032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:06.444] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:21:0) after 1m0s
[13:17:06.502] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:21:0)
[13:17:06.502] Timer tick, asking Buildbucket for the build status
[13:17:06.621] Build 8884852823233132032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:06.621] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:22:0) after 1m0s
[13:18:06.732] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:22:0)
[13:18:06.732] Timer tick, asking Buildbucket for the build status
[13:18:06.854] Build 8884852823233132032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:06.854] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:23:0) after 1m0s
[13:19:06.937] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:23:0)
[13:19:06.937] Timer tick, asking Buildbucket for the build status
[13:19:07.020] Build 8884852823233132032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:07.020] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:24:0) after 1m0s
[13:20:07.170] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:24:0)
[13:20:07.170] Timer tick, asking Buildbucket for the build status
[13:20:07.399] Build 8884852823233132032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:07.399] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:25:0) after 1m0s
[13:21:07.520] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:25:0)
[13:21:07.520] Timer tick, asking Buildbucket for the build status
[13:21:07.646] Build 8884852823233132032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:07.646] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:26:0) after 1m0s
[13:22:07.735] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:26:0)
[13:22:07.735] Timer tick, asking Buildbucket for the build status
[13:22:07.810] Build 8884852823233132032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:07.810] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:27:0) after 1m0s
[13:23:07.855] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:27:0)
[13:23:07.855] Timer tick, asking Buildbucket for the build status
[13:23:07.955] Build 8884852823233132032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:07.955] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:28:0) after 1m0s
[13:24:08.074] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:28:0)
[13:24:08.074] Timer tick, asking Buildbucket for the build status
[13:24:08.198] Build 8884852823233132032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:08.198] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:29:0) after 1m0s
[13:25:08.325] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:29:0)
[13:25:08.326] Timer tick, asking Buildbucket for the build status
[13:25:08.422] Build 8884852823233132032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:08.422] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:30:0) after 1m0s
[13:26:08.472] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:30:0)
[13:26:08.472] Timer tick, asking Buildbucket for the build status
[13:26:08.588] Build 8884852823233132032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:08.588] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:31:0) after 1m0s
[13:27:08.640] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:31:0)
[13:27:08.640] Timer tick, asking Buildbucket for the build status
[13:27:09.159] Build 8884852823233132032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:09.159] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:32:0) after 1m0s
[13:28:09.251] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:32:0)
[13:28:09.251] Timer tick, asking Buildbucket for the build status
[13:28:09.491] Build 8884852823233132032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:09.491] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:33:0) after 1m0s
[13:29:09.504] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:33:0)
[13:29:09.504] Timer tick, asking Buildbucket for the build status
[13:29:09.682] Build 8884852823233132032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:09.682] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:34:0) after 1m0s
[13:30:09.788] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:34:0)
[13:30:09.788] Timer tick, asking Buildbucket for the build status
[13:30:09.901] Build 8884852823233132032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:09.901] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:35:0) after 1m0s
[13:31:09.951] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:35:0)
[13:31:09.951] Timer tick, asking Buildbucket for the build status
[13:31:10.335] Build 8884852823233132032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:10.335] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:36:0) after 1m0s
[13:32:10.340] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:36:0)
[13:32:10.340] Timer tick, asking Buildbucket for the build status
[13:32:10.454] Build 8884852823233132032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:10.454] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:37:0) after 1m0s
[13:33:10.484] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:37:0)
[13:33:10.484] Timer tick, asking Buildbucket for the build status
[13:33:10.601] Build 8884852823233132032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:10.601] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:38:0) after 1m0s
[13:34:10.686] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:38:0)
[13:34:10.686] Timer tick, asking Buildbucket for the build status
[13:34:10.842] Build 8884852823233132032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:10.842] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:39:0) after 1m0s
[13:35:10.914] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:39:0)
[13:35:10.914] Timer tick, asking Buildbucket for the build status
[13:35:11.065] Build 8884852823233132032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:11.065] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:40:0) after 1m0s
[13:36:12.306] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:40:0)
[13:36:12.306] Timer tick, asking Buildbucket for the build status
[13:36:12.458] Build 8884852823233132032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:12.458] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:41:0) after 1m0s
[13:37:12.694] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:41:0)
[13:37:12.694] Timer tick, asking Buildbucket for the build status
[13:37:12.871] Build 8884852823233132032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:12.871] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:42:0) after 1m0s
[13:38:12.961] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:42:0)
[13:38:12.961] Timer tick, asking Buildbucket for the build status
[13:38:15.276] Build 8884852823233132032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:15.276] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:43:0) after 1m0s
[13:39:15.279] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:43:0)
[13:39:15.279] Timer tick, asking Buildbucket for the build status
[13:39:15.409] Build 8884852823233132032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:15.409] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:44:0) after 1m0s
[13:40:15.472] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:44:0)
[13:40:15.472] Timer tick, asking Buildbucket for the build status
[13:40:15.605] Build 8884852823233132032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:15.605] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:45:0) after 1m0s
[13:41:15.813] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:45:0)
[13:41:15.813] Timer tick, asking Buildbucket for the build status
[13:41:16.151] Build 8884852823233132032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:16.152] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:46:0) after 1m0s
[13:42:16.431] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:46:0)
[13:42:16.431] Timer tick, asking Buildbucket for the build status
[13:42:16.540] Build 8884852823233132032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:16.540] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:47:0) after 1m0s
[13:43:16.673] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:47:0)
[13:43:16.673] Timer tick, asking Buildbucket for the build status
[13:43:16.753] Build 8884852823233132032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:16.753] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:48:0) after 1m0s
[13:44:16.966] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:48:0)
[13:44:16.966] Timer tick, asking Buildbucket for the build status
[13:44:17.150] Build 8884852823233132032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:17.150] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:49:0) after 1m0s
[13:45:17.275] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:49:0)
[13:45:17.275] Timer tick, asking Buildbucket for the build status
[13:45:17.412] Build 8884852823233132032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:17.412] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:50:0) after 1m0s
[13:46:17.491] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:50:0)
[13:46:17.491] Timer tick, asking Buildbucket for the build status
[13:46:17.696] Build 8884852823233132032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:17.696] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:51:0) after 1m0s
[13:47:17.805] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:51:0)
[13:47:17.805] Timer tick, asking Buildbucket for the build status
[13:47:17.943] Build 8884852823233132032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:17.943] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:52:0) after 1m0s
[13:48:18.042] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:52:0)
[13:48:18.043] Timer tick, asking Buildbucket for the build status
[13:48:18.187] Build 8884852823233132032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:18.187] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:53:0) after 1m0s
[13:49:18.342] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:53:0)
[13:49:18.342] Timer tick, asking Buildbucket for the build status
[13:49:18.429] Build 8884852823233132032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:18.429] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:54:0) after 1m0s
[13:50:18.472] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:54:0)
[13:50:18.472] Timer tick, asking Buildbucket for the build status
[13:50:18.596] Build 8884852823233132032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:18.596] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:55:0) after 1m0s
[13:51:18.780] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:55:0)
[13:51:18.780] Timer tick, asking Buildbucket for the build status
[13:51:18.940] Build 8884852823233132032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:18.940] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:56:0) after 1m0s
[13:52:19.099] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:56:0)
[13:52:19.099] Timer tick, asking Buildbucket for the build status
[13:52:19.239] Build 8884852823233132032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:19.239] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050282885289903968:57:0) after 1m0s
[13:53:18.466] Received PubSub notification, asking Buildbucket for the build status
[13:53:18.541] Build 8884852823233132032: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[13:53:18.541] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1585144396867156",
"created_by": "project:chromium",
"created_ts": "1585141079641452",
"id": "8884852823233132032",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"7f817a42a203920f3857d2b1ff6606743776b94e\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/chromium_tests\": {\"bucketed_triggers\": true}, \"$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-8-59-ngn4\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 170168, \"got_angle_revision\": \"107e4e989e94e70c594eb91b090d3f4a0108bea5\", \"got_dawn_revision\": \"0eff2a2b463fa5bf03b080432d19526221ce6c65\", \"got_nacl_revision\": \"ffed96d1528df9f114a30b14d4eb06120c8fb022\", \"got_revision\": \"7f817a42a203920f3857d2b1ff6606743776b94e\", \"got_revision_cp\": \"refs/heads/master@{#753197}\", \"got_swarming_client_revision\": \"cc958279ffd6853e0a1b227a7e957ca334fe56af\", \"got_swiftshader_revision\": \"540bdf92531d0c325d7b5c770ec33e869b56059f\", \"got_v8_revision\": \"aafdcc7e2d0265f6d5e1c480aa28e6105bcb3e3f\", \"got_v8_revision_cp\": \"refs/heads/8.3.73@{#1}\", \"got_webrtc_revision\": \"f5bbd1f06880f3cba83cc993a76b1515e0cd8228\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30875}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"7f817a42a203920f3857d2b1ff6606743776b94e\", \"swarm_hashes_refs/heads/master(at){#753197}_without_patch\": {\"accessibility_unittests\": \"6ab81f04d8a96bc9fd3f214c94a9f99f1af0f9da\", \"angle_unittests\": \"64d9a5d0874c915d5a830205a8dce56a05c9d0e2\", \"app_shell_unittests\": \"560b6a3ff6620b136117ef4669c4b300771f6607\", \"aura_unittests\": \"a36fbfdee818ed8cc73d358300334c8f94ea73cd\", \"base_unittests\": \"8271047bb89ab2670b111a2eb04793ff811d9f23\", \"base_util_unittests\": \"e92e9de05a8ae45701c86545b3ca33141336a08f\", \"blink_common_unittests\": \"6c0a7dda6a87c3b057512405d794e209125acd29\", \"blink_fuzzer_unittests\": \"33aede9801e4e2348a58615f6d0c30628ee0ed5a\", \"blink_heap_unittests\": \"b98991fbfc41ff76d976c005a0c54ea349e566b8\", \"blink_platform_unittests\": \"2fd8594ce4254ba544c485ba8ecd4cdabf469d49\", \"blink_python_tests\": \"4f79bd3ac4a3fe055c49a6253071a7deb88725ec\", \"blink_unittests\": \"d2d4bb598e7602e6735b2d2af46b88a850aeeb7e\", \"blink_web_tests\": \"4ef05331c8c08f75924b531595da3873d1e515a0\", \"boringssl_crypto_tests\": \"df460a348c6f5816fdf56dfaa88bbf9ef46dcc1f\", \"boringssl_ssl_tests\": \"f9db89d0a330e8699480e74cad00f076576813bd\", \"browser_tests\": \"fe9af9ebccd809df55bf76730de4feeb60ec6e11\", \"cacheinvalidation_unittests\": \"524f1c72e38e0a5e334e1f5b45eec07dc45c9583\", \"capture_unittests\": \"a2a8e5cde256c8c117476d4ad4a80f97c0811eed\", \"cast_unittests\": \"a3373022c20920752e5902d07474c2442d74e454\", \"cc_unittests\": \"0373a559950cadf5aded8e511a24584e8b5c52f7\", \"chrome_app_unittests\": \"13015f21c2667bd34e58541b864601100ce63704\", \"chromedriver_unittests\": \"fbcc1486689352ccc585288f8f40426aeb913d4f\", \"components_browsertests\": \"443e4a4ef7fc521978d5af4dca07f4ac2069f888\", \"components_unittests\": \"648bde02a94caf03c3d645995681cf4bc0e781f4\", \"compositor_unittests\": \"caee00361d961b8ef0f68ad2afb402a2571c4d82\", \"content_browsertests\": \"9dbd78c05e0b8f37750a7187a6326762cc5c8f0b\", \"content_shell_crash_test\": \"41123425462483a40cf6fee5b402b32db4d8400e\", \"content_unittests\": \"fd7220c07782e1222f199780c498c8e6fcd9e65d\", \"crashpad_tests\": \"16ac6bf16e91292166fa4ffb43d47b00c7ed48c0\", \"cronet_tests\": \"5aced7c9717bb788abc963f536dc1652a99f7c5e\", \"cronet_unittests\": \"1da2549c2cb5851fab3b42d2d90cebd0a42ad0ed\", \"crypto_unittests\": \"1d87cea5bd7a867cdbd7e712deff68f30408d072\", \"dbus_unittests\": \"7236755bf53337b112e5cc3989ee115dfaf0e700\", \"device_unittests\": \"cdd8be4d40fdb7d1c4a2315893221e34219fb89a\", \"display_unittests\": \"9e75a6dd9733e7e9e1808051411e7b9f8e0bb3a5\", \"events_unittests\": \"72074597fdac824dd5e4093d616694f4e8f337e4\", \"extensions_browsertests\": \"dd3b9f72a0ee44dd3116999cdf5bcd0fa2305b7c\", \"extensions_unittests\": \"3ded0ff04fd85dc09c27219e329904bc0272dcc1\", \"filesystem_service_unittests\": \"872263d31ab277565a82a2e5fa61130485933430\", \"flatbuffers_unittests\": \"50e47be8364b1aba201300b5e504c8137bc62062\", \"gcm_unit_tests\": \"c2b9c2fcc4edffa11289afc1fa1c5ee15e973d48\", \"gfx_unittests\": \"d85eb56ccc362364715659940e09fdd0a0fe9d6d\", \"gin_unittests\": \"b5a606a664f232a59411c67c17be7904669b1f1b\", \"google_apis_unittests\": \"088e9ee828bd88373fd60d095c2ac1532863c08f\", \"gpu_unittests\": \"cc760bc6c6627f1fc74c6993bb7082205f65c18e\", \"grit_python_unittests\": \"afbbe1df7c81809157ec9d2af582b8cb9256fe66\", \"gwp_asan_unittests\": \"2f0f5b753ebfbb6bdac5e62266cd1aa6139f97c6\", \"headless_browsertests\": \"86d6b7d2abef972e36915224782b2b1baae305a9\", \"headless_unittests\": \"43a64b285ab34c4b2f6c5e22c101c1f862952588\", \"interactive_ui_tests\": \"b26a730df2d94f629744e65fc1b4c1ce03d36fe7\", \"ipc_tests\": \"94b44bfa376267eccc27ef56f7bf61258906dd46\", \"jingle_unittests\": \"6db671ee023e386da189dd37c71228f57254058c\", \"latency_unittests\": \"d21f7098a40adb09b6bd26142739e5e5208f328e\", \"libjingle_xmpp_unittests\": \"fdec854aa13bd4528612eef2f6206e953a969436\", \"media_blink_unittests\": \"17c427b3d01e590f65095fe4b419f40eeb488d55\", \"media_service_unittests\": \"d9d543364959acc56373c64372d549584b00dcd6\", \"media_unittests\": \"510175660ccfea7444f40f2d9c4eb3cf699ffd85\", \"message_center_unittests\": \"77b68c1e52c95feb7518c958487b4a13f5907349\", \"metrics_python_tests\": \"1f0ec8e8121a603de5cb1039259ff132aab270a9\", \"midi_unittests\": \"522a1e4e17409706df52d4f66c5d8412f4919eda\", \"mojo_core_unittests\": \"6db26f3aaf9143de0473df330663600af3483aff\", \"mojo_unittests\": \"a17550f894f04a5a4e4fa67d286ebbb5aee940cf\", \"nacl_helper_nonsfi_unittests\": \"e293d5d5e9b6e0a48ecb27f8f67c3481cf5343af\", \"nacl_loader_unittests\": \"8ac887dc5d665117e351c046d45e060f984c393b\", \"native_theme_unittests\": \"b6265837403efb1cfd91fa2f4c749f40b44184e8\", \"net_unittests\": \"bfca91c0c01ce437a65da3dc66d6d23e7c46ab35\", \"pdf_unittests\": \"3569ce6b960ea92742ba67a79cdb721a0f444e32\", \"perfetto_unittests\": \"526b4fd0ed6467919b1b7301488a9c33ff6b037f\", \"ppapi_unittests\": \"c3f06d0c69396c64ebc2fc128ca74c323252d934\", \"printing_unittests\": \"9664007e4a5d13cafbac018aaa7ea0017f58c50f\", \"remoting_unittests\": \"6f6f153c224961739b71ab6c29a280e13d2b5bcd\", \"sandbox_linux_unittests\": \"6b9cb9029d791dc928854e146034dc32bf24a833\", \"service_manager_unittests\": \"e9eb2d2c94b5a4bd3e5fa264f28ae6f165d47c7f\", \"services_unittests\": \"a7fa913ea2772603e10031cf80b4b59d890bf709\", \"shell_dialogs_unittests\": \"c9300ff75ba2dd3124df38300cd07e025ea27a0a\", \"skia_unittests\": \"f35c5443b9bbf4a000028e3009de92685d43e0f3\", \"snapshot_unittests\": \"9f95e251b72eeb02a526d5ee02a99f1804049e5b\", \"sql_unittests\": \"7737fc73e35ba5ed214956a3f8fb05ed2ac5fa9e\", \"storage_unittests\": \"64baa752f22b6bd8c64d397d8c71d8e37f4fdf8b\", \"sync_integration_tests\": \"7aa7c93a07346f2df81efa0fa50c8ef53729667e\", \"telemetry_gpu_unittests\": \"27c2720fca6c28cb944d005de0a716e39401c275\", \"telemetry_perf_unittests\": \"a8a1d4a2b9aa098f4c0ff57c34fe96444a903f88\", \"telemetry_unittests\": \"cf9c5e9d75a774c6f3399c0f69a2562b4fdd46fc\", \"traffic_annotation_auditor_unittests\": \"e53cbe5d6ce870f21cf8f03a64d822fd6793298d\", \"ui_base_unittests\": \"4dc2451690aa4e46a8e1bdebd284f92cc49c7660\", \"ui_touch_selection_unittests\": \"a9c69b81bf6bc4105fec5c7bd75d6b3b49bc8860\", \"unit_tests\": \"b22da5b0f3cd10aa55dc490808cbfc2bfe3fb02d\", \"url_unittests\": \"fba59725ddb9d877e67e20ff96ee6d657837393b\", \"views_perftests\": \"8aa1fb40ff2d3324c4d861599dd67ebd451e58f7\", \"views_unittests\": \"af47bf5ccee5e0327fdf58cd775ae4bf6af6f508\", \"viz_unittests\": \"ddd1a5acfd6ab5c92fb2b29f7fff985b5a42e4f0\", \"vr_common_unittests\": \"a80d7ad4a5b3d2c69c7b0f53773ce955e8652f29\", \"vr_pixeltests\": \"e6fec023d58f35ef1e7e075829947717414440cf\", \"webdriver_wpt_tests\": \"ddf10fa5377e729f4c5496c8e44a593847d0cef9\", \"weblayer_browsertests\": \"20d0ac4eb04e1cfb85eebf95c5aa44a5a315f203\", \"wm_unittests\": \"d5f1da54cde714412602e2ad85d2c17e8aea49c9\", \"wtf_unittests\": \"3b028da2cffcca49735ed1e8dadb2333f96d615c\", \"xr_browser_tests\": \"62ab577dee04fb3bdc66d49b344912d67d9518bc\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_1855fffe06c5131ebf89186d4b9c18984f724019f1e040002bd6399dfa18192c_v2\", \"builder_f72b88ee7914fe5eb8b32d979ad6b47003d3a0322a10970d43d78c86a32dd14e_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"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-8-59-ngn4\"], \"image\": [\"chrome-xenial-20032200-f0f7a76907a\"], \"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\": [\"4895-ce889b2\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1585141381779524",
"status": "COMPLETED",
"status_changed_ts": "1585144398143987",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/170168",
"builder:Linux Builder (dbg)",
"buildset:commit/git/7f817a42a203920f3857d2b1ff6606743776b94e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7f817a42a203920f3857d2b1ff6606743776b94e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9050282885289903968",
"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/8884852823233132032/+/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:4b2a970c4f71b110",
"user_agent:luci-scheduler"
],
"updated_ts": "1585144398149014",
"url": "https://ci.chromium.org/b/8884852823233132032",
"utcnow_ts": "1585144398529348"
}
}
[13:53:18.541] Invocation finished in 55m20.245913274s with status SUCCEEDED