[18:25:20.784] New invocation is queued and will start shortly
[18:25:22.461] Starting the invocation (attempt 1)
[18:25:22.574] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:25:22.574] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:25:22.574] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9052074227994708016",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"6a23f301de451d08405b0b12df83ab0a709b78c0\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9052074227994708016",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/6a23f301de451d08405b0b12df83ab0a709b78c0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6a23f301de451d08405b0b12df83ab0a709b78c0",
"gitiles_ref:refs/heads/master"
]
}
[18:25:23.363] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1583432722717340",
"id": "8886644165302800976",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"6a23f301de451d08405b0b12df83ab0a709b78c0\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1583432723115764",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/169378",
"builder:Linux Builder (dbg)",
"buildset:commit/git/6a23f301de451d08405b0b12df83ab0a709b78c0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6a23f301de451d08405b0b12df83ab0a709b78c0",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9052074227994708016",
"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/8886644165302800976/+/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": "1583432723115874",
"url": "https://ci.chromium.org/b/8886644165302800976",
"utcnow_ts": "1583432723345919"
}
}
[18:25:23.363] Task URL: https://ci.chromium.org/b/8886644165302800976
[18:25:23.363] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:2:0) after 1m0s
[18:26:23.399] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:2:0)
[18:26:23.399] Timer tick, asking Buildbucket for the build status
[18:26:23.501] Build 8886644165302800976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:26:23.501] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:3:0) after 1m0s
[18:27:23.586] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:3:0)
[18:27:23.586] Timer tick, asking Buildbucket for the build status
[18:27:23.684] Build 8886644165302800976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:27:23.684] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:4:0) after 1m0s
[18:28:23.686] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:4:0)
[18:28:23.686] Timer tick, asking Buildbucket for the build status
[18:28:23.798] Build 8886644165302800976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:28:23.798] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:5:0) after 1m0s
[18:29:23.895] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:5:0)
[18:29:23.895] Timer tick, asking Buildbucket for the build status
[18:29:24.003] Build 8886644165302800976: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:29:24.003] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:6:0) after 1m0s
[18:30:04.800] Received PubSub notification, asking Buildbucket for the build status
[18:30:05.038] Build 8886644165302800976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:24.179] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:6:0)
[18:30:24.179] Timer tick, asking Buildbucket for the build status
[18:30:24.282] Build 8886644165302800976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:24.282] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:8:0) after 1m0s
[18:31:24.297] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:8:0)
[18:31:24.297] Timer tick, asking Buildbucket for the build status
[18:31:24.395] Build 8886644165302800976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:24.395] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:9:0) after 1m0s
[18:32:24.508] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:9:0)
[18:32:24.508] Timer tick, asking Buildbucket for the build status
[18:32:24.622] Build 8886644165302800976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:24.622] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:10:0) after 1m0s
[18:33:24.757] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:10:0)
[18:33:24.757] Timer tick, asking Buildbucket for the build status
[18:33:25.046] Build 8886644165302800976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:25.046] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:11:0) after 1m0s
[18:34:25.098] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:11:0)
[18:34:25.098] Timer tick, asking Buildbucket for the build status
[18:34:25.194] Build 8886644165302800976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:25.194] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:12:0) after 1m0s
[18:35:25.214] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:12:0)
[18:35:25.214] Timer tick, asking Buildbucket for the build status
[18:35:25.552] Build 8886644165302800976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:25.552] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:13:0) after 1m0s
[18:36:25.605] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:13:0)
[18:36:25.605] Timer tick, asking Buildbucket for the build status
[18:36:25.818] Build 8886644165302800976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:25.818] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:14:0) after 1m0s
[18:37:26.311] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:14:0)
[18:37:26.311] Timer tick, asking Buildbucket for the build status
[18:37:26.463] Build 8886644165302800976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:26.463] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:15:0) after 1m0s
[18:38:26.697] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:15:0)
[18:38:26.697] Timer tick, asking Buildbucket for the build status
[18:38:26.946] Build 8886644165302800976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:26.946] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:16:0) after 1m0s
[18:39:26.953] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:16:0)
[18:39:26.953] Timer tick, asking Buildbucket for the build status
[18:39:27.289] Build 8886644165302800976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:27.289] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:17:0) after 1m0s
[18:40:27.414] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:17:0)
[18:40:27.414] Timer tick, asking Buildbucket for the build status
[18:40:27.693] Build 8886644165302800976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:27.693] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:18:0) after 1m0s
[18:41:27.968] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:18:0)
[18:41:27.968] Timer tick, asking Buildbucket for the build status
[18:41:28.062] Build 8886644165302800976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:28.062] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:19:0) after 1m0s
[18:42:28.286] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:19:0)
[18:42:28.286] Timer tick, asking Buildbucket for the build status
[18:42:28.417] Build 8886644165302800976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:28.417] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:20:0) after 1m0s
[18:43:28.717] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:20:0)
[18:43:28.717] Timer tick, asking Buildbucket for the build status
[18:43:28.810] Build 8886644165302800976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:28.810] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:21:0) after 1m0s
[18:44:28.902] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:21:0)
[18:44:28.902] Timer tick, asking Buildbucket for the build status
[18:44:29.015] Build 8886644165302800976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:29.015] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:22:0) after 1m0s
[18:45:29.187] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:22:0)
[18:45:29.187] Timer tick, asking Buildbucket for the build status
[18:45:29.461] Build 8886644165302800976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:29.461] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:23:0) after 1m0s
[18:46:29.647] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:23:0)
[18:46:29.647] Timer tick, asking Buildbucket for the build status
[18:46:29.858] Build 8886644165302800976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:29.858] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:24:0) after 1m0s
[18:47:29.942] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:24:0)
[18:47:29.942] Timer tick, asking Buildbucket for the build status
[18:47:30.152] Build 8886644165302800976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:30.152] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:25:0) after 1m0s
[18:48:30.199] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:25:0)
[18:48:30.199] Timer tick, asking Buildbucket for the build status
[18:48:30.320] Build 8886644165302800976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:30.320] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:26:0) after 1m0s
[18:49:30.461] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:26:0)
[18:49:30.461] Timer tick, asking Buildbucket for the build status
[18:49:30.885] Build 8886644165302800976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:30.885] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:27:0) after 1m0s
[18:50:30.913] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:27:0)
[18:50:30.913] Timer tick, asking Buildbucket for the build status
[18:50:31.363] Build 8886644165302800976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:31.363] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:28:0) after 1m0s
[18:51:31.465] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:28:0)
[18:51:31.465] Timer tick, asking Buildbucket for the build status
[18:51:31.781] Build 8886644165302800976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:31.781] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:29:0) after 1m0s
[18:52:31.800] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:29:0)
[18:52:31.800] Timer tick, asking Buildbucket for the build status
[18:52:31.890] Build 8886644165302800976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:31.890] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:30:0) after 1m0s
[18:53:31.948] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:30:0)
[18:53:31.948] Timer tick, asking Buildbucket for the build status
[18:53:32.477] Build 8886644165302800976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:32.477] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:31:0) after 1m0s
[18:54:32.653] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:31:0)
[18:54:32.653] Timer tick, asking Buildbucket for the build status
[18:54:32.969] Build 8886644165302800976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:32.969] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:32:0) after 1m0s
[18:55:33.059] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:32:0)
[18:55:33.059] Timer tick, asking Buildbucket for the build status
[18:55:33.285] Build 8886644165302800976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:33.285] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:33:0) after 1m0s
[18:56:33.399] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:33:0)
[18:56:33.399] Timer tick, asking Buildbucket for the build status
[18:56:33.506] Build 8886644165302800976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:33.506] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:34:0) after 1m0s
[18:57:33.539] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:34:0)
[18:57:33.539] Timer tick, asking Buildbucket for the build status
[18:57:33.878] Build 8886644165302800976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:33.879] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:35:0) after 1m0s
[18:58:34.086] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:35:0)
[18:58:34.086] Timer tick, asking Buildbucket for the build status
[18:58:34.480] Build 8886644165302800976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:34.480] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:36:0) after 1m0s
[18:59:34.618] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:36:0)
[18:59:34.618] Timer tick, asking Buildbucket for the build status
[18:59:34.725] Build 8886644165302800976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:34.725] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:37:0) after 1m0s
[19:00:34.820] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:37:0)
[19:00:34.820] Timer tick, asking Buildbucket for the build status
[19:00:35.191] Build 8886644165302800976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:35.191] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:38:0) after 1m0s
[19:01:35.411] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:38:0)
[19:01:35.411] Timer tick, asking Buildbucket for the build status
[19:01:35.581] Build 8886644165302800976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:35.581] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:39:0) after 1m0s
[19:02:35.703] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:39:0)
[19:02:35.703] Timer tick, asking Buildbucket for the build status
[19:02:36.201] Build 8886644165302800976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:36.201] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:40:0) after 1m0s
[19:03:36.299] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:40:0)
[19:03:36.299] Timer tick, asking Buildbucket for the build status
[19:03:36.395] Build 8886644165302800976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:36.395] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:41:0) after 1m0s
[19:04:36.771] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:41:0)
[19:04:36.771] Timer tick, asking Buildbucket for the build status
[19:04:36.989] Build 8886644165302800976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:36.989] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:42:0) after 1m0s
[19:05:37.125] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:42:0)
[19:05:37.125] Timer tick, asking Buildbucket for the build status
[19:05:37.448] Build 8886644165302800976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:37.448] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:43:0) after 1m0s
[19:06:37.564] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:43:0)
[19:06:37.564] Timer tick, asking Buildbucket for the build status
[19:06:37.749] Build 8886644165302800976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:37.749] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:44:0) after 1m0s
[19:07:37.815] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:44:0)
[19:07:37.816] Timer tick, asking Buildbucket for the build status
[19:07:38.104] Build 8886644165302800976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:38.104] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:45:0) after 1m0s
[19:08:38.359] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:45:0)
[19:08:38.359] Timer tick, asking Buildbucket for the build status
[19:08:38.462] Build 8886644165302800976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:38.462] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:46:0) after 1m0s
[19:09:38.552] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:46:0)
[19:09:38.552] Timer tick, asking Buildbucket for the build status
[19:09:38.793] Build 8886644165302800976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:38.793] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:47:0) after 1m0s
[19:10:38.984] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:47:0)
[19:10:38.984] Timer tick, asking Buildbucket for the build status
[19:10:39.089] Build 8886644165302800976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:39.089] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:48:0) after 1m0s
[19:11:39.209] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:48:0)
[19:11:39.209] Timer tick, asking Buildbucket for the build status
[19:11:39.377] Build 8886644165302800976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:39.377] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:49:0) after 1m0s
[19:12:39.410] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:49:0)
[19:12:39.410] Timer tick, asking Buildbucket for the build status
[19:12:39.554] Build 8886644165302800976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:39.554] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:50:0) after 1m0s
[19:13:39.595] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:50:0)
[19:13:39.595] Timer tick, asking Buildbucket for the build status
[19:13:39.703] Build 8886644165302800976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:39.703] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:51:0) after 1m0s
[19:14:39.837] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:51:0)
[19:14:39.837] Timer tick, asking Buildbucket for the build status
[19:14:40.223] Build 8886644165302800976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:40.223] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:52:0) after 1m0s
[19:15:40.294] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:52:0)
[19:15:40.294] Timer tick, asking Buildbucket for the build status
[19:15:40.390] Build 8886644165302800976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:40.391] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:53:0) after 1m0s
[19:16:40.471] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:53:0)
[19:16:40.471] Timer tick, asking Buildbucket for the build status
[19:16:40.592] Build 8886644165302800976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:40.592] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:54:0) after 1m0s
[19:17:40.624] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:54:0)
[19:17:40.624] Timer tick, asking Buildbucket for the build status
[19:17:40.725] Build 8886644165302800976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:40.725] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:55:0) after 1m0s
[19:18:40.894] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:55:0)
[19:18:40.894] Timer tick, asking Buildbucket for the build status
[19:18:41.007] Build 8886644165302800976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:41.007] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:56:0) after 1m0s
[19:19:41.147] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:56:0)
[19:19:41.147] Timer tick, asking Buildbucket for the build status
[19:19:41.298] Build 8886644165302800976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:41.298] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:57:0) after 1m0s
[19:20:41.409] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:57:0)
[19:20:41.409] Timer tick, asking Buildbucket for the build status
[19:20:41.513] Build 8886644165302800976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:41.513] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:58:0) after 1m0s
[19:21:41.593] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:58:0)
[19:21:41.593] Timer tick, asking Buildbucket for the build status
[19:21:41.746] Build 8886644165302800976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:41.746] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:59:0) after 1m0s
[19:22:41.815] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:59:0)
[19:22:41.815] Timer tick, asking Buildbucket for the build status
[19:22:41.990] Build 8886644165302800976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:41.990] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:60:0) after 1m0s
[19:23:42.017] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:60:0)
[19:23:42.017] Timer tick, asking Buildbucket for the build status
[19:23:42.128] Build 8886644165302800976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:42.128] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:61:0) after 1m0s
[19:24:42.213] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:61:0)
[19:24:42.213] Timer tick, asking Buildbucket for the build status
[19:24:42.324] Build 8886644165302800976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:42.324] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:62:0) after 1m0s
[19:25:42.497] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:62:0)
[19:25:42.498] Timer tick, asking Buildbucket for the build status
[19:25:42.697] Build 8886644165302800976: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:42.697] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052074227994708016:63:0) after 1m0s
[19:26:37.337] Received PubSub notification, asking Buildbucket for the build status
[19:26:37.426] Build 8886644165302800976: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[19:26:37.426] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1583436396699789",
"created_by": "project:chromium",
"created_ts": "1583432722717340",
"id": "8886644165302800976",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"6a23f301de451d08405b0b12df83ab0a709b78c0\"}}",
"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-east1-d-8-49-sawd\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 169378, \"got_angle_revision\": \"852653954526386c86f120f6120b70fb74904b94\", \"got_dawn_revision\": \"2de34c1791f301f8ec647afd9011dd3afd502465\", \"got_nacl_revision\": \"35bcc9f6d5e81c3c39ad57de97ef9cd22301ee9e\", \"got_revision\": \"6a23f301de451d08405b0b12df83ab0a709b78c0\", \"got_revision_cp\": \"refs/heads/master@{#747335}\", \"got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"got_swiftshader_revision\": \"a095711c5d771437d7f9bfcbd78a82062e19c55e\", \"got_v8_revision\": \"fbadeeaf76d8a5000c7c5cd6e913be73ad46d524\", \"got_v8_revision_cp\": \"refs/heads/8.2.266@{#1}\", \"got_webrtc_revision\": \"b05ca4b6168ccad0db5f6350d438272e59a3b9ca\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30691}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"6a23f301de451d08405b0b12df83ab0a709b78c0\", \"swarm_hashes_refs/heads/master(at){#747335}_without_patch\": {\"accessibility_unittests\": \"f8040e6365f6845157fc80996193598ec88035f7\", \"angle_unittests\": \"2292afa79237cc3552f8b108a05ce86afbf62956\", \"app_shell_unittests\": \"fa6f9c311301f2956b1bbdcd7aa2a16b4beec1a3\", \"aura_unittests\": \"525df22826b14a45d237adbfee03e2cbcf50c9cd\", \"base_unittests\": \"76fa2259b70a7c784601c6957d72926b5fe5c59a\", \"base_util_unittests\": \"4b372af91e89e1c8e9de2783b6e6f5937981f262\", \"blink_common_unittests\": \"10504312dd9cedd4118df582396eaac980c41afc\", \"blink_fuzzer_unittests\": \"e76266f030efb4e901f1e4ba66be34dca3eb9139\", \"blink_heap_unittests\": \"e82f0b4cbb68fb58a73e4ce98910a8dd2c7a7756\", \"blink_platform_unittests\": \"83f071a9013896ec4de64fcd8be6b796d8be0a5e\", \"blink_python_tests\": \"e19120189aa0fe9a98019c905096a71647593a4e\", \"blink_unittests\": \"51815c79ed88f7c7d8c1b1884877bf6ba80b588b\", \"blink_web_tests\": \"fe139d8a6731b8e18656ffe59570cbc3796b7296\", \"boringssl_crypto_tests\": \"42e38e6c454a6b351ff344cd4e7749dbc9e9f2e4\", \"boringssl_ssl_tests\": \"e2c06736d256dfbb2b01043ac99f24bbc50f2616\", \"browser_tests\": \"1ca1c9dd3ace95973afdac2890496cc3bab69f1c\", \"cacheinvalidation_unittests\": \"5cd0061dcf277b4759dc6febcde36ee156729fdb\", \"capture_unittests\": \"08ade0d8efdc37b1f069d577a704ce4970044877\", \"cast_unittests\": \"c76085bcc79c5724bb585b915b4e20b271cd2257\", \"cc_unittests\": \"28f4899b3f898a674d6b6aa3c75be05b7c3a6501\", \"chrome_app_unittests\": \"9feff9302f0d78867809c21e21b252d522381f34\", \"chromedriver_unittests\": \"a5717f51fa150461185e5497e224b021fdd17fd1\", \"components_browsertests\": \"5fddfd9af2201fbf1d5c09e3dd505405f873e910\", \"components_unittests\": \"efb9ec1ca4ae57611a78d07a639d60e6ea8d4a0d\", \"compositor_unittests\": \"444f26bcd8a7481c8a71a8b58f81f04753c24e74\", \"content_browsertests\": \"e82db85fe89fcaca8560d172a6acb9ddfaccc493\", \"content_shell_crash_test\": \"a8604dfa1fced004ddacbef6c2718efcd9a92f75\", \"content_unittests\": \"1c1cbe9bd7869c54de301329018a859c60ef0e46\", \"crashpad_tests\": \"5e22312a04ec95c71f5928d87c8a3b42dd1575a3\", \"cronet_tests\": \"02829d8209698c26921700fee05250192fb83e82\", \"cronet_unittests\": \"51c0578356b324fab1321d60c67c633ca484339a\", \"crypto_unittests\": \"deb7f34acafeb32c30f32c1d4353e7aae719d408\", \"dbus_unittests\": \"71b2049ba9d9546e5af844e6436789cbee92ca90\", \"device_unittests\": \"1ff0e5a7d12fd0a9ec325e690ec74fd1d138e155\", \"display_unittests\": \"d17ca0ac3841a456576fd298af14073f566e9e88\", \"events_unittests\": \"d1f1b733f4626dcb2197cff5d15de69f9bc8c082\", \"extensions_browsertests\": \"7cd249a8b3ea59b407c5677f388080a77bc88805\", \"extensions_unittests\": \"ef6bb8061847089bb02b464d4de458f9d7bd3bc0\", \"filesystem_service_unittests\": \"36c695e98ba900f67677c424f820190b74b44d01\", \"flatbuffers_unittests\": \"501dfa51280eae93fccecaec30d9257cff6dbcc8\", \"gcm_unit_tests\": \"e5eb242bfed4e4cc5afb19b60f02ba29f534d9bc\", \"gfx_unittests\": \"67ed2e77bed3454c4880190829e64b466a4d91a5\", \"gin_unittests\": \"5a534a365c7049fd95b7573d6692d58bc5184d03\", \"google_apis_unittests\": \"866369c94896c29531555f05bd2c7fa43e13d4af\", \"gpu_unittests\": \"6cfe238554403ef2136ed858671e386fbfa768de\", \"grit_python_unittests\": \"300ea8455288eab00cd459e887bd99a4f6468e8a\", \"gwp_asan_unittests\": \"afc05923de4fe84e975cbc505ef136718eb8f5d5\", \"headless_browsertests\": \"e428f54fc1e293742d96d21417de695b71a67442\", \"headless_unittests\": \"dfc86b0722d7b2ad9c0c3eb599eec8b042b0ed1a\", \"interactive_ui_tests\": \"65696f2cc484808277bfb94e0c24690a0a5d2ba8\", \"ipc_tests\": \"6d6b4aad45f22edf97c17009a0bfa001154bafa6\", \"jingle_unittests\": \"188cddb18dd464f1ca848e5bfe38ace20520f4f2\", \"latency_unittests\": \"aae07dacd8709d6ca49517ce01f9c9a884b660be\", \"libjingle_xmpp_unittests\": \"90bb771f9aafa3a51e1ea3e7dbc9612872e16e28\", \"media_blink_unittests\": \"de5a011541a38121a5ba7aeefd85462a9c4fc351\", \"media_service_unittests\": \"d7a32dd3f6dac46cf016459e0e5c9fbbd514e53f\", \"media_unittests\": \"243d47a2b7d3332352cf4c830a469b8f13a521b0\", \"message_center_unittests\": \"d3e8f9b6a5a37388ff523bc6e4b4c28f0d44abca\", \"metrics_python_tests\": \"cc997a8acb50c05f404eae817c648d279f8ff3f7\", \"midi_unittests\": \"4562492ff05069e38b3c44da1935a8d368f2fa29\", \"mojo_core_unittests\": \"2db3d6ad5ea57263d7ec25a823f9edb819ea54e1\", \"mojo_unittests\": \"7cb8a14c9d0ce0efe5f3c5753382189f9093d4cc\", \"nacl_helper_nonsfi_unittests\": \"356ff34f1466d2f686ce8b84786c1d358cda7d85\", \"nacl_loader_unittests\": \"b6c6f1d467a2937aacb27cb37f5601ab4cf12c3a\", \"native_theme_unittests\": \"c24a9673788c51c06c442b5bcb0775b9e3bf735a\", \"net_unittests\": \"bfaa9c11edab87c58ea49ed2eb2f2e926db774ce\", \"pdf_unittests\": \"9e11e04c912a6c0aa3bb13db752a90f0d53ec533\", \"perfetto_unittests\": \"42ccabfcfcf2536a007a0306d066f21fde8da1d3\", \"ppapi_unittests\": \"ccb8ab145289697ce63e18dc1f634372ba240021\", \"printing_unittests\": \"2aa0549f3dded7975bf245cf089712bbeb4d3022\", \"remoting_unittests\": \"c0cffb31f0a385fb943dbed52f37f5175dfd5d17\", \"sandbox_linux_unittests\": \"35746514834cdd7817c052ee0ea76f38bab5dc7c\", \"service_manager_unittests\": \"afe439d2b5fddb72358ebb22c1d7819347b42e6a\", \"services_unittests\": \"0b58301ccea0f4725a4c3a5a80425ed8bd3d1218\", \"shell_dialogs_unittests\": \"2209c6d403705f29eb38fe0a270b6165434a69f7\", \"skia_unittests\": \"045b8b1c168718bd3ce9e19fd2e361739ac860da\", \"snapshot_unittests\": \"be58555190d9520bc3909aa1ff950a65ffe9a5a2\", \"sql_unittests\": \"86a2098fd33f1b07bfa9301e9159318bc1fe21ff\", \"storage_unittests\": \"ba48f585219fabb95211fcbd5e90df7e453df0ec\", \"sync_integration_tests\": \"2de721205cb6098e970e5a804ced8f2f63bf9425\", \"telemetry_gpu_unittests\": \"72ec4705043fc8de1f551b0c8d1df5630100c714\", \"telemetry_perf_unittests\": \"3fb53ec17caf6330079c44051be20b3d63ba521b\", \"telemetry_unittests\": \"5d1759287c23897ed6b31a3663e419a7c5c15de9\", \"traffic_annotation_auditor_unittests\": \"2c336cd184d8c29c92eac23bd51d95d315b0fdf0\", \"ui_base_unittests\": \"1a18458ad5c98c4d030b1794729800df23b703fc\", \"ui_touch_selection_unittests\": \"aad7fc5b0f612237e9a3930d2d8d1996505742e1\", \"unit_tests\": \"830ea6438bb9588b2dfa560888426f227eda83b5\", \"url_unittests\": \"636fc44f1efd8fa84b88c5dda090a1d23f3e0b4e\", \"views_perftests\": \"d829c0ae12eeb4757b4b95224196b9340805a40e\", \"views_unittests\": \"5b8b0069b6a2265d00d6b43a25f84b25d192b47b\", \"viz_unittests\": \"91db555662cc0ec083dd226b3431400204f513fc\", \"vr_common_unittests\": \"28563b9d80c31ac75d7dbce39b8965de06f6e4de\", \"vr_pixeltests\": \"72abd82811b95c07a6841c69e2a267a71b5c5e59\", \"webdriver_wpt_tests\": \"1a38d4000959452df80a5111bef1aa988e0806e9\", \"weblayer_browsertests\": \"dfff35165219f32af48c4713fad2e91d21c5005c\", \"wm_unittests\": \"6d950d8b8ce120124ef8a7851f0af91a15895e6b\", \"wtf_unittests\": \"f12d9a3dc4df0f95ad895fde80b76c92fe9c2594\", \"xr_browser_tests\": \"d03dfc3a359befc8fcfa5ad1caea7809f714033d\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_2dd6a1af21400b3785138e4c8a94108d5525f829439b094d94efcf967177507f_v2\", \"builder_361cbaa79261c75a92fc63a8f8c6fc117764238b3277286fa51086e6da3301df_v2\", \"builder_3d74f5cea4cbf91e4b26f92a522c5c74e61b738440ef29ba16f5c9a596a5b201_v2\", \"builder_5018db700e530f8e7ae860b5b3feab2b02d3dedf1bbd3e704c6c59fde1b308dd_v2\", \"builder_562e40d9a0016a631bf2d4b73416a93abb069b959f020aa1a03e04f54d5ec620_v2\", \"builder_93045653dd8238f9b5a16956c3abf0c7402036195ded7f89ac6ed546092c13e3_v2\", \"builder_9ccb21220795a1d1972030c6b5d9b4569472a28790e1458f6676edb8e4495e8f_v2\", \"builder_d0ba3cd99c36b697d8651140d51bf4b4f4b9cd9aa25af11363af5ede5f6e35b1_v2\", \"builder_fa0031875b7f2f11c46443055ab400bfaeb07a1d2a142b58c631314016c04e0a_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-east1-d-8-49-sawd\"], \"image\": [\"chrome-xenial-20030100-2bddf837f90\"], \"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\": [\"4851-268d16b\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1583433003967024",
"status": "COMPLETED",
"status_changed_ts": "1583436397059319",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/169378",
"builder:Linux Builder (dbg)",
"buildset:commit/git/6a23f301de451d08405b0b12df83ab0a709b78c0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6a23f301de451d08405b0b12df83ab0a709b78c0",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9052074227994708016",
"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/8886644165302800976/+/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:4ac4c373b3299410",
"user_agent:luci-scheduler"
],
"updated_ts": "1583436397067241",
"url": "https://ci.chromium.org/b/8886644165302800976",
"utcnow_ts": "1583436397419337"
}
}
[19:26:37.426] Invocation finished in 1h1m16.660747046s with status SUCCEEDED