[01:22:16.442] New invocation is queued and will start shortly
[01:22:17.596] Starting the invocation (attempt 1)
[01:22:17.629] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:22:17.629] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:22:17.629] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9055309487966712176",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"3475028d4c03238d8be9a7fbe1c6be56e14aba53\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9055309487966712176",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/3475028d4c03238d8be9a7fbe1c6be56e14aba53",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3475028d4c03238d8be9a7fbe1c6be56e14aba53",
"gitiles_ref:refs/heads/master"
]
}
[01:22:19.442] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1580347338249954",
"id": "8889879425406445040",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3475028d4c03238d8be9a7fbe1c6be56e14aba53\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1580347339013724",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168008",
"builder:Linux Builder (dbg)",
"buildset:commit/git/3475028d4c03238d8be9a7fbe1c6be56e14aba53",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3475028d4c03238d8be9a7fbe1c6be56e14aba53",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9055309487966712176",
"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/8889879425406445040/+/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": "1580347339013994",
"url": "https://ci.chromium.org/b/8889879425406445040",
"utcnow_ts": "1580347339426980"
}
}
[01:22:19.442] Task URL: https://ci.chromium.org/b/8889879425406445040
[01:22:19.442] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:2:0) after 1m0s
[01:23:19.441] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:2:0)
[01:23:19.441] Timer tick, asking Buildbucket for the build status
[01:23:20.163] Build 8889879425406445040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:23:20.163] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:3:0) after 1m0s
[01:24:20.196] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:3:0)
[01:24:20.196] Timer tick, asking Buildbucket for the build status
[01:24:21.128] Build 8889879425406445040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:24:21.128] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:4:0) after 1m0s
[01:25:21.231] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:4:0)
[01:25:21.231] Timer tick, asking Buildbucket for the build status
[01:25:21.419] Build 8889879425406445040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:25:21.419] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:5:0) after 1m0s
[01:26:21.534] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:5:0)
[01:26:21.534] Timer tick, asking Buildbucket for the build status
[01:26:21.769] Build 8889879425406445040: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:26:21.769] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:6:0) after 1m0s
[01:27:05.514] Received PubSub notification, asking Buildbucket for the build status
[01:27:05.708] Build 8889879425406445040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:21.843] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:6:0)
[01:27:21.843] Timer tick, asking Buildbucket for the build status
[01:27:22.047] Build 8889879425406445040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:22.047] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:8:0) after 1m0s
[01:28:22.093] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:8:0)
[01:28:22.093] Timer tick, asking Buildbucket for the build status
[01:28:22.291] Build 8889879425406445040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:22.291] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:9:0) after 1m0s
[01:29:22.339] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:9:0)
[01:29:22.339] Timer tick, asking Buildbucket for the build status
[01:29:22.521] Build 8889879425406445040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:22.521] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:10:0) after 1m0s
[01:30:22.613] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:10:0)
[01:30:22.613] Timer tick, asking Buildbucket for the build status
[01:30:23.226] Build 8889879425406445040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:23.226] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:11:0) after 1m0s
[01:31:23.289] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:11:0)
[01:31:23.289] Timer tick, asking Buildbucket for the build status
[01:31:23.508] Build 8889879425406445040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:23.508] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:12:0) after 1m0s
[01:32:23.560] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:12:0)
[01:32:23.560] Timer tick, asking Buildbucket for the build status
[01:32:24.245] Build 8889879425406445040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:24.245] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:13:0) after 1m0s
[01:33:24.378] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:13:0)
[01:33:24.378] Timer tick, asking Buildbucket for the build status
[01:33:24.636] Build 8889879425406445040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:24.636] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:14:0) after 1m0s
[01:34:24.624] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:14:0)
[01:34:24.624] Timer tick, asking Buildbucket for the build status
[01:34:25.132] Build 8889879425406445040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:25.132] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:15:0) after 1m0s
[01:35:25.115] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:15:0)
[01:35:25.115] Timer tick, asking Buildbucket for the build status
[01:35:25.305] Build 8889879425406445040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:25.305] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:16:0) after 1m0s
[01:36:25.342] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:16:0)
[01:36:25.342] Timer tick, asking Buildbucket for the build status
[01:36:25.521] Build 8889879425406445040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:25.521] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:17:0) after 1m0s
[01:37:25.557] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:17:0)
[01:37:25.557] Timer tick, asking Buildbucket for the build status
[01:37:26.395] Build 8889879425406445040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:26.395] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:18:0) after 1m0s
[01:38:26.513] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:18:0)
[01:38:26.513] Timer tick, asking Buildbucket for the build status
[01:38:27.295] Build 8889879425406445040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:27.295] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:19:0) after 1m0s
[01:39:27.455] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:19:0)
[01:39:27.455] Timer tick, asking Buildbucket for the build status
[01:39:27.906] Build 8889879425406445040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:27.906] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:20:0) after 1m0s
[01:40:27.993] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:20:0)
[01:40:27.993] Timer tick, asking Buildbucket for the build status
[01:40:28.213] Build 8889879425406445040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:28.213] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:21:0) after 1m0s
[01:41:28.300] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:21:0)
[01:41:28.300] Timer tick, asking Buildbucket for the build status
[01:41:29.237] Build 8889879425406445040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:29.237] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:22:0) after 1m0s
[01:42:29.264] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:22:0)
[01:42:29.264] Timer tick, asking Buildbucket for the build status
[01:42:29.787] Build 8889879425406445040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:29.788] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:23:0) after 1m0s
[01:43:29.813] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:23:0)
[01:43:29.813] Timer tick, asking Buildbucket for the build status
[01:43:30.725] Build 8889879425406445040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:30.725] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:24:0) after 1m0s
[01:44:30.825] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:24:0)
[01:44:30.825] Timer tick, asking Buildbucket for the build status
[01:44:31.325] Build 8889879425406445040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:31.325] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:25:0) after 1m0s
[01:45:31.351] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:25:0)
[01:45:31.351] Timer tick, asking Buildbucket for the build status
[01:45:31.558] Build 8889879425406445040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:31.558] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:26:0) after 1m0s
[01:46:31.583] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:26:0)
[01:46:31.583] Timer tick, asking Buildbucket for the build status
[01:46:31.773] Build 8889879425406445040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:31.773] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:27:0) after 1m0s
[01:47:31.794] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:27:0)
[01:47:31.794] Timer tick, asking Buildbucket for the build status
[01:47:32.143] Build 8889879425406445040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:32.143] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:28:0) after 1m0s
[01:48:32.149] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:28:0)
[01:48:32.149] Timer tick, asking Buildbucket for the build status
[01:48:32.932] Build 8889879425406445040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:32.932] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:29:0) after 1m0s
[01:49:32.953] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:29:0)
[01:49:32.953] Timer tick, asking Buildbucket for the build status
[01:49:33.419] Build 8889879425406445040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:33.419] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:30:0) after 1m0s
[01:50:33.472] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:30:0)
[01:50:33.472] Timer tick, asking Buildbucket for the build status
[01:50:34.996] Build 8889879425406445040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:34.996] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:31:0) after 1m0s
[01:51:35.111] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:31:0)
[01:51:35.111] Timer tick, asking Buildbucket for the build status
[01:51:35.418] Build 8889879425406445040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:35.418] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:32:0) after 1m0s
[01:52:35.448] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:32:0)
[01:52:35.448] Timer tick, asking Buildbucket for the build status
[01:52:35.703] Build 8889879425406445040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:35.703] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:33:0) after 1m0s
[01:53:35.748] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:33:0)
[01:53:35.748] Timer tick, asking Buildbucket for the build status
[01:53:36.433] Build 8889879425406445040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:36.433] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:34:0) after 1m0s
[01:54:36.464] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:34:0)
[01:54:36.464] Timer tick, asking Buildbucket for the build status
[01:54:37.014] Build 8889879425406445040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:37.014] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:35:0) after 1m0s
[01:55:37.128] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:35:0)
[01:55:37.128] Timer tick, asking Buildbucket for the build status
[01:55:37.274] Build 8889879425406445040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:37.274] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:36:0) after 1m0s
[01:56:37.362] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:36:0)
[01:56:37.362] Timer tick, asking Buildbucket for the build status
[01:56:37.714] Build 8889879425406445040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:37.714] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:37:0) after 1m0s
[01:57:38.019] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:37:0)
[01:57:38.019] Timer tick, asking Buildbucket for the build status
[01:57:38.392] Build 8889879425406445040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:38.392] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:38:0) after 1m0s
[01:58:38.477] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:38:0)
[01:58:38.477] Timer tick, asking Buildbucket for the build status
[01:58:38.702] Build 8889879425406445040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:38.702] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:39:0) after 1m0s
[01:59:38.841] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:39:0)
[01:59:38.841] Timer tick, asking Buildbucket for the build status
[01:59:39.135] Build 8889879425406445040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:39.135] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:40:0) after 1m0s
[02:00:39.281] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:40:0)
[02:00:39.281] Timer tick, asking Buildbucket for the build status
[02:00:39.413] Build 8889879425406445040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:39.413] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:41:0) after 1m0s
[02:01:39.459] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:41:0)
[02:01:39.459] Timer tick, asking Buildbucket for the build status
[02:01:39.768] Build 8889879425406445040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:39.768] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:42:0) after 1m0s
[02:02:39.825] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:42:0)
[02:02:39.825] Timer tick, asking Buildbucket for the build status
[02:02:40.084] Build 8889879425406445040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:40.084] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:43:0) after 1m0s
[02:03:40.186] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:43:0)
[02:03:40.186] Timer tick, asking Buildbucket for the build status
[02:03:40.346] Build 8889879425406445040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:40.346] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:44:0) after 1m0s
[02:04:40.485] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:44:0)
[02:04:40.485] Timer tick, asking Buildbucket for the build status
[02:04:40.659] Build 8889879425406445040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:40.659] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:45:0) after 1m0s
[02:05:40.752] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:45:0)
[02:05:40.752] Timer tick, asking Buildbucket for the build status
[02:05:41.142] Build 8889879425406445040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:41.142] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:46:0) after 1m0s
[02:06:41.351] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:46:0)
[02:06:41.351] Timer tick, asking Buildbucket for the build status
[02:06:41.639] Build 8889879425406445040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:41.639] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:47:0) after 1m0s
[02:07:41.736] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:47:0)
[02:07:41.736] Timer tick, asking Buildbucket for the build status
[02:07:42.522] Build 8889879425406445040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:42.522] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:48:0) after 1m0s
[02:08:42.656] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:48:0)
[02:08:42.656] Timer tick, asking Buildbucket for the build status
[02:08:43.088] Build 8889879425406445040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:43.088] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:49:0) after 1m0s
[02:09:43.275] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:49:0)
[02:09:43.275] Timer tick, asking Buildbucket for the build status
[02:09:43.886] Build 8889879425406445040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:43.886] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:50:0) after 1m0s
[02:10:43.947] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:50:0)
[02:10:43.947] Timer tick, asking Buildbucket for the build status
[02:10:44.491] Build 8889879425406445040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:44.491] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:51:0) after 1m0s
[02:11:44.672] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:51:0)
[02:11:44.672] Timer tick, asking Buildbucket for the build status
[02:11:44.972] Build 8889879425406445040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:44.972] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055309487966712176:52:0) after 1m0s
[02:12:13.633] Received PubSub notification, asking Buildbucket for the build status
[02:12:14.899] Build 8889879425406445040: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[02:12:14.899] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1580350332573533",
"created_by": "project:chromium",
"created_ts": "1580347338249954",
"id": "8889879425406445040",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3475028d4c03238d8be9a7fbe1c6be56e14aba53\"}}",
"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-52-z84e\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 168008, \"got_angle_revision\": \"053929aca61b088d9ebf814aa095f8fe874bfdb2\", \"got_dawn_revision\": \"7b6246a2ade815a032e1abc8df1be7c72d3dd0c2\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"3475028d4c03238d8be9a7fbe1c6be56e14aba53\", \"got_revision_cp\": \"refs/heads/master@{#736659}\", \"got_swarming_client_revision\": \"885b3febcc170a60f25795304e60927b77d1e92d\", \"got_swiftshader_revision\": \"6c3dc3581eaf4345c0507d5ac7bb013d55351547\", \"got_v8_revision\": \"a4dcd39d521d14c4b1cac020812e44ee04a7f244\", \"got_v8_revision_cp\": \"refs/heads/8.1.307@{#1}\", \"got_webrtc_revision\": \"1cf15bfe55a3b9c007e9810d13ec210628b64e86\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30416}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3475028d4c03238d8be9a7fbe1c6be56e14aba53\", \"swarm_hashes_refs/heads/master(at){#736659}_without_patch\": {\"accessibility_unittests\": \"0340b7d1209b663696ed76f472185095772e5a25\", \"angle_unittests\": \"adc844c7b8b5067bcb4285cfcc903d37a2d0aa7b\", \"app_shell_unittests\": \"dc5f352ec88e06a9f855f3dfdd880503d1d4bcf2\", \"aura_unittests\": \"252933a4f399bd8a422bc2603e29e56560377491\", \"base_unittests\": \"f6e9748aa8f0bd8ba87d23f57a2806c37da20135\", \"base_util_unittests\": \"3f5955b69c8c7e7aa382497c74b9910404a7fdf3\", \"blink_common_unittests\": \"8c8a6bf52e94c5b9d823dab6e391f1fbc485362e\", \"blink_fuzzer_unittests\": \"6217dee130f3feec9e9c277c97f147391ab9538a\", \"blink_heap_unittests\": \"bf9165a651443e7b9f4c86e01c48d5634e6303f2\", \"blink_platform_unittests\": \"cefb3e63e446c6641cd66d8c0a49fc5e37540afc\", \"blink_python_tests\": \"90b0abb6b43b0891a40506c1db4ffbdfd38ec639\", \"blink_unittests\": \"7c52b68d4f29e0505923f9774f74cd15fd0a502d\", \"blink_web_tests\": \"c4db9241f1b181b2776b7b0b9731d5c23531970e\", \"boringssl_crypto_tests\": \"0ece471e61d912e43e0bd5de7b10ea843135a55b\", \"boringssl_ssl_tests\": \"0225ecd07322440fb2fff4f71dff87912c5cf36d\", \"browser_tests\": \"dc2c07218b1b9dfb0a617d8a2e1a0daef98dffb6\", \"cacheinvalidation_unittests\": \"52a4a2a63ac8312e64804cb37476b5812ea7bffb\", \"capture_unittests\": \"6744a3bbba9ba5c3e966b221079e1dcf3cf29c9c\", \"cast_unittests\": \"6398725d3a7c2aa13d44cadc5fe83bb1cbba0573\", \"cc_unittests\": \"7e45008a9ad99681a9358ea49cc196a0e9888d5c\", \"chrome_app_unittests\": \"8ca483adcff7740dd9162bb226564adbdcaab8c2\", \"chromedriver_unittests\": \"f018db9ae3d4d20bea5bbaccd56818bea75ebdf2\", \"components_browsertests\": \"e5ab262b19e1731ca600c3d276cb4593da1b8ddb\", \"components_unittests\": \"1401f44a4ae2cbe87de7257a007361675e9c784e\", \"compositor_unittests\": \"134e77675e30b5f525f4323420af3799e629ea1b\", \"content_browsertests\": \"5ddad30ca22d5e5e469d7a315a7e0434935e5d52\", \"content_shell_crash_test\": \"ddaf1eef4e305b57fb25fe1a2f8f28bcc95d4039\", \"content_unittests\": \"105872554f10ac8d9322e390237baefb1571b182\", \"crashpad_tests\": \"1154c981bc9180b9ad833726aa4b8a76db282b64\", \"cronet_tests\": \"bbd6606386f2cb18e375862d19c6031da9b53fae\", \"cronet_unittests\": \"ae2df1a81e91de846c96fc951365af4b8f36a70f\", \"crypto_unittests\": \"2a2a53b1c73ad29608ca63971b14ca768432d62f\", \"dbus_unittests\": \"236aa3dc51383a1355fc4831b702a177145d439a\", \"device_unittests\": \"6d7561917c8c075c1e2d8a00d0f538ef637907fd\", \"display_unittests\": \"9d336607b7dd36fffe2545c9af982f43fa2a7b59\", \"events_unittests\": \"fb15dd2adfb060ed0294c703c0e5bb5e53e1bfcd\", \"extensions_browsertests\": \"5208f0d99dbde23112e4d8b3d3ced0cb2120e3d0\", \"extensions_unittests\": \"4a903423c1c2ce77e01f586bd5d7635d83412f6a\", \"filesystem_service_unittests\": \"59f0b504905a4bc15d6ff9f30a450b47ab700aea\", \"flatbuffers_unittests\": \"ca71348ce5934d53fb892115f8e6e8a2436d5e6c\", \"gcm_unit_tests\": \"c351e30f910d3b7a8c60109879e5785bd51929f9\", \"gfx_unittests\": \"1a6869e138be94de115d567209bf17897e2a1a95\", \"gin_unittests\": \"865bc49cb6b78cb0b951bb2c332daf7ba2b92488\", \"google_apis_unittests\": \"d47f9cbd140fe7620893c4cae7d071cefa4f412b\", \"gpu_unittests\": \"c50e226b7f59ea4699ee5d25d1dc19d9f7eae0c3\", \"grit_python_unittests\": \"896da2265579af982b0c06ff8662522b696fea99\", \"gwp_asan_unittests\": \"ae36e4b95fc86dd90f197f5f08311347f2b27339\", \"headless_browsertests\": \"1460b4db9b060741e92bdbcc741f680d072a75dc\", \"headless_unittests\": \"b2d73cca6f0a4cb8f7d917b51581372c9d5dc3d2\", \"interactive_ui_tests\": \"f7f3a79a38664ab8c9e0536c1a3bacad8b046262\", \"ipc_tests\": \"157f675042b47cc76e3f3dc3500d8a9e03843fae\", \"jingle_unittests\": \"c7904fc8cc63dab40362bd1a5f257b1a22c4bb68\", \"latency_unittests\": \"e43c5cf02c8cf040308018ed9547e2accb8d0c55\", \"libjingle_xmpp_unittests\": \"2e3c26ff4c6822b8bd048d46a8a63a4169412bca\", \"media_blink_unittests\": \"e103931282674a717cde8b1b366c28a3960ac2bb\", \"media_service_unittests\": \"ab67e2ddbd8b5566e49722a476958c88a37a0f87\", \"media_unittests\": \"1fba0f952da5cb7df955f7b735783492b81ed552\", \"message_center_unittests\": \"0597aa6922bc8d5eb62655b5c2acaad77e4222b3\", \"metrics_python_tests\": \"fd89d82db2094284e347843f790ff5e5025caca5\", \"midi_unittests\": \"349ef7c1258a462da6554ca9a442c8f775618d5a\", \"mojo_core_unittests\": \"77b1a8593569c32aeb80750eb5b4a185e78f46bf\", \"mojo_unittests\": \"3a55a565a6bf38feeeeeaa14a6ffd48435d96060\", \"nacl_helper_nonsfi_unittests\": \"65067849b46052aabf17e4487f02b2065fb449fc\", \"nacl_loader_unittests\": \"c8913ee2e06b1fd364692454552650f8bc2e3377\", \"native_theme_unittests\": \"7537369450e572097cb1addbded2d5c2cbec1244\", \"net_unittests\": \"39a3b2f56a4b3c12ef80544726072339fb5db6f5\", \"pdf_unittests\": \"3187dcc162acf5ac2d28e3060b265c959c62643a\", \"perfetto_unittests\": \"e1a6fc25f2c644b1aeb669bc9adb69a673bc6a6e\", \"ppapi_unittests\": \"9e1906839c332de96b1a5c1881f3eb394c652a1e\", \"printing_unittests\": \"d68a16b9aecf2c215b94bc399d90c8ee4e1c67b3\", \"remoting_unittests\": \"f0473cf45d344eaf5ea0b0b2430a21a4a4fa54f2\", \"sandbox_linux_unittests\": \"e26867c5d215fb042e24b3bee71cbeff721ee694\", \"service_manager_unittests\": \"e5d6a63ed35ccd0ea5def9a10ac839eb031d4d03\", \"services_unittests\": \"fd3b4f4286b5538a32c23dd3d56e23ca1915a79b\", \"shell_dialogs_unittests\": \"fee7182734ad041f097c686866ba7e5a90d63723\", \"skia_unittests\": \"7c4a6237d17f4a728b55fa2b4ee1c158f2144983\", \"snapshot_unittests\": \"0061623b32b2c1436f5c770d7860d98605682b2f\", \"sql_unittests\": \"c1dbab682ab7118de197fc9fe70a1369ef69d695\", \"storage_unittests\": \"6aed7203ce36735f041b39c48ace3c21b0f330bb\", \"sync_integration_tests\": \"f1e1d4e7be368afa09ce74de77b2c69f44045557\", \"telemetry_gpu_unittests\": \"398e9d07226e8e5d906e305224df3ccfaaf5e234\", \"telemetry_perf_unittests\": \"b9fc85af6ba1fbeb5f2eea04baaa8b261a35dbf1\", \"telemetry_unittests\": \"b6f348826e926e45de6a7bb65ac481b45b7e10c2\", \"traffic_annotation_auditor_unittests\": \"95556b87ff8fe38568146dd37f7b3803b71cb19c\", \"ui_base_unittests\": \"0c7a3947691e76b8be06b47dd5e4d53c26b6fd4b\", \"ui_touch_selection_unittests\": \"0468a1f8abeaf7ab1d2f8e96a47705db75a65692\", \"unit_tests\": \"f56febeaf8627e4f9033451fcda432b9690f4e19\", \"url_unittests\": \"f04b3cd3d9f35679e03b7bb45e49765e43752c66\", \"views_perftests\": \"f8af6b7885e9925bb5af4ac8587ef2eef5b26917\", \"views_unittests\": \"b4d233115b10144591985b18720040aede8817e8\", \"viz_unittests\": \"96141491b8ccf1b8e45018af4d88ac05e73b58e2\", \"vr_common_unittests\": \"df77d3bd3ac45a415132bdd838168002a14aa1cc\", \"vr_pixeltests\": \"2cade38fee4cb11a255de36b465b2ee113e2f1f9\", \"webdriver_wpt_tests\": \"ea0e0a47628ec0af0cd69695f8c4ac0a60456564\", \"weblayer_browsertests\": \"bf6a7531019be662c732f0a6992544bf7dc7b65b\", \"wm_unittests\": \"c39e18b8e01d42e82029bc941e8243e44bacd63d\", \"wtf_unittests\": \"6ccd204407b8c6f5643f4024452eaf5f55157b16\", \"xr_browser_tests\": \"e9ba998e479d7bca414be22f6d55b403a7b9cb0e\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_65ed874bd033a5984c3cbb528261ef1b136b40627f077f473aaaca27b3fa3caf_v2\", \"builder_746f25208a125120dfc7ef61e5ef35531bce9c0f7be870eb0811bf6f56bb01d6_v2\", \"builder_a8228d45a2e4046ab48e21b574ab916f6d3c7e4ad955b91040a9e9374d3dd18a_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-52-z84e\"], \"image\": [\"chrome-xenial-20012600-8e1a7e485b7\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"4801-8ac0b59\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1580347624435100",
"status": "COMPLETED",
"status_changed_ts": "1580350332937547",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168008",
"builder:Linux Builder (dbg)",
"buildset:commit/git/3475028d4c03238d8be9a7fbe1c6be56e14aba53",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3475028d4c03238d8be9a7fbe1c6be56e14aba53",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9055309487966712176",
"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/8889879425406445040/+/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:4a0cdc3a7d3b5a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1580350332972739",
"url": "https://ci.chromium.org/b/8889879425406445040",
"utcnow_ts": "1580350334879386"
}
}
[02:12:14.900] Invocation finished in 49m58.4754533s with status SUCCEEDED