[01:49:58.430] New invocation is queued and will start shortly
[01:49:59.611] Starting the invocation (attempt 1)
[01:49:59.649] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:49:59.649] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:49:59.649] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022239852507007168",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"604cbb55e17d77e60269fa253bb936fbe2b0d00c\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9022239852507007168",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/604cbb55e17d77e60269fa253bb936fbe2b0d00c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/604cbb55e17d77e60269fa253bb936fbe2b0d00c",
"gitiles_ref:refs/heads/master"
]
}
[01:50:00.340] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611884999770392",
"id": "8856809790439740736",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"604cbb55e17d77e60269fa253bb936fbe2b0d00c\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611885000153302",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/180081",
"builder:Linux Builder (dbg)",
"buildset:commit/git/604cbb55e17d77e60269fa253bb936fbe2b0d00c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/604cbb55e17d77e60269fa253bb936fbe2b0d00c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022239852507007168",
"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/8856809790439740736/+/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": "1611885000153410",
"url": "https://ci.chromium.org/b/8856809790439740736",
"utcnow_ts": "1611885000274926"
}
}
[01:50:00.340] Task URL: https://ci.chromium.org/b/8856809790439740736
[01:50:00.340] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:2:0) after 1m0s
[01:50:02.694] Received PubSub notification, asking Buildbucket for the build status
[01:50:02.815] Build 8856809790439740736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:00.827] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:2:0)
[01:51:00.827] Timer tick, asking Buildbucket for the build status
[01:51:00.914] Build 8856809790439740736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:00.914] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:4:0) after 1m0s
[01:52:00.933] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:4:0)
[01:52:00.933] Timer tick, asking Buildbucket for the build status
[01:52:00.987] Build 8856809790439740736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:00.987] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:5:0) after 1m0s
[01:53:01.119] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:5:0)
[01:53:01.119] Timer tick, asking Buildbucket for the build status
[01:53:01.465] Build 8856809790439740736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:01.466] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:6:0) after 1m0s
[01:54:01.565] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:6:0)
[01:54:01.565] Timer tick, asking Buildbucket for the build status
[01:54:01.728] Build 8856809790439740736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:01.728] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:7:0) after 1m0s
[01:55:01.831] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:7:0)
[01:55:01.831] Timer tick, asking Buildbucket for the build status
[01:55:01.929] Build 8856809790439740736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:01.929] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:8:0) after 1m0s
[01:56:01.952] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:8:0)
[01:56:01.952] Timer tick, asking Buildbucket for the build status
[01:56:02.065] Build 8856809790439740736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:02.065] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:9:0) after 1m0s
[01:57:02.090] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:9:0)
[01:57:02.090] Timer tick, asking Buildbucket for the build status
[01:57:02.199] Build 8856809790439740736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:02.199] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:10:0) after 1m0s
[01:58:02.476] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:10:0)
[01:58:02.476] Timer tick, asking Buildbucket for the build status
[01:58:02.530] Build 8856809790439740736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:02.530] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:11:0) after 1m0s
[01:59:02.685] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:11:0)
[01:59:02.685] Timer tick, asking Buildbucket for the build status
[01:59:03.068] Build 8856809790439740736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:03.068] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:12:0) after 1m0s
[02:00:03.201] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:12:0)
[02:00:03.201] Timer tick, asking Buildbucket for the build status
[02:00:03.612] Build 8856809790439740736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:03.612] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:13:0) after 1m0s
[02:01:03.637] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:13:0)
[02:01:03.637] Timer tick, asking Buildbucket for the build status
[02:01:03.936] Build 8856809790439740736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:03.936] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:14:0) after 1m0s
[02:02:03.949] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:14:0)
[02:02:03.949] Timer tick, asking Buildbucket for the build status
[02:02:04.012] Build 8856809790439740736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:04.012] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:15:0) after 1m0s
[02:03:04.042] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:15:0)
[02:03:04.042] Timer tick, asking Buildbucket for the build status
[02:03:04.347] Build 8856809790439740736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:04.347] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:16:0) after 1m0s
[02:04:04.387] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:16:0)
[02:04:04.387] Timer tick, asking Buildbucket for the build status
[02:04:04.450] Build 8856809790439740736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:04.450] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:17:0) after 1m0s
[02:05:04.497] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:17:0)
[02:05:04.498] Timer tick, asking Buildbucket for the build status
[02:05:04.596] Build 8856809790439740736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:04.596] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:18:0) after 1m0s
[02:06:04.651] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:18:0)
[02:06:04.651] Timer tick, asking Buildbucket for the build status
[02:06:04.726] Build 8856809790439740736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:04.726] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:19:0) after 1m0s
[02:07:04.789] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:19:0)
[02:07:04.789] Timer tick, asking Buildbucket for the build status
[02:07:04.902] Build 8856809790439740736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:04.902] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:20:0) after 1m0s
[02:08:05.023] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:20:0)
[02:08:05.023] Timer tick, asking Buildbucket for the build status
[02:08:05.213] Build 8856809790439740736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:05.213] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:21:0) after 1m0s
[02:09:05.245] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:21:0)
[02:09:05.245] Timer tick, asking Buildbucket for the build status
[02:09:05.305] Build 8856809790439740736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:05.305] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:22:0) after 1m0s
[02:10:05.410] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:22:0)
[02:10:05.410] Timer tick, asking Buildbucket for the build status
[02:10:05.543] Build 8856809790439740736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:05.543] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:23:0) after 1m0s
[02:11:05.572] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:23:0)
[02:11:05.572] Timer tick, asking Buildbucket for the build status
[02:11:05.663] Build 8856809790439740736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:05.663] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:24:0) after 1m0s
[02:12:05.741] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:24:0)
[02:12:05.741] Timer tick, asking Buildbucket for the build status
[02:12:05.934] Build 8856809790439740736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:05.934] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:25:0) after 1m0s
[02:13:06.004] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:25:0)
[02:13:06.004] Timer tick, asking Buildbucket for the build status
[02:13:06.124] Build 8856809790439740736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:06.124] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:26:0) after 1m0s
[02:14:06.162] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:26:0)
[02:14:06.162] Timer tick, asking Buildbucket for the build status
[02:14:06.222] Build 8856809790439740736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:06.222] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:27:0) after 1m0s
[02:15:06.240] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:27:0)
[02:15:06.240] Timer tick, asking Buildbucket for the build status
[02:15:06.339] Build 8856809790439740736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:06.339] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:28:0) after 1m0s
[02:16:06.560] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:28:0)
[02:16:06.560] Timer tick, asking Buildbucket for the build status
[02:16:06.634] Build 8856809790439740736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:06.634] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:29:0) after 1m0s
[02:17:06.651] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:29:0)
[02:17:06.651] Timer tick, asking Buildbucket for the build status
[02:17:06.718] Build 8856809790439740736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:06.718] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:30:0) after 1m0s
[02:18:06.802] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:30:0)
[02:18:06.802] Timer tick, asking Buildbucket for the build status
[02:18:06.879] Build 8856809790439740736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:06.879] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:31:0) after 1m0s
[02:19:06.921] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:31:0)
[02:19:06.922] Timer tick, asking Buildbucket for the build status
[02:19:06.982] Build 8856809790439740736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:06.982] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:32:0) after 1m0s
[02:20:07.112] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:32:0)
[02:20:07.112] Timer tick, asking Buildbucket for the build status
[02:20:07.239] Build 8856809790439740736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:07.239] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:33:0) after 1m0s
[02:21:07.473] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:33:0)
[02:21:07.473] Timer tick, asking Buildbucket for the build status
[02:21:07.528] Build 8856809790439740736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:07.528] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:34:0) after 1m0s
[02:22:07.713] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:34:0)
[02:22:07.713] Timer tick, asking Buildbucket for the build status
[02:22:07.813] Build 8856809790439740736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:07.813] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:35:0) after 1m0s
[02:23:07.847] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:35:0)
[02:23:07.847] Timer tick, asking Buildbucket for the build status
[02:23:07.938] Build 8856809790439740736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:07.938] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:36:0) after 1m0s
[02:24:08.157] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:36:0)
[02:24:08.157] Timer tick, asking Buildbucket for the build status
[02:24:08.243] Build 8856809790439740736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:08.243] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:37:0) after 1m0s
[02:25:08.421] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:37:0)
[02:25:08.421] Timer tick, asking Buildbucket for the build status
[02:25:08.562] Build 8856809790439740736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:08.562] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:38:0) after 1m0s
[02:26:08.848] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:38:0)
[02:26:08.848] Timer tick, asking Buildbucket for the build status
[02:26:08.921] Build 8856809790439740736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:08.921] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:39:0) after 1m0s
[02:27:08.972] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:39:0)
[02:27:08.972] Timer tick, asking Buildbucket for the build status
[02:27:09.031] Build 8856809790439740736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:09.031] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:40:0) after 1m0s
[02:28:09.124] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:40:0)
[02:28:09.124] Timer tick, asking Buildbucket for the build status
[02:28:09.186] Build 8856809790439740736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:09.186] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:41:0) after 1m0s
[02:29:09.343] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:41:0)
[02:29:09.343] Timer tick, asking Buildbucket for the build status
[02:29:09.422] Build 8856809790439740736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:09.422] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:42:0) after 1m0s
[02:30:09.470] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:42:0)
[02:30:09.470] Timer tick, asking Buildbucket for the build status
[02:30:09.590] Build 8856809790439740736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:09.590] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:43:0) after 1m0s
[02:31:09.633] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:43:0)
[02:31:09.633] Timer tick, asking Buildbucket for the build status
[02:31:09.745] Build 8856809790439740736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:09.745] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:44:0) after 1m0s
[02:32:09.798] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:44:0)
[02:32:09.798] Timer tick, asking Buildbucket for the build status
[02:32:09.957] Build 8856809790439740736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:09.957] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:45:0) after 1m0s
[02:33:10.034] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:45:0)
[02:33:10.034] Timer tick, asking Buildbucket for the build status
[02:33:10.291] Build 8856809790439740736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:10.291] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:46:0) after 1m0s
[02:34:10.360] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:46:0)
[02:34:10.360] Timer tick, asking Buildbucket for the build status
[02:34:10.469] Build 8856809790439740736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:10.469] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:47:0) after 1m0s
[02:35:10.580] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:47:0)
[02:35:10.580] Timer tick, asking Buildbucket for the build status
[02:35:10.855] Build 8856809790439740736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:10.855] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:48:0) after 1m0s
[02:36:10.930] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:48:0)
[02:36:10.930] Timer tick, asking Buildbucket for the build status
[02:36:11.238] Build 8856809790439740736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:11.238] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:49:0) after 1m0s
[02:37:11.401] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:49:0)
[02:37:11.401] Timer tick, asking Buildbucket for the build status
[02:37:11.456] Build 8856809790439740736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:11.456] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:50:0) after 1m0s
[02:38:11.654] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:50:0)
[02:38:11.654] Timer tick, asking Buildbucket for the build status
[02:38:11.722] Build 8856809790439740736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:11.722] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:51:0) after 1m0s
[02:39:11.773] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:51:0)
[02:39:11.773] Timer tick, asking Buildbucket for the build status
[02:39:11.891] Build 8856809790439740736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:11.891] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:52:0) after 1m0s
[02:40:11.967] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:52:0)
[02:40:11.967] Timer tick, asking Buildbucket for the build status
[02:40:12.151] Build 8856809790439740736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:12.151] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:53:0) after 1m0s
[02:41:12.187] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:53:0)
[02:41:12.187] Timer tick, asking Buildbucket for the build status
[02:41:12.277] Build 8856809790439740736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:12.277] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:54:0) after 1m0s
[02:42:12.349] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:54:0)
[02:42:12.349] Timer tick, asking Buildbucket for the build status
[02:42:12.411] Build 8856809790439740736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:12.411] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:55:0) after 1m0s
[02:43:12.526] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:55:0)
[02:43:12.526] Timer tick, asking Buildbucket for the build status
[02:43:12.926] Build 8856809790439740736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:12.927] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:56:0) after 1m0s
[02:44:13.025] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:56:0)
[02:44:13.025] Timer tick, asking Buildbucket for the build status
[02:44:13.089] Build 8856809790439740736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:13.089] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9022239852507007168:57:0) after 1m0s
[02:45:07.042] Received PubSub notification, asking Buildbucket for the build status
[02:45:07.466] Build 8856809790439740736: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[02:45:07.466] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611888305803643",
"created_by": "project:chromium",
"created_ts": "1611884999770392",
"id": "8856809790439740736",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"604cbb55e17d77e60269fa253bb936fbe2b0d00c\"}}",
"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\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"604cbb55e17d77e60269fa253bb936fbe2b0d00c\", \"project\": \"chromium/src\"}], \"$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-72-9inl\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.linux\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 180081, \"got_angle_revision\": \"a685db2e9745b615987b1b514dc23cc2fafff7bd\", \"got_dawn_revision\": \"c1d3a66bd217395a501543b09c6d2c25e9d2e22e\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"604cbb55e17d77e60269fa253bb936fbe2b0d00c\", \"got_revision_cp\": \"refs/heads/master@{#848372}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"3e9b79ff42de0f5547809354de89e24c7a439e39\", \"got_v8_revision\": \"89b433d7c3defd04809fba9deb6f8885160044a4\", \"got_v8_revision_cp\": \"refs/heads/9.0.100@{#1}\", \"got_webrtc_revision\": \"54b925cfc2cd7c2f8f83e8e484f281aa87b92dfc\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33090}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.linux:linux-clang-tidy-dbg\", \"tryserver.chromium.linux:linux_chromium_dbg_ng\", \"tryserver.chromium.linux:linux_chromium_compile_dbg_ng\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"604cbb55e17d77e60269fa253bb936fbe2b0d00c\", \"swarm_hashes_refs/heads/master(at){#848372}_without_patch\": {\"absl_hardening_tests\": \"794b4bb07b0d466bd0105d14d2203dc9ac5d6593\", \"accessibility_unittests\": \"b6c6f1c8d8729663c222c82f4fd223cbe124a8cd\", \"angle_unittests\": \"5882dd77e2a5b9f12f1f5b5dbabda8122ad2333a\", \"app_shell_unittests\": \"3fbb1f3cefead1dcb9c0f9a49fa5367e957e9162\", \"aura_unittests\": \"e51608b5f9cdc3016d83aa3aeb11f221f63b3b81\", \"base_unittests\": \"a6be62846f47ad96e16d088ca0b1bbce82833f47\", \"base_util_unittests\": \"23cbf3ac1d339ca4253feb6eb0c363a9b216b2de\", \"blink_common_unittests\": \"aa27489f7ec27081d062cd02921225a6957e668c\", \"blink_fuzzer_unittests\": \"71f454642761397a38e2e12a03c3950991f4374c\", \"blink_heap_unittests\": \"5856bdf2ab378361f037a4d8e9dc9c89520de596\", \"blink_platform_unittests\": \"f20b95161b72d60ebc23cfb7537ba5e364723f69\", \"blink_python_tests\": \"f218a47e696375c8a4076a6cfe118193d5a0a36f\", \"blink_unittests\": \"a9bc37e77a6abc0269ece5d7bf600af1bcd3fcb2\", \"blink_web_tests\": \"049de0bf12db3ee40c6bc3708192b757b33ce48d\", \"boringssl_crypto_tests\": \"f0ee32bda973d7889f56d7c36cd6cac125de4fb8\", \"boringssl_ssl_tests\": \"bc10f1b2c234d02a63663af1b952411ce68efa91\", \"browser_tests\": \"c55ff62767bbc5c21fd5b0175589a066f97847f2\", \"capture_unittests\": \"a267e9f2372eb52f1c8510ba3a3e6b16c88bf1cf\", \"cast_unittests\": \"8cb77bb737c99a288b7a3b9a04d9173fd0a6bd3d\", \"cc_unittests\": \"2f1832d46e6cb04dc85d4212715b6ec697234335\", \"chrome_app_unittests\": \"1468d0d153c348eacebe91e66d61d507df83860d\", \"chromedriver_unittests\": \"6c36255d6bd8c4adb08827336fa25f91f12883d3\", \"components_browsertests\": \"afb7899d7fdc87ae127872dc6f3f7c020f4e5aac\", \"components_unittests\": \"7b1b9cd08cd4295b383966945f0bede67b7e4202\", \"compositor_unittests\": \"0417d57c5b17b16950a058b1d1abe97dfc4b3ace\", \"content_browsertests\": \"8969224564dd8c66a8ef7a157c936e02e2f34d7c\", \"content_shell_crash_test\": \"7b586a5f2445ae0763faad4b2b533da306f6f2db\", \"content_unittests\": \"234a7ef8be07d8474f015976633b802929de5b1e\", \"crashpad_tests\": \"9de87a7347634425d4315f5dcd51dc022a0ce4c3\", \"cronet_tests\": \"5868428f43470dfa9f124948b1bcf8d754875b45\", \"cronet_unittests\": \"0b3b91300f082cd5587b41d7e7a25c0bb6acde91\", \"crypto_unittests\": \"eb70c04c7882e1ce1d9554cfc62651c97450cf9e\", \"dbus_unittests\": \"b2d1529911c6a0fea22520469bd0cbfe8469f35d\", \"device_unittests\": \"f762489f9c047a818a2fb87f16f74988e208fb64\", \"display_unittests\": \"88f8724c2312f79e8ff8460336034ace827e0c89\", \"events_unittests\": \"bd3ac53da143473b062ec1e134f63d126fa3f610\", \"extensions_browsertests\": \"69c2a5225bace79be7ee92456614c9ebbc51f6ee\", \"extensions_unittests\": \"26d939a9956aecd707c4c902e00642a98213993f\", \"filesystem_service_unittests\": \"e0877790c9a8ba8eb75425dfcd30b7a5e21f8f99\", \"flatbuffers_unittests\": \"fce37724071c784947940c6bc999d4d0257c1896\", \"gcm_unit_tests\": \"a2a3c038781e89910367f2909b2fefe2cb76805e\", \"gfx_unittests\": \"15d32a275db9a9be5749959ad533debc4fa0e0bb\", \"gin_unittests\": \"e9edcf617099f8980cb3fd80f636d4a5f7ffd998\", \"google_apis_unittests\": \"d841242a10d40763e069ff74718fd30bfc14a57c\", \"gpu_unittests\": \"5e1bdedd3e1ae537005dddd4010917fa64f1043d\", \"grit_python_unittests\": \"b0890b035c0d25a1641db23ae376539c3f64cf64\", \"gwp_asan_unittests\": \"43717c7fed98c3ba8d7637877dbc347d0894cef6\", \"headless_browsertests\": \"1a60cd3fda19f0362c805a10f5c0a084f0592fba\", \"headless_unittests\": \"18ef7e8b980b4ab08fa10f48c04e68f56f34fa9b\", \"interactive_ui_tests\": \"7ae8971bb05d105d284c6d74ba5ef47c906ceee4\", \"ipc_tests\": \"1b3ae573ee5faeefc466386a2a6557e7b96cfe53\", \"jingle_unittests\": \"ba28cdb7a41f646f1a7e48023fbaf27b72d1fa1a\", \"latency_unittests\": \"2631a1b210c372af66772920aa0dc120f8bc6d95\", \"libjingle_xmpp_unittests\": \"1765f0736363db200955495271e51ec2c6f3e645\", \"liburlpattern_unittests\": \"91fab8c336e1feb5d52309480d9809b0000f7751\", \"media_blink_unittests\": \"1eda7d181ef49e9ddb335c9bdc0a714220bb835c\", \"media_unittests\": \"b58b830debf8d1f0e6a9ffe2deb7cf070257bc6c\", \"message_center_unittests\": \"2d49a6a9031fb69fc53d8a79801f0fb0b93d13ff\", \"metrics_python_tests\": \"031981b287aff1d32853c64ff5cd7f5488f6bf94\", \"midi_unittests\": \"a6c544a44009da051033e4729cef1eaea58bb01d\", \"mojo_core_unittests\": \"b86a736b7b5db0a681d36460acc6caabf432a23f\", \"mojo_unittests\": \"61e8b9fb4a4dc0db7834f9429ed3f7ebf9d7aa5f\", \"nacl_helper_nonsfi_unittests\": \"beb16ef7a2ea1dde6d9671953ad3020a034a345d\", \"nacl_loader_unittests\": \"1f718b30cc91ab380039ad45f903c5273d710192\", \"native_theme_unittests\": \"468fd80ff609f7bd88792e40227a682db7d29eca\", \"net_unittests\": \"c93e3b5bafc108048caf07092d2630ec77d5a98b\", \"openscreen_unittests\": \"6f56b655b64770809c5c6d880d644939632f469e\", \"pdf_unittests\": \"35d4473d0ed8ecee0f0252cf34d17275ac16c43d\", \"perfetto_unittests\": \"22ca10edc36e4b1a3ac115c0af839735a098400c\", \"ppapi_unittests\": \"8123723ff658de2224869f772238c6c7ba5b668a\", \"printing_unittests\": \"5555e58b5ed4bde63ba94503968e51cd53a8e795\", \"remoting_unittests\": \"f5951cb0a7b5febbe9d428ae1da1d143af6c67f2\", \"sandbox_linux_unittests\": \"ed50997fb6facfed91cb3d24f8365e789e7b232e\", \"service_manager_unittests\": \"15a8b6c47e8d6cde625d52b44633f8688abef1f8\", \"services_unittests\": \"4f278a5d1c7d2cad039c26ff645a5c387a3cddfd\", \"shell_dialogs_unittests\": \"071d4057b2db760b6305625ed94b2057a5ede1f3\", \"skia_unittests\": \"2cb6f41a49d430b0bb6a0934f3f5ea089c70f3ee\", \"snapshot_unittests\": \"ae11e1b22cec45bf95b1712f3576897bc7a87b5a\", \"sql_unittests\": \"355193100c7729e2ccdc0b59338591a6ab1e3249\", \"storage_unittests\": \"0c1e1559861a181c54b90767b2fd6feb38588e5b\", \"sync_integration_tests\": \"82c39492d81001dc0703d27de0a1f2aafc73d156\", \"telemetry_gpu_unittests\": \"902a7312585d8c78486b472f8d0be58811224073\", \"telemetry_perf_unittests\": \"b46b82ccb84a0c7132352ffa335fab08ebc21c72\", \"telemetry_unittests\": \"29500eb0ad2df58753dd24f1ffe53c51cf3ad187\", \"traffic_annotation_auditor_unittests\": \"e7c257b8e6d984d91bb210b1db279ec5e9c79934\", \"ui_base_unittests\": \"161d96319af950f472b2a4d0ac50caa56e5d728b\", \"ui_touch_selection_unittests\": \"849b524c886548c0908f391d87ca2be8e111be07\", \"unit_tests\": \"ad0b1a29eaf77ea948d4a9c34a0b52c81f21804d\", \"url_unittests\": \"f2f7bd3817c7d0807b7c837ac98464ee8fa970bd\", \"views_perftests\": \"b6da589410d352e472805f2acfb6e07fc3702beb\", \"views_unittests\": \"2fd926894fb73bf2e6127ca38b17a95a66fede1e\", \"viz_unittests\": \"9ae1a7823edc592789ed491cbade2b22ae8de6f9\", \"vr_common_unittests\": \"f19b939d52959dd5f061443237a662a7b0b5ce35\", \"vr_pixeltests\": \"f403764c5aecd113bd9423c0ebf81327a73635b1\", \"webdriver_wpt_tests\": \"1e3dda7253d0d09e6daeabed90b981e9f1be271d\", \"weblayer_browsertests\": \"d795df330a9a8a68660a26fec2d188d553c383ea\", \"weblayer_unittests\": \"6b20571e14c65f39b68217a00d7b1d7d576a6fa8\", \"wm_unittests\": \"57a935e581eab8dde9afecd9cecebd16d3249dda\", \"wtf_unittests\": \"43318a4a7ec482ad897b818ce05717c980f3c154\", \"x11_unittests\": \"e1e06da4faf164ea615142866f71c01bd9907cd1\", \"xr_browser_tests\": \"4a685c6d07705df0ce74427f044e556f819c1a0a\", \"zlib_unittests\": \"9325356fff3106db8bcbbb45ff41b9ab5399935a\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_1565bd2e801a59db86c3cb29ff80b2e229b1f19a08963dbe4c2b49186438622b_v2\", \"builder_17616f83fd632132c65ea3dd60ce01b78f71f01fa76c3e3446f88a64858a3864_v2\", \"builder_746f25208a125120dfc7ef61e5ef35531bce9c0f7be870eb0811bf6f56bb01d6_v2\", \"builder_d99b56d6fc6fd15316497c0509dbc8d437110174a09b554c19aeca35e1687cdb_v2\", \"builder_e21c17032b725bcf1c922976a36352546baf3f39ec148d1e6730a93c0400d8cc_v2\", \"builder_fa0031875b7f2f11c46443055ab400bfaeb07a1d2a142b58c631314016c04e0a_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-8-72-9inl\"], \"image\": [\"chrome-xenial-21011100-9488e34e558\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5673-2413400\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1611885001828147",
"status": "COMPLETED",
"status_changed_ts": "1611888306621460",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/180081",
"builder:Linux Builder (dbg)",
"buildset:commit/git/604cbb55e17d77e60269fa253bb936fbe2b0d00c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/604cbb55e17d77e60269fa253bb936fbe2b0d00c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022239852507007168",
"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/8856809790439740736/+/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:5164a6bea2064710",
"user_agent:luci-scheduler"
],
"updated_ts": "1611888306626718",
"url": "https://ci.chromium.org/b/8856809790439740736",
"utcnow_ts": "1611888307447827"
}
}
[02:45:07.466] Invocation finished in 55m9.051015219s with status SUCCEEDED