[08:45:44.172] New invocation is queued and will start shortly
[08:45:45.269] Starting the invocation (attempt 1)
[08:45:45.322] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:45:45.322] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:45:45.322] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9054103827176857296",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"b20f96817fbab10be2be26813bb93bf498198cfe\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9054103827176857296",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/b20f96817fbab10be2be26813bb93bf498198cfe",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b20f96817fbab10be2be26813bb93bf498198cfe",
"gitiles_ref:refs/heads/master"
]
}
[08:45:47.483] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1581497146439526",
"id": "8888673764133438576",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b20f96817fbab10be2be26813bb93bf498198cfe\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1581497147257336",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168511",
"builder:Linux Builder (dbg)",
"buildset:commit/git/b20f96817fbab10be2be26813bb93bf498198cfe",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b20f96817fbab10be2be26813bb93bf498198cfe",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054103827176857296",
"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/8888673764133438576/+/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": "1581497147257921",
"url": "https://ci.chromium.org/b/8888673764133438576",
"utcnow_ts": "1581497147472211"
}
}
[08:45:47.483] Task URL: https://ci.chromium.org/b/8888673764133438576
[08:45:47.483] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:2:0) after 1m0s
[08:46:47.500] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:2:0)
[08:46:47.500] Timer tick, asking Buildbucket for the build status
[08:46:47.638] Build 8888673764133438576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:46:47.638] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:3:0) after 1m0s
[08:47:47.646] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:3:0)
[08:47:47.646] Timer tick, asking Buildbucket for the build status
[08:47:47.775] Build 8888673764133438576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:47:47.775] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:4:0) after 1m0s
[08:48:47.768] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:4:0)
[08:48:47.768] Timer tick, asking Buildbucket for the build status
[08:48:47.918] Build 8888673764133438576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:48:47.918] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:5:0) after 1m0s
[08:49:47.934] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:5:0)
[08:49:47.934] Timer tick, asking Buildbucket for the build status
[08:49:48.639] Build 8888673764133438576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:49:48.639] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:6:0) after 1m0s
[08:50:08.170] Received PubSub notification, asking Buildbucket for the build status
[08:50:08.297] Build 8888673764133438576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:48.823] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:6:0)
[08:50:48.823] Timer tick, asking Buildbucket for the build status
[08:50:48.963] Build 8888673764133438576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:48.963] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:8:0) after 1m0s
[08:51:48.980] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:8:0)
[08:51:48.980] Timer tick, asking Buildbucket for the build status
[08:51:49.158] Build 8888673764133438576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:49.158] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:9:0) after 1m0s
[08:52:49.177] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:9:0)
[08:52:49.177] Timer tick, asking Buildbucket for the build status
[08:52:49.983] Build 8888673764133438576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:49.983] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:10:0) after 1m0s
[08:53:50.015] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:10:0)
[08:53:50.015] Timer tick, asking Buildbucket for the build status
[08:53:50.210] Build 8888673764133438576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:50.210] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:11:0) after 1m0s
[08:54:50.270] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:11:0)
[08:54:50.270] Timer tick, asking Buildbucket for the build status
[08:54:50.516] Build 8888673764133438576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:50.516] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:12:0) after 1m0s
[08:55:50.595] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:12:0)
[08:55:50.595] Timer tick, asking Buildbucket for the build status
[08:55:51.063] Build 8888673764133438576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:51.063] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:13:0) after 1m0s
[08:56:51.083] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:13:0)
[08:56:51.083] Timer tick, asking Buildbucket for the build status
[08:56:51.249] Build 8888673764133438576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:51.249] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:14:0) after 1m0s
[08:57:51.268] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:14:0)
[08:57:51.268] Timer tick, asking Buildbucket for the build status
[08:57:51.420] Build 8888673764133438576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:51.420] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:15:0) after 1m0s
[08:58:51.466] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:15:0)
[08:58:51.466] Timer tick, asking Buildbucket for the build status
[08:58:51.913] Build 8888673764133438576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:51.913] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:16:0) after 1m0s
[08:59:51.933] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:16:0)
[08:59:51.933] Timer tick, asking Buildbucket for the build status
[08:59:52.275] Build 8888673764133438576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:52.275] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:17:0) after 1m0s
[09:00:52.325] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:17:0)
[09:00:52.325] Timer tick, asking Buildbucket for the build status
[09:00:52.826] Build 8888673764133438576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:52.826] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:18:0) after 1m0s
[09:01:52.937] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:18:0)
[09:01:52.937] Timer tick, asking Buildbucket for the build status
[09:01:53.723] Build 8888673764133438576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:53.723] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:19:0) after 1m0s
[09:02:53.754] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:19:0)
[09:02:53.754] Timer tick, asking Buildbucket for the build status
[09:02:53.913] Build 8888673764133438576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:53.914] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:20:0) after 1m0s
[09:03:53.923] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:20:0)
[09:03:53.923] Timer tick, asking Buildbucket for the build status
[09:03:54.389] Build 8888673764133438576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:54.389] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:21:0) after 1m0s
[09:04:54.405] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:21:0)
[09:04:54.405] Timer tick, asking Buildbucket for the build status
[09:04:54.783] Build 8888673764133438576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:54.783] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:22:0) after 1m0s
[09:05:54.837] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:22:0)
[09:05:54.837] Timer tick, asking Buildbucket for the build status
[09:05:54.979] Build 8888673764133438576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:54.980] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:23:0) after 1m0s
[09:06:55.037] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:23:0)
[09:06:55.037] Timer tick, asking Buildbucket for the build status
[09:06:55.385] Build 8888673764133438576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:55.385] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:24:0) after 1m0s
[09:07:55.506] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:24:0)
[09:07:55.506] Timer tick, asking Buildbucket for the build status
[09:07:55.639] Build 8888673764133438576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:55.639] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:25:0) after 1m0s
[09:08:55.658] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:25:0)
[09:08:55.658] Timer tick, asking Buildbucket for the build status
[09:08:56.347] Build 8888673764133438576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:56.347] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:26:0) after 1m0s
[09:09:56.443] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:26:0)
[09:09:56.443] Timer tick, asking Buildbucket for the build status
[09:09:56.747] Build 8888673764133438576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:56.747] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:27:0) after 1m0s
[09:10:56.782] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:27:0)
[09:10:56.782] Timer tick, asking Buildbucket for the build status
[09:10:56.951] Build 8888673764133438576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:56.951] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:28:0) after 1m0s
[09:11:57.021] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:28:0)
[09:11:57.021] Timer tick, asking Buildbucket for the build status
[09:11:57.201] Build 8888673764133438576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:57.201] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:29:0) after 1m0s
[09:12:57.229] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:29:0)
[09:12:57.229] Timer tick, asking Buildbucket for the build status
[09:12:57.364] Build 8888673764133438576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:57.364] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:30:0) after 1m0s
[09:13:57.384] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:30:0)
[09:13:57.385] Timer tick, asking Buildbucket for the build status
[09:13:57.689] Build 8888673764133438576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:57.689] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:31:0) after 1m0s
[09:14:57.805] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:31:0)
[09:14:57.805] Timer tick, asking Buildbucket for the build status
[09:14:57.950] Build 8888673764133438576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:57.950] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:32:0) after 1m0s
[09:15:58.017] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:32:0)
[09:15:58.017] Timer tick, asking Buildbucket for the build status
[09:15:59.135] Build 8888673764133438576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:59.135] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:33:0) after 1m0s
[09:16:59.161] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:33:0)
[09:16:59.161] Timer tick, asking Buildbucket for the build status
[09:16:59.319] Build 8888673764133438576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:59.319] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:34:0) after 1m0s
[09:17:59.486] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:34:0)
[09:17:59.486] Timer tick, asking Buildbucket for the build status
[09:18:00.383] Build 8888673764133438576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:00.383] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:35:0) after 1m0s
[09:19:00.444] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:35:0)
[09:19:00.444] Timer tick, asking Buildbucket for the build status
[09:19:00.648] Build 8888673764133438576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:00.648] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:36:0) after 1m0s
[09:20:00.675] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:36:0)
[09:20:00.675] Timer tick, asking Buildbucket for the build status
[09:20:00.924] Build 8888673764133438576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:00.924] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:37:0) after 1m0s
[09:21:01.044] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:37:0)
[09:21:01.044] Timer tick, asking Buildbucket for the build status
[09:21:01.575] Build 8888673764133438576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:01.575] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:38:0) after 1m0s
[09:22:01.609] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:38:0)
[09:22:01.610] Timer tick, asking Buildbucket for the build status
[09:22:01.796] Build 8888673764133438576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:01.796] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:39:0) after 1m0s
[09:23:01.840] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:39:0)
[09:23:01.840] Timer tick, asking Buildbucket for the build status
[09:23:02.171] Build 8888673764133438576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:02.171] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:40:0) after 1m0s
[09:24:02.201] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:40:0)
[09:24:02.201] Timer tick, asking Buildbucket for the build status
[09:24:02.600] Build 8888673764133438576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:02.600] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:41:0) after 1m0s
[09:25:02.736] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:41:0)
[09:25:02.736] Timer tick, asking Buildbucket for the build status
[09:25:02.887] Build 8888673764133438576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:02.887] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:42:0) after 1m0s
[09:26:03.033] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:42:0)
[09:26:03.033] Timer tick, asking Buildbucket for the build status
[09:26:03.858] Build 8888673764133438576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:03.858] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:43:0) after 1m0s
[09:27:03.974] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:43:0)
[09:27:03.974] Timer tick, asking Buildbucket for the build status
[09:27:04.136] Build 8888673764133438576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:04.136] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:44:0) after 1m0s
[09:28:04.327] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:44:0)
[09:28:04.327] Timer tick, asking Buildbucket for the build status
[09:28:04.531] Build 8888673764133438576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:04.531] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:45:0) after 1m0s
[09:29:04.540] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:45:0)
[09:29:04.540] Timer tick, asking Buildbucket for the build status
[09:29:05.365] Build 8888673764133438576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:05.365] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:46:0) after 1m0s
[09:30:05.415] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:46:0)
[09:30:05.415] Timer tick, asking Buildbucket for the build status
[09:30:06.097] Build 8888673764133438576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:06.097] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:47:0) after 1m0s
[09:31:06.172] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:47:0)
[09:31:06.172] Timer tick, asking Buildbucket for the build status
[09:31:06.358] Build 8888673764133438576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:06.358] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:48:0) after 1m0s
[09:32:06.400] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:48:0)
[09:32:06.400] Timer tick, asking Buildbucket for the build status
[09:32:06.531] Build 8888673764133438576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:06.531] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:49:0) after 1m0s
[09:33:06.585] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:49:0)
[09:33:06.585] Timer tick, asking Buildbucket for the build status
[09:33:06.804] Build 8888673764133438576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:06.804] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:50:0) after 1m0s
[09:34:06.814] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:50:0)
[09:34:06.814] Timer tick, asking Buildbucket for the build status
[09:34:07.564] Build 8888673764133438576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:07.564] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:51:0) after 1m0s
[09:35:07.717] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:51:0)
[09:35:07.717] Timer tick, asking Buildbucket for the build status
[09:35:07.903] Build 8888673764133438576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:07.903] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:52:0) after 1m0s
[09:36:07.994] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:52:0)
[09:36:07.994] Timer tick, asking Buildbucket for the build status
[09:36:08.138] Build 8888673764133438576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:08.138] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:53:0) after 1m0s
[09:37:08.155] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:53:0)
[09:37:08.155] Timer tick, asking Buildbucket for the build status
[09:37:08.613] Build 8888673764133438576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:08.613] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:54:0) after 1m0s
[09:38:08.615] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:54:0)
[09:38:08.615] Timer tick, asking Buildbucket for the build status
[09:38:09.082] Build 8888673764133438576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:09.082] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:55:0) after 1m0s
[09:39:09.156] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:55:0)
[09:39:09.156] Timer tick, asking Buildbucket for the build status
[09:39:09.311] Build 8888673764133438576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:09.311] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:56:0) after 1m0s
[09:40:09.368] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:56:0)
[09:40:09.368] Timer tick, asking Buildbucket for the build status
[09:40:09.554] Build 8888673764133438576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:09.554] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:57:0) after 1m0s
[09:41:09.630] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:57:0)
[09:41:09.630] Timer tick, asking Buildbucket for the build status
[09:41:09.844] Build 8888673764133438576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:09.844] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:58:0) after 1m0s
[09:42:09.830] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:58:0)
[09:42:09.830] Timer tick, asking Buildbucket for the build status
[09:42:10.152] Build 8888673764133438576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:10.152] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:59:0) after 1m0s
[09:43:10.243] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:59:0)
[09:43:10.243] Timer tick, asking Buildbucket for the build status
[09:43:10.437] Build 8888673764133438576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:10.437] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:60:0) after 1m0s
[09:44:10.474] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:60:0)
[09:44:10.475] Timer tick, asking Buildbucket for the build status
[09:44:10.946] Build 8888673764133438576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:10.946] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:61:0) after 1m0s
[09:45:10.965] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:61:0)
[09:45:10.965] Timer tick, asking Buildbucket for the build status
[09:45:11.118] Build 8888673764133438576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:11.118] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:62:0) after 1m0s
[09:46:11.170] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:62:0)
[09:46:11.170] Timer tick, asking Buildbucket for the build status
[09:46:12.060] Build 8888673764133438576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:12.060] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:63:0) after 1m0s
[09:47:12.124] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:63:0)
[09:47:12.124] Timer tick, asking Buildbucket for the build status
[09:47:12.311] Build 8888673764133438576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:12.311] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054103827176857296:64:0) after 1m0s
[09:47:18.079] Received PubSub notification, asking Buildbucket for the build status
[09:47:18.194] Build 8888673764133438576: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[09:47:18.195] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1581500834451477",
"created_by": "project:chromium",
"created_ts": "1581497146439526",
"id": "8888673764133438576",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b20f96817fbab10be2be26813bb93bf498198cfe\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"jobs\": 500.0, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-central1-b-8-55-c9c9\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 168511, \"got_angle_revision\": \"e1ccac7ff9f088c841a4393b633be402012cfb92\", \"got_dawn_revision\": \"2fd618192985122aafb494ac5e9a39f572ef46fc\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"b20f96817fbab10be2be26813bb93bf498198cfe\", \"got_revision_cp\": \"refs/heads/master@{#740589}\", \"got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"got_swiftshader_revision\": \"6cbb02e0dc934ac1e21b182f0f632b4e806449a6\", \"got_v8_revision\": \"56ab990e69987df228757938e23a9bafccab2fe5\", \"got_v8_revision_cp\": \"refs/heads/8.2.57@{#1}\", \"got_webrtc_revision\": \"d4c3c3a454215b21f02d2a772a729b4c87d6b33e\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30486}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b20f96817fbab10be2be26813bb93bf498198cfe\", \"swarm_hashes_refs/heads/master(at){#740589}_without_patch\": {\"accessibility_unittests\": \"ec3854401fc3bf46e46f34bda6d87f214bbdfe3d\", \"angle_unittests\": \"1c11af93e0bf8397aabae070796ca366e775950a\", \"app_shell_unittests\": \"af5d9f422fe3db929b3553321699d889cfa85f30\", \"aura_unittests\": \"95f3d7668c6e51f22081ca48df9c29a6aa538f49\", \"base_unittests\": \"f666db766ec7d6f7cadd9fc0636f85f2654e85aa\", \"base_util_unittests\": \"fec655806adb25aa0455b1c1dc55f38e1c6cb58f\", \"blink_common_unittests\": \"ca6c347a15f8b023e87558c1d291452ef85ebf9d\", \"blink_fuzzer_unittests\": \"6a8c63b61e95f6db60e4d788c74f4fd23237809a\", \"blink_heap_unittests\": \"a631381d9d3ff6c9184e1c17b0bc6b55e8c4963b\", \"blink_platform_unittests\": \"db7e56db2fb898afd2a5984f74671abf29cbe976\", \"blink_python_tests\": \"66909fb4e1499e7733c5eb95536ed9ce6fc49608\", \"blink_unittests\": \"7b69b22231bfca58b84a6f2b0c8562a94d80054b\", \"blink_web_tests\": \"604c4a43055f3e402608a8fbb544b87b0c8e65e6\", \"boringssl_crypto_tests\": \"b431d9c10e0e691f8114025cb8a7758b7354828e\", \"boringssl_ssl_tests\": \"3174629a785aa144356ee3fb009e7e76637b6c17\", \"browser_tests\": \"0eadc25a1e978b0af13da0e26c0e97e92b11de0d\", \"cacheinvalidation_unittests\": \"8bb66ebe1fd63a9109e966a966d2366dce23df05\", \"capture_unittests\": \"cdc1592311814bf34b010372ebd8dc5cfb1c5ae0\", \"cast_unittests\": \"ca2af681ee41bd4fc1009ec15735e05322002948\", \"cc_unittests\": \"574c07c51b8a497b78a2d6180f11c671135e6ccc\", \"chrome_app_unittests\": \"4583694bfe8c7beba2547aefef7bf5235fe3ca58\", \"chromedriver_unittests\": \"193d0489e1a344cdf2d33cd5822ba770fe39a172\", \"components_browsertests\": \"9f38c771d43a31da6aa27292740dd7d22b39b031\", \"components_unittests\": \"87fc75637b2f20eb626a2f5b46733544449dd7ee\", \"compositor_unittests\": \"aea2f5b2d8072119b560b08b43e43e861cd4607d\", \"content_browsertests\": \"2ec908c7de678764fdda8323453320b2664031d0\", \"content_shell_crash_test\": \"815c8d8495448728581a12234e358b764d301997\", \"content_unittests\": \"c5712ddb7c6f9ea87eb8cbe1fff3391d90a64daf\", \"crashpad_tests\": \"ec75d37dc8fad08c60c1feeadacbbd76a35e82b4\", \"cronet_tests\": \"50ac60992a4ff8f579e7cf7657e4980633f5dbee\", \"cronet_unittests\": \"8fa2d8576f22517111eb889c8c02be815d39131e\", \"crypto_unittests\": \"a25a335f76c04c794b33f3d50fc599b73cbefca3\", \"dbus_unittests\": \"6dd1ce165c8b3c581f1b5cb07ab2f79b51ab5693\", \"device_unittests\": \"cac92fabc0e7aee280b2ca549780030a45338065\", \"display_unittests\": \"251f909a5cb1cb935d1ef2b7d60295cfe5672ff8\", \"events_unittests\": \"a33dd3638c140c7020525a8d9b42a4cc289bb7ee\", \"extensions_browsertests\": \"85010bd94f993f1066bb612c2b95447605b7d18a\", \"extensions_unittests\": \"c53cb4fc6b9f225f91ef02df1ce0a541de87fbc2\", \"filesystem_service_unittests\": \"e8beca5466babbe21f822b923e5da0853ef728b3\", \"flatbuffers_unittests\": \"9eab69acab0f6bd1460710d9ac6e8475de73625c\", \"gcm_unit_tests\": \"c89ef6a8d2fd2ce76160db819aee1de1c72f3f50\", \"gfx_unittests\": \"f85238c58e261480027f0761651d8fe471da29de\", \"gin_unittests\": \"f0b3e71fbeb09b035e6e5415145f6467da2c564f\", \"google_apis_unittests\": \"d019f1b299b68af3aaa70f2fc963e77bf81a9b20\", \"gpu_unittests\": \"5d38845d10be9fb06fc9e215e2cb5b33a6b80b80\", \"grit_python_unittests\": \"07488c096d20bb2a4e6d9280813fca6afbee6dc0\", \"gwp_asan_unittests\": \"f8db7f6d16cfb86de0453cd3fd01c1fb75daaa4d\", \"headless_browsertests\": \"08f2ddba019aa87e943e48a7a3448a9c8ee40995\", \"headless_unittests\": \"d484a0e2ecf0fc1c13b90253d10108531240502f\", \"interactive_ui_tests\": \"a1b817923dc5bd02d64182d3c521324714bf5abe\", \"ipc_tests\": \"e7e3666711459b7defb2c9eeadb5b7c84ff542b8\", \"jingle_unittests\": \"600f29cd773eb799a6529c5638700d22da125d2f\", \"latency_unittests\": \"58ec2286986e38a08fd7c323aeb5e8e32e6cb351\", \"libjingle_xmpp_unittests\": \"1fafd9711094fecb4293bc2f62369dbd2550ad5b\", \"media_blink_unittests\": \"1b199112237422b6d30fd5973a107fba662ed419\", \"media_service_unittests\": \"d836047fed0298c26ae5a45d3b21781a8cca30da\", \"media_unittests\": \"1ddbb9b85e329c432da43aa9000c9c81e856f246\", \"message_center_unittests\": \"1788299fad3257182c7d9b804df4acff03bd8f74\", \"metrics_python_tests\": \"a5974fbb5dc92027dc0af14d4957a4687a3769ea\", \"midi_unittests\": \"22dad53ed0cde4bee032d037594c09ec103b0470\", \"mojo_core_unittests\": \"baa40b90d445e56571704070c6fab3c333a1e6e1\", \"mojo_unittests\": \"7d841d4c7192f0b783df1ef7f778b7c152a58ab3\", \"nacl_helper_nonsfi_unittests\": \"a33f7c68729f10c173a87595856239d628714681\", \"nacl_loader_unittests\": \"a942e6f96afbcc0243132239cfa4259a9c318f02\", \"native_theme_unittests\": \"32f67017cf2100dceee0607286bd7992ba4e37ff\", \"net_unittests\": \"44e33cade02be3654259d457d719840bc990ec3e\", \"pdf_unittests\": \"bdb03ff9e3a05605c74063ce0bde4d6660258b0d\", \"perfetto_unittests\": \"ce6431570c88a01311ecd13fc058fb2b2700a6ad\", \"ppapi_unittests\": \"6d7651ef1fcca64651e090d46089542dcc9fe544\", \"printing_unittests\": \"237aafec5653c6d8981a6f044912f15cca98dc6b\", \"remoting_unittests\": \"805193ecc621afac02f99e9bb07c4fccedcec390\", \"sandbox_linux_unittests\": \"36d18bc0e90dc8d1020d2cd6162a5f7c7c134607\", \"service_manager_unittests\": \"072a4cd2292d303da1f596012701987b0c5df878\", \"services_unittests\": \"ba8e536bd7606f0f7d5a2abf2fad723b1afb487c\", \"shell_dialogs_unittests\": \"e91aa811b9951242af5213ae263e88bd3fe38bee\", \"skia_unittests\": \"354ace5e1db513fd8c9f8b97551c9e46cb8fbe1c\", \"snapshot_unittests\": \"67a549d83438f91e6c6aced45c9f7e7f7ad05117\", \"sql_unittests\": \"b1d6675db201737c7dd9ca6e7440af9bc9f9670b\", \"storage_unittests\": \"38c451f87dadf5752c54d814e48472a2ed79d6ad\", \"sync_integration_tests\": \"0e1c3a03aa89477dc4a8fc680895a1b170610f49\", \"telemetry_gpu_unittests\": \"6b9b7ec82828f1456c575591d206b3530ecfcbbe\", \"telemetry_perf_unittests\": \"ccbd88d112695463cebaef8c1b761bcfe36ec122\", \"telemetry_unittests\": \"80118285475f4d229f2442290c8d7ab452123f11\", \"traffic_annotation_auditor_unittests\": \"ff1cef85b5f21821757efa081b29b5442e845e66\", \"ui_base_unittests\": \"e55b5f0e9bec52e4e889e935f375a0bc9d05cb74\", \"ui_touch_selection_unittests\": \"a4b2a004e75f94e87ad7ad66744920f9e14dbac9\", \"unit_tests\": \"ccd617ed6e80b70d09f88e93fc966848e0ce38a2\", \"url_unittests\": \"cee860a07e8eb33c1973f780b006aa32e84c7089\", \"views_perftests\": \"30a6fc73095fa3c8af00d5be287ceab476968e8b\", \"views_unittests\": \"37acacdb34c1f59c399012385ce3d838fd4fa9a9\", \"viz_unittests\": \"cc50ce25c3a239bed96e39187af0e67130ab5519\", \"vr_common_unittests\": \"fe9fdb76920d157d79ff725e5cf900a6aee34062\", \"vr_pixeltests\": \"e9f305344b7efa5cec65f7bc1b5c6b8619a424a6\", \"webdriver_wpt_tests\": \"66bb51146d2f5deb2e2657b077cc6ab535f39a30\", \"weblayer_browsertests\": \"44b0583899bf398aed6b21c8f3c5aff4df3e8fe9\", \"wm_unittests\": \"75671d34fafae27a65410a1474f46b2111cd2919\", \"wtf_unittests\": \"bb08e1035835efed28c31eb073e9ca1a87fd32d9\", \"xr_browser_tests\": \"5e344702f54cdbe179089691d887369e35430358\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_06f1ed350fecda52f561db3eb00cb39e105b14f5c02d94078111c7babc8da8c6_v2\", \"builder_349392596e0d17d3422c55529f4e230875f36ec061130c176581539a4c7cd365_v2\", \"builder_361cbaa79261c75a92fc63a8f8c6fc117764238b3277286fa51086e6da3301df_v2\", \"builder_5ae174071c5d6730648bf4e0d5db68d27bfbde0d9c5b879a557aa1bc8e90c36b_v2\", \"builder_5c30bb016a77fbc5ea3e6fb824c869c1bb0f4222c21aad0a92d459b086fbc996_v2\", \"builder_980e79f61a9eb10b3ecd5f710f08f0308a5299da9889db3d2b6a7010c019cc1f_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-55-c9c9\"], \"image\": [\"chrome-xenial-20012600-8e1a7e485b7\"], \"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\": [\"4812-ea7e8fc\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1581497406867972",
"status": "COMPLETED",
"status_changed_ts": "1581500836952036",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168511",
"builder:Linux Builder (dbg)",
"buildset:commit/git/b20f96817fbab10be2be26813bb93bf498198cfe",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b20f96817fbab10be2be26813bb93bf498198cfe",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054103827176857296",
"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/8888673764133438576/+/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:4a5164e8b858f110",
"user_agent:luci-scheduler"
],
"updated_ts": "1581500836955571",
"url": "https://ci.chromium.org/b/8888673764133438576",
"utcnow_ts": "1581500838183606"
}
}
[09:47:18.195] Invocation finished in 1h1m34.04237943s with status SUCCEEDED