[22:40:01.039] New invocation is queued and will start shortly
[22:40:02.497] Starting the invocation (attempt 1)
[22:40:02.604] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:40:02.604] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:40:02.604] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9059305962803917280",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"0a94c81c38af1968a774cb54c79225b93b5a3bbf\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9059305962803917280",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/0a94c81c38af1968a774cb54c79225b93b5a3bbf",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0a94c81c38af1968a774cb54c79225b93b5a3bbf",
"gitiles_ref:refs/heads/master"
]
}
[22:40:04.125] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1576536003338977",
"id": "8893875899721876960",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0a94c81c38af1968a774cb54c79225b93b5a3bbf\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1576536003876276",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/166179",
"builder:Linux Builder (dbg)",
"buildset:commit/git/0a94c81c38af1968a774cb54c79225b93b5a3bbf",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0a94c81c38af1968a774cb54c79225b93b5a3bbf",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9059305962803917280",
"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/8893875899721876960/+/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": "1576536003876403",
"url": "https://ci.chromium.org/b/8893875899721876960",
"utcnow_ts": "1576536004058101"
}
}
[22:40:04.125] Task URL: https://ci.chromium.org/b/8893875899721876960
[22:40:04.125] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:2:0) after 1m0s
[22:41:04.143] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:2:0)
[22:41:04.143] Timer tick, asking Buildbucket for the build status
[22:41:04.949] Build 8893875899721876960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:41:04.949] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:3:0) after 1m0s
[22:42:05.022] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:3:0)
[22:42:05.022] Timer tick, asking Buildbucket for the build status
[22:42:05.286] Build 8893875899721876960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:42:05.286] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:4:0) after 1m0s
[22:43:05.347] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:4:0)
[22:43:05.347] Timer tick, asking Buildbucket for the build status
[22:43:05.762] Build 8893875899721876960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:43:05.762] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:5:0) after 1m0s
[22:44:06.282] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:5:0)
[22:44:06.282] Timer tick, asking Buildbucket for the build status
[22:44:06.990] Build 8893875899721876960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:44:06.990] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:6:0) after 1m0s
[22:45:02.916] Received PubSub notification, asking Buildbucket for the build status
[22:45:03.067] Build 8893875899721876960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:07.096] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:6:0)
[22:45:07.096] Timer tick, asking Buildbucket for the build status
[22:45:08.002] Build 8893875899721876960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:08.002] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:8:0) after 1m0s
[22:46:08.105] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:8:0)
[22:46:08.105] Timer tick, asking Buildbucket for the build status
[22:46:08.322] Build 8893875899721876960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:08.322] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:9:0) after 1m0s
[22:47:08.358] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:9:0)
[22:47:08.358] Timer tick, asking Buildbucket for the build status
[22:47:09.014] Build 8893875899721876960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:09.014] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:10:0) after 1m0s
[22:48:09.104] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:10:0)
[22:48:09.104] Timer tick, asking Buildbucket for the build status
[22:48:09.754] Build 8893875899721876960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:09.754] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:11:0) after 1m0s
[22:49:09.835] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:11:0)
[22:49:09.835] Timer tick, asking Buildbucket for the build status
[22:49:10.276] Build 8893875899721876960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:10.276] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:12:0) after 1m0s
[22:50:10.298] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:12:0)
[22:50:10.298] Timer tick, asking Buildbucket for the build status
[22:50:12.681] Build 8893875899721876960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:12.682] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:13:0) after 1m0s
[22:51:12.848] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:13:0)
[22:51:12.848] Timer tick, asking Buildbucket for the build status
[22:51:13.067] Build 8893875899721876960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:13.067] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:14:0) after 1m0s
[22:52:13.128] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:14:0)
[22:52:13.128] Timer tick, asking Buildbucket for the build status
[22:52:13.778] Build 8893875899721876960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:13.778] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:15:0) after 1m0s
[22:53:13.933] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:15:0)
[22:53:13.933] Timer tick, asking Buildbucket for the build status
[22:53:14.693] Build 8893875899721876960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:14.693] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:16:0) after 1m0s
[22:54:14.739] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:16:0)
[22:54:14.739] Timer tick, asking Buildbucket for the build status
[22:54:15.375] Build 8893875899721876960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:15.375] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:17:0) after 1m0s
[22:55:15.384] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:17:0)
[22:55:15.384] Timer tick, asking Buildbucket for the build status
[22:55:15.674] Build 8893875899721876960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:15.674] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:18:0) after 1m0s
[22:56:15.838] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:18:0)
[22:56:15.838] Timer tick, asking Buildbucket for the build status
[22:56:16.648] Build 8893875899721876960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:16.649] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:19:0) after 1m0s
[22:57:16.683] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:19:0)
[22:57:16.683] Timer tick, asking Buildbucket for the build status
[22:57:16.874] Build 8893875899721876960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:16.874] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:20:0) after 1m0s
[22:58:16.954] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:20:0)
[22:58:16.954] Timer tick, asking Buildbucket for the build status
[22:58:17.671] Build 8893875899721876960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:17.671] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:21:0) after 1m0s
[22:59:17.723] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:21:0)
[22:59:17.724] Timer tick, asking Buildbucket for the build status
[22:59:18.044] Build 8893875899721876960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:18.044] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:22:0) after 1m0s
[23:00:18.177] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:22:0)
[23:00:18.177] Timer tick, asking Buildbucket for the build status
[23:00:18.538] Build 8893875899721876960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:18.538] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:23:0) after 1m0s
[23:01:18.739] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:23:0)
[23:01:18.739] Timer tick, asking Buildbucket for the build status
[23:01:19.168] Build 8893875899721876960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:19.168] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:24:0) after 1m0s
[23:02:19.342] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:24:0)
[23:02:19.342] Timer tick, asking Buildbucket for the build status
[23:02:19.774] Build 8893875899721876960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:19.774] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:25:0) after 1m0s
[23:03:19.859] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:25:0)
[23:03:19.859] Timer tick, asking Buildbucket for the build status
[23:03:20.905] Build 8893875899721876960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:20.905] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:26:0) after 1m0s
[23:04:21.043] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:26:0)
[23:04:21.043] Timer tick, asking Buildbucket for the build status
[23:04:21.236] Build 8893875899721876960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:21.236] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:27:0) after 1m0s
[23:05:21.291] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:27:0)
[23:05:21.291] Timer tick, asking Buildbucket for the build status
[23:05:21.464] Build 8893875899721876960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:21.464] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:28:0) after 1m0s
[23:06:21.470] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:28:0)
[23:06:21.470] Timer tick, asking Buildbucket for the build status
[23:06:21.918] Build 8893875899721876960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:21.918] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:29:0) after 1m0s
[23:07:21.945] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:29:0)
[23:07:21.945] Timer tick, asking Buildbucket for the build status
[23:07:22.100] Build 8893875899721876960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:22.100] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:30:0) after 1m0s
[23:08:22.326] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:30:0)
[23:08:22.326] Timer tick, asking Buildbucket for the build status
[23:08:23.495] Build 8893875899721876960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:23.495] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:31:0) after 1m0s
[23:09:23.589] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:31:0)
[23:09:23.589] Timer tick, asking Buildbucket for the build status
[23:09:24.406] Build 8893875899721876960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:24.406] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:32:0) after 1m0s
[23:10:24.618] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:32:0)
[23:10:24.618] Timer tick, asking Buildbucket for the build status
[23:10:24.909] Build 8893875899721876960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:24.909] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:33:0) after 1m0s
[23:11:24.988] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:33:0)
[23:11:24.988] Timer tick, asking Buildbucket for the build status
[23:11:25.545] Build 8893875899721876960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:25.545] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:34:0) after 1m0s
[23:12:25.582] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:34:0)
[23:12:25.582] Timer tick, asking Buildbucket for the build status
[23:12:25.850] Build 8893875899721876960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:25.850] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:35:0) after 1m0s
[23:13:25.892] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:35:0)
[23:13:25.892] Timer tick, asking Buildbucket for the build status
[23:13:26.097] Build 8893875899721876960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:26.097] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:36:0) after 1m0s
[23:14:26.237] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:36:0)
[23:14:26.237] Timer tick, asking Buildbucket for the build status
[23:14:26.459] Build 8893875899721876960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:26.459] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:37:0) after 1m0s
[23:15:26.459] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:37:0)
[23:15:26.459] Timer tick, asking Buildbucket for the build status
[23:15:26.824] Build 8893875899721876960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:26.825] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:38:0) after 1m0s
[23:16:26.928] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:38:0)
[23:16:26.928] Timer tick, asking Buildbucket for the build status
[23:16:27.117] Build 8893875899721876960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:27.117] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:39:0) after 1m0s
[23:17:27.104] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:39:0)
[23:17:27.104] Timer tick, asking Buildbucket for the build status
[23:17:27.693] Build 8893875899721876960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:27.693] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:40:0) after 1m0s
[23:18:27.762] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:40:0)
[23:18:27.762] Timer tick, asking Buildbucket for the build status
[23:18:28.168] Build 8893875899721876960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:28.168] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:41:0) after 1m0s
[23:19:28.670] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:41:0)
[23:19:28.670] Timer tick, asking Buildbucket for the build status
[23:19:28.896] Build 8893875899721876960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:28.896] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:42:0) after 1m0s
[23:20:29.237] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:42:0)
[23:20:29.237] Timer tick, asking Buildbucket for the build status
[23:20:30.032] Build 8893875899721876960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:30.032] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:43:0) after 1m0s
[23:21:30.282] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:43:0)
[23:21:30.282] Timer tick, asking Buildbucket for the build status
[23:21:30.827] Build 8893875899721876960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:30.827] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:44:0) after 1m0s
[23:22:30.941] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:44:0)
[23:22:30.941] Timer tick, asking Buildbucket for the build status
[23:22:31.638] Build 8893875899721876960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:31.638] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:45:0) after 1m0s
[23:23:31.708] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:45:0)
[23:23:31.708] Timer tick, asking Buildbucket for the build status
[23:23:31.932] Build 8893875899721876960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:31.932] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:46:0) after 1m0s
[23:24:31.957] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:46:0)
[23:24:31.957] Timer tick, asking Buildbucket for the build status
[23:24:32.227] Build 8893875899721876960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:32.227] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:47:0) after 1m0s
[23:25:32.283] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:47:0)
[23:25:32.283] Timer tick, asking Buildbucket for the build status
[23:25:32.725] Build 8893875899721876960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:32.725] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:48:0) after 1m0s
[23:26:32.792] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:48:0)
[23:26:32.792] Timer tick, asking Buildbucket for the build status
[23:26:34.773] Build 8893875899721876960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:34.773] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:49:0) after 1m0s
[23:27:34.831] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:49:0)
[23:27:34.831] Timer tick, asking Buildbucket for the build status
[23:27:35.730] Build 8893875899721876960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:35.730] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:50:0) after 1m0s
[23:28:35.867] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:50:0)
[23:28:35.867] Timer tick, asking Buildbucket for the build status
[23:28:36.288] Build 8893875899721876960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:36.288] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:51:0) after 1m0s
[23:29:36.352] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:51:0)
[23:29:36.352] Timer tick, asking Buildbucket for the build status
[23:29:36.868] Build 8893875899721876960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:36.868] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:52:0) after 1m0s
[23:30:36.966] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:52:0)
[23:30:36.966] Timer tick, asking Buildbucket for the build status
[23:30:37.353] Build 8893875899721876960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:37.353] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:53:0) after 1m0s
[23:31:37.462] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:53:0)
[23:31:37.462] Timer tick, asking Buildbucket for the build status
[23:31:38.144] Build 8893875899721876960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:38.144] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:54:0) after 1m0s
[23:32:38.222] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:54:0)
[23:32:38.222] Timer tick, asking Buildbucket for the build status
[23:32:38.730] Build 8893875899721876960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:38.730] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:55:0) after 1m0s
[23:33:38.869] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:55:0)
[23:33:38.869] Timer tick, asking Buildbucket for the build status
[23:33:39.458] Build 8893875899721876960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:39.458] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:56:0) after 1m0s
[23:34:39.558] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:56:0)
[23:34:39.558] Timer tick, asking Buildbucket for the build status
[23:34:39.963] Build 8893875899721876960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:39.963] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:57:0) after 1m0s
[23:35:40.028] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:57:0)
[23:35:40.028] Timer tick, asking Buildbucket for the build status
[23:35:40.245] Build 8893875899721876960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:40.245] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:58:0) after 1m0s
[23:36:40.280] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:58:0)
[23:36:40.280] Timer tick, asking Buildbucket for the build status
[23:36:40.973] Build 8893875899721876960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:40.973] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059305962803917280:59:0) after 1m0s
[23:37:21.126] Received PubSub notification, asking Buildbucket for the build status
[23:37:21.257] Build 8893875899721876960: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[23:37:21.257] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1576539440368351",
"created_by": "project:chromium",
"created_ts": "1576536003338977",
"id": "8893875899721876960",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0a94c81c38af1968a774cb54c79225b93b5a3bbf\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"jobs\": 500.0, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-east1-d-8-30-915y\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 166179, \"got_angle_revision\": \"7587588595901010a7b8d8683d06796ae420edb8\", \"got_dawn_revision\": \"1bbbe8f52da580bd245af733e068eaabacf72731\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"0a94c81c38af1968a774cb54c79225b93b5a3bbf\", \"got_revision_cp\": \"refs/heads/master@{#725300}\", \"got_swarming_client_revision\": \"885b3febcc170a60f25795304e60927b77d1e92d\", \"got_swiftshader_revision\": \"fbbfeb700295d1fe1ec6419a93594e5e1540ea32\", \"got_v8_revision\": \"72bfdb17a811bc323e02a79b27619913ac3101d2\", \"got_v8_revision_cp\": \"refs/heads/8.1.42@{#1}\", \"got_webrtc_revision\": \"3a8df884d1691260c93da86ce864f8c890e9d155\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30103}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0a94c81c38af1968a774cb54c79225b93b5a3bbf\", \"swarm_hashes_refs/heads/master(at){#725300}_without_patch\": {\"accessibility_unittests\": \"af7b9dcbfadb6ac9f80d1523e88c534ad6510ae9\", \"angle_unittests\": \"e3b8175fa7ee01999e3a112b907715d1be517836\", \"app_shell_unittests\": \"c8a3af0cfed0726cfc2145e6a066d77a91e6c1be\", \"aura_unittests\": \"aa44f6d40f9aab2aecf6f86b8f704045e11c6d73\", \"base_unittests\": \"db1be9fa87c0bfa66d0bb280bab97efed82e4a9d\", \"base_util_unittests\": \"e6791946406af3786da4fa1d664c6956568b5abf\", \"blink_common_unittests\": \"01e7a16a30712320ab200ebdd80faae141dfa40d\", \"blink_fuzzer_unittests\": \"b16c76618d91a44b3c5c3637242665283613b22c\", \"blink_heap_unittests\": \"9170602f1c35b658d44d53e8c643cbef6ac54660\", \"blink_platform_unittests\": \"4a5e96770a1d394ca5e92e4511065a0929100415\", \"blink_python_tests\": \"3cb03f947ffc84b43fb58029bf55313d7dda34ec\", \"blink_unittests\": \"cce323840a69570ad55064525e54d33e413c1454\", \"blink_web_tests\": \"9a6d2e68611af9c1bf117cc09f79429f8389954b\", \"boringssl_crypto_tests\": \"7ad4ff2b1ab298d589868d14515505508eadda8c\", \"boringssl_ssl_tests\": \"f7adf21b51bb3cf953cbc88385f8a40d709b6e72\", \"browser_tests\": \"77c45f21f15db6e5a5be7e73293da7e9516006f0\", \"cacheinvalidation_unittests\": \"676e8246f75c7836508646a8fe8835fc358435b7\", \"capture_unittests\": \"c4de1f65615f54367235866424386697fbcd4c2f\", \"cast_unittests\": \"b8f146b188f1b5d90eed64d7ce69bb91f1182553\", \"cc_unittests\": \"23e8821e663fb3e65e4308137ceef7c575b4c77a\", \"chrome_app_unittests\": \"ec8d55c9ebe2b0707fd25f7e95cdcbb95a293577\", \"chromedriver_unittests\": \"bf0f8a56c8f1a41ce5b326c24f3e6c28505c532f\", \"components_browsertests\": \"56c756072f35b2083c5ddd9d7190f797fdde6b7d\", \"components_unittests\": \"76d8b83c9949c51913ef71f8fee7d3b52df8aea4\", \"compositor_unittests\": \"5e69779346c0082c280bb0c947a83bbfdb5bca78\", \"content_browsertests\": \"56822aa52b75487f303e017e666055908bdee44e\", \"content_shell_crash_test\": \"000a219fb2a4f47eb2f6888a697affaa637224a8\", \"content_unittests\": \"520b423afbc99702b5329213af49b0e09744769e\", \"crashpad_tests\": \"6aa2f32656466fa75a44047e35d0927b34f79e24\", \"cronet_tests\": \"c6a71831457384e6e78837fe65160818c7937801\", \"cronet_unittests\": \"c83775b8c1cfbeb66265e485e42e1763a9984455\", \"crypto_unittests\": \"ab6f7b66e0ae026bd4136a2c235a4e512cd858a2\", \"dbus_unittests\": \"d34a07af75ee8b5a31efa0444f5f506d82e57b9a\", \"device_unittests\": \"708f72ba7ff967b9e2eb354fc6f04fe6c95d8212\", \"devtools_lint_check\": \"e89cdebf802c0af0e411b0a4ddf755d863dae4d7\", \"devtools_type_check\": \"5c636320feb36915018f7e89bbd583cbbdb55b0b\", \"display_unittests\": \"59c640407b7fa5af6c0063484c4eb9c148ab6ba3\", \"events_unittests\": \"4238d3e240a6292e2f474c8bd820ef57a9037726\", \"extensions_browsertests\": \"9404378b5b86db044cbb9d5d24bbfa6c56ec3f6f\", \"extensions_unittests\": \"bca1c82d49a37fe269b24944a626a9aadff2b4e0\", \"filesystem_service_unittests\": \"36306f3626b7741d1f32aeeb51de95068d3ad9be\", \"flatbuffers_unittests\": \"034d1c63a8199a680c9bbf29b5866b1c851e28ee\", \"gcm_unit_tests\": \"ae44c90419d21c1dd2e528d87defd9641674355f\", \"gfx_unittests\": \"097a82a96f0a9ba32c7dab9cf5fbfb844b454adc\", \"gin_unittests\": \"4fc5f5396a484faf3c66a34404d7c5c4a5faf8d0\", \"google_apis_unittests\": \"9ebe6484ee6631db19c0be0492183a7dd463b6fa\", \"gpu_unittests\": \"2f2c1546faad6ac1c684fedda5e2da14909bcdbe\", \"grit_python_unittests\": \"25dff9ef6327c596609d376409bc1d0e605b6d43\", \"gwp_asan_unittests\": \"e824ad38cfa20428e0beda6803f3976529a0e636\", \"headless_browsertests\": \"8deebf38ecd8d038c39c7f8ddae237513bc52496\", \"headless_unittests\": \"73788d724a9ee954f2352bf464c306a8f64c2824\", \"interactive_ui_tests\": \"3b9c9fe9ef572318d7bcff185e58cb91f25a7a9b\", \"ipc_tests\": \"904b64b17fe77583faece3f2cdba492d56e7e64d\", \"jingle_unittests\": \"eb051ce396d299fccc6d68fa6b5f9c91683b19d0\", \"latency_unittests\": \"cdbd8980c3fd749dceb9ffce2182a2d2fb79100f\", \"libjingle_xmpp_unittests\": \"021c27b1127534043650acb05af7dca18f120258\", \"media_blink_unittests\": \"2b797088f83be83b4c316b5a4040a5d6c052ac22\", \"media_service_unittests\": \"4616de8e96b518b1a3edf41c458d495854d078d0\", \"media_unittests\": \"6052c09e24ea6abadf98438b6d61af7d2e6ceaaf\", \"message_center_unittests\": \"500e0f1fdad4c7fcae0655d54249071e63b947d3\", \"metrics_python_tests\": \"80cdfb749a50ee7ba232c6f22b154aa62552a7d3\", \"midi_unittests\": \"18f23bbad9ba4d6e12a4044671878ef8e7006b4b\", \"mojo_core_unittests\": \"0983e458f7e49dd917c13247d1d308bbb220a39d\", \"mojo_unittests\": \"d96b837445a7106c10efc30ccf2deee56e077e54\", \"nacl_helper_nonsfi_unittests\": \"62207257d42997a80c7f061d100e94b4a47d492b\", \"nacl_loader_unittests\": \"8b9c692048acc8813e5206056287b7b32014b17f\", \"native_theme_unittests\": \"6c64396415fb2054c91ffa35f8bfeaa89cdf306c\", \"net_unittests\": \"7440c462752111dee9462a366444a4dde0f3e9ed\", \"pdf_unittests\": \"b7c1d990d0909f3e2dee730c60305c38ceb6d975\", \"perfetto_unittests\": \"382690dd912e70e89a4c08f89401ab55ae85d132\", \"ppapi_unittests\": \"ef54b58814d99d3be3f6d00ebe03e316a440add2\", \"printing_unittests\": \"b0a4d2272e729cf3fdd859a93aa79af9cb19a712\", \"remoting_unittests\": \"9e90d297b74e1910d127606fb4aa5e676fa81d59\", \"sandbox_linux_unittests\": \"e6d9bdc4cf7dbe88fb0ce468331b9dcd501460f9\", \"service_manager_unittests\": \"6323cc914e901a8eee7d2c44cf733fcbfdbd6529\", \"services_unittests\": \"7b485350ef29a5fba04b8507dc48e800951bd6e1\", \"shell_dialogs_unittests\": \"1aa811b19ef0085aff5b6dbdbe263142482b2dbe\", \"skia_unittests\": \"ee337f316bee3ad4137132770e6c042c462ffa4c\", \"snapshot_unittests\": \"10e30f9972ebe775294ebe2193dee19f87325a8c\", \"sql_unittests\": \"7260301d555d3ace99ada04cc8d3dd70fd66b0e7\", \"storage_unittests\": \"46a06763a819041b762e60b7b7bc41da8b0f9961\", \"sync_integration_tests\": \"dcb8870631b4982941ed510600c4e427f4944f01\", \"telemetry_gpu_unittests\": \"777aecccc4ff5e4d87652ffee826b4fcacef4092\", \"telemetry_perf_unittests\": \"b15494c582ffb774befc50ad7249a2122c6abc9e\", \"telemetry_unittests\": \"426113d7994ea63eafb96c5239cf78096ceacab2\", \"traffic_annotation_auditor_unittests\": \"01d6ec4979c78cade170bc348813f7f9e4d582a7\", \"ui_base_unittests\": \"78d794e2631f76dcf1fd66f8dc0b126eb5667a74\", \"ui_touch_selection_unittests\": \"65316d9fb564c2cf0af6d8ead809aa34bac1b7b0\", \"unit_tests\": \"87da8145f3553931f7bc8bdaf1402e7894a8a822\", \"url_unittests\": \"138df635b9687f2cf949970fba25d1c661596d8f\", \"views_perftests\": \"f4ebc39265d2b7730ece11c4b47f2e676576179f\", \"views_unittests\": \"774b1ba4d2be76d5649083c335c197b00a5cca14\", \"viz_unittests\": \"2cb29a041745e6f354d29295193059235912566d\", \"vr_common_unittests\": \"8866a84d1850f402bbf3f3596b1203c66b64d0ea\", \"vr_pixeltests\": \"544c57fbeb45d77d47d1ffd093f8fcea5e5d2d74\", \"webdriver_wpt_tests\": \"9e761665b9a26dde4fecaf8bef9c42860a5641da\", \"weblayer_browsertests\": \"9a4cba13a74a8de7ee4c66b9e87e082234f088f1\", \"wm_unittests\": \"b8b0f71256b4629cbd3ddbc2f81f0e63faae7065\", \"wtf_unittests\": \"7d82503b65289a1dec83bd3f51131e14788ada36\", \"xr_browser_tests\": \"cf57c2f1d80d6b7b4634a8aff440cc6ab45a1279\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_1989594bdf03467d4cca251563547d36d290a477ee0cdac91b5f17239ddd8c8b_v2\", \"builder_5031bc3d586fdedb2b02f62dae64b9ebfd5c405012b7a28b41092bb1d66cfccb_v2\", \"builder_71ab4550ecc1db180544ee9bd5ebac58222d39ccc6e59d756fcde46903b3067c_v2\", \"builder_9a2f1f8988f1f66ad7e537b613a307edd4200b924c9c36d38f851454b2c5979c_v2\", \"builder_abe2adbd32ad911c3e8d1f384e915329cf1f65e427edb6b91a9fbb61a55ed743_v2\", \"builder_bb898022d2469672cf25def24fe6674f04bc82432723cbfd6a0db18494567798_v2\", \"builder_f5b4454e99a04aaa5ba743b59cc70de19e20bebedef65e50e3b90534a8d6a5e4_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-east1-d-8-30-915y\"], \"image\": [\"chrome-xenial-19120800-09d8782d9a1\"], \"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\": [\"4730-f2fc813\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1576536301847548",
"status": "COMPLETED",
"status_changed_ts": "1576539440821888",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/166179",
"builder:Linux Builder (dbg)",
"buildset:commit/git/0a94c81c38af1968a774cb54c79225b93b5a3bbf",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0a94c81c38af1968a774cb54c79225b93b5a3bbf",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9059305962803917280",
"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/8893875899721876960/+/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:4929b0022a0e7410",
"user_agent:luci-scheduler"
],
"updated_ts": "1576539440837012",
"url": "https://ci.chromium.org/b/8893875899721876960",
"utcnow_ts": "1576539441247434"
}
}
[23:37:21.258] Invocation finished in 57m20.242954646s with status SUCCEEDED