[23:21:48.831] New invocation is queued and will start shortly
[23:21:49.928] Starting the invocation (attempt 1)
[23:21:49.995] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:21:49.997] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:21:49.997] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9019350071001268288",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"8213c580a81c63b913f9686d66bdb359a492355c\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9019350071001268288",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/8213c580a81c63b913f9686d66bdb359a492355c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8213c580a81c63b913f9686d66bdb359a492355c",
"gitiles_ref:refs/heads/master"
]
}
[23:21:52.557] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1614640910241967",
"id": "8853920008861840176",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8213c580a81c63b913f9686d66bdb359a492355c\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614640912224969",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/181022",
"builder:Linux Builder (dbg)",
"buildset:commit/git/8213c580a81c63b913f9686d66bdb359a492355c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8213c580a81c63b913f9686d66bdb359a492355c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019350071001268288",
"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/8853920008861840176/+/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": "1614640912225082",
"url": "https://ci.chromium.org/b/8853920008861840176",
"utcnow_ts": "1614640912515415"
}
}
[23:21:52.557] Task URL: https://ci.chromium.org/b/8853920008861840176
[23:21:52.557] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:2:0) after 1m0s
[23:21:54.885] Received PubSub notification, asking Buildbucket for the build status
[23:21:54.971] Build 8853920008861840176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:52.582] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:2:0)
[23:22:52.582] Timer tick, asking Buildbucket for the build status
[23:22:52.686] Build 8853920008861840176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:52.686] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:4:0) after 1m0s
[23:23:52.732] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:4:0)
[23:23:52.732] Timer tick, asking Buildbucket for the build status
[23:23:53.143] Build 8853920008861840176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:53.143] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:5:0) after 1m0s
[23:24:53.150] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:5:0)
[23:24:53.150] Timer tick, asking Buildbucket for the build status
[23:24:53.230] Build 8853920008861840176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:53.230] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:6:0) after 1m0s
[23:25:53.236] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:6:0)
[23:25:53.236] Timer tick, asking Buildbucket for the build status
[23:25:53.529] Build 8853920008861840176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:53.529] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:7:0) after 1m0s
[23:26:53.539] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:7:0)
[23:26:53.539] Timer tick, asking Buildbucket for the build status
[23:26:53.646] Build 8853920008861840176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:53.646] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:8:0) after 1m0s
[23:27:53.674] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:8:0)
[23:27:53.674] Timer tick, asking Buildbucket for the build status
[23:27:53.843] Build 8853920008861840176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:53.843] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:9:0) after 1m0s
[23:28:53.849] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:9:0)
[23:28:53.849] Timer tick, asking Buildbucket for the build status
[23:28:54.345] Build 8853920008861840176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:54.345] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:10:0) after 1m0s
[23:29:54.365] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:10:0)
[23:29:54.365] Timer tick, asking Buildbucket for the build status
[23:29:57.045] Build 8853920008861840176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:57.045] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:11:0) after 1m0s
[23:30:57.072] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:11:0)
[23:30:57.072] Timer tick, asking Buildbucket for the build status
[23:30:57.376] Build 8853920008861840176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:57.376] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:12:0) after 1m0s
[23:31:57.396] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:12:0)
[23:31:57.396] Timer tick, asking Buildbucket for the build status
[23:31:57.503] Build 8853920008861840176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:57.503] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:13:0) after 1m0s
[23:32:57.524] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:13:0)
[23:32:57.524] Timer tick, asking Buildbucket for the build status
[23:33:01.177] Build 8853920008861840176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:01.177] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:14:0) after 1m0s
[23:34:01.172] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:14:0)
[23:34:01.172] Timer tick, asking Buildbucket for the build status
[23:34:01.457] Build 8853920008861840176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:01.457] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:15:0) after 1m0s
[23:35:01.496] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:15:0)
[23:35:01.496] Timer tick, asking Buildbucket for the build status
[23:35:02.661] Build 8853920008861840176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:02.661] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:16:0) after 1m0s
[23:36:02.705] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:16:0)
[23:36:02.705] Timer tick, asking Buildbucket for the build status
[23:36:02.994] Build 8853920008861840176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:02.994] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:17:0) after 1m0s
[23:37:03.007] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:17:0)
[23:37:03.007] Timer tick, asking Buildbucket for the build status
[23:37:03.323] Build 8853920008861840176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:03.323] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:18:0) after 1m0s
[23:38:03.353] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:18:0)
[23:38:03.353] Timer tick, asking Buildbucket for the build status
[23:38:03.431] Build 8853920008861840176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:03.431] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:19:0) after 1m0s
[23:39:03.490] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:19:0)
[23:39:03.490] Timer tick, asking Buildbucket for the build status
[23:39:03.833] Build 8853920008861840176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:03.833] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:20:0) after 1m0s
[23:40:03.878] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:20:0)
[23:40:03.878] Timer tick, asking Buildbucket for the build status
[23:40:04.068] Build 8853920008861840176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:04.068] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:21:0) after 1m0s
[23:41:04.046] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:21:0)
[23:41:04.046] Timer tick, asking Buildbucket for the build status
[23:41:06.025] Build 8853920008861840176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:06.025] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:22:0) after 1m0s
[23:42:06.070] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:22:0)
[23:42:06.070] Timer tick, asking Buildbucket for the build status
[23:42:07.910] Build 8853920008861840176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:07.910] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:23:0) after 1m0s
[23:43:08.142] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:23:0)
[23:43:08.142] Timer tick, asking Buildbucket for the build status
[23:43:08.214] Build 8853920008861840176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:08.214] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:24:0) after 1m0s
[23:44:08.275] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:24:0)
[23:44:08.275] Timer tick, asking Buildbucket for the build status
[23:44:08.365] Build 8853920008861840176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:08.365] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:25:0) after 1m0s
[23:45:08.409] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:25:0)
[23:45:08.409] Timer tick, asking Buildbucket for the build status
[23:45:08.523] Build 8853920008861840176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:08.523] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:26:0) after 1m0s
[23:46:08.600] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:26:0)
[23:46:08.600] Timer tick, asking Buildbucket for the build status
[23:46:08.926] Build 8853920008861840176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:08.926] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:27:0) after 1m0s
[23:47:09.067] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:27:0)
[23:47:09.067] Timer tick, asking Buildbucket for the build status
[23:47:09.541] Build 8853920008861840176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:09.541] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:28:0) after 1m0s
[23:48:09.539] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:28:0)
[23:48:09.539] Timer tick, asking Buildbucket for the build status
[23:48:09.616] Build 8853920008861840176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:09.616] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:29:0) after 1m0s
[23:49:09.670] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:29:0)
[23:49:09.670] Timer tick, asking Buildbucket for the build status
[23:49:09.742] Build 8853920008861840176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:09.742] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:30:0) after 1m0s
[23:50:09.765] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:30:0)
[23:50:09.765] Timer tick, asking Buildbucket for the build status
[23:50:09.848] Build 8853920008861840176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:09.848] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:31:0) after 1m0s
[23:51:09.915] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:31:0)
[23:51:09.915] Timer tick, asking Buildbucket for the build status
[23:51:10.041] Build 8853920008861840176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:10.041] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:32:0) after 1m0s
[23:52:10.059] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:32:0)
[23:52:10.059] Timer tick, asking Buildbucket for the build status
[23:52:10.152] Build 8853920008861840176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:10.152] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:33:0) after 1m0s
[23:53:10.233] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:33:0)
[23:53:10.233] Timer tick, asking Buildbucket for the build status
[23:53:10.301] Build 8853920008861840176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:10.301] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:34:0) after 1m0s
[23:54:10.574] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:34:0)
[23:54:10.574] Timer tick, asking Buildbucket for the build status
[23:54:13.074] Build 8853920008861840176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:13.074] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:35:0) after 1m0s
[23:55:13.651] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:35:0)
[23:55:13.651] Timer tick, asking Buildbucket for the build status
[23:55:13.733] Build 8853920008861840176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:13.733] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:36:0) after 1m0s
[23:56:13.728] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:36:0)
[23:56:13.728] Timer tick, asking Buildbucket for the build status
[23:56:13.827] Build 8853920008861840176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:13.827] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:37:0) after 1m0s
[23:57:13.876] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:37:0)
[23:57:13.876] Timer tick, asking Buildbucket for the build status
[23:57:13.966] Build 8853920008861840176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:13.966] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:38:0) after 1m0s
[23:58:14.233] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:38:0)
[23:58:14.233] Timer tick, asking Buildbucket for the build status
[23:58:14.706] Build 8853920008861840176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:14.706] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:39:0) after 1m0s
[23:59:14.787] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:39:0)
[23:59:14.787] Timer tick, asking Buildbucket for the build status
[23:59:14.870] Build 8853920008861840176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:14.870] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:40:0) after 1m0s
[00:00:14.938] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:40:0)
[00:00:14.938] Timer tick, asking Buildbucket for the build status
[00:00:15.116] Build 8853920008861840176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:15.116] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:41:0) after 1m0s
[00:01:15.650] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:41:0)
[00:01:15.650] Timer tick, asking Buildbucket for the build status
[00:01:15.764] Build 8853920008861840176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:15.764] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:42:0) after 1m0s
[00:02:15.816] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:42:0)
[00:02:15.816] Timer tick, asking Buildbucket for the build status
[00:02:15.904] Build 8853920008861840176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:15.904] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:43:0) after 1m0s
[00:03:15.953] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:43:0)
[00:03:15.953] Timer tick, asking Buildbucket for the build status
[00:03:16.032] Build 8853920008861840176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:16.032] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:44:0) after 1m0s
[00:04:16.056] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:44:0)
[00:04:16.056] Timer tick, asking Buildbucket for the build status
[00:04:16.187] Build 8853920008861840176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:16.187] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:45:0) after 1m0s
[00:05:16.193] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:45:0)
[00:05:16.193] Timer tick, asking Buildbucket for the build status
[00:05:16.284] Build 8853920008861840176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:16.284] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:46:0) after 1m0s
[00:06:16.315] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:46:0)
[00:06:16.315] Timer tick, asking Buildbucket for the build status
[00:06:16.513] Build 8853920008861840176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:16.513] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:47:0) after 1m0s
[00:07:16.920] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:47:0)
[00:07:16.920] Timer tick, asking Buildbucket for the build status
[00:07:17.466] Build 8853920008861840176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:17.466] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:48:0) after 1m0s
[00:08:17.639] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:48:0)
[00:08:17.639] Timer tick, asking Buildbucket for the build status
[00:08:17.753] Build 8853920008861840176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:17.753] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:49:0) after 1m0s
[00:09:17.844] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:49:0)
[00:09:17.844] Timer tick, asking Buildbucket for the build status
[00:09:17.924] Build 8853920008861840176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:17.924] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:50:0) after 1m0s
[00:10:17.978] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:50:0)
[00:10:17.978] Timer tick, asking Buildbucket for the build status
[00:10:18.273] Build 8853920008861840176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:18.273] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:51:0) after 1m0s
[00:11:18.346] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:51:0)
[00:11:18.346] Timer tick, asking Buildbucket for the build status
[00:11:18.409] Build 8853920008861840176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:18.409] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:52:0) after 1m0s
[00:12:18.453] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:52:0)
[00:12:18.453] Timer tick, asking Buildbucket for the build status
[00:12:18.601] Build 8853920008861840176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:18.601] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:53:0) after 1m0s
[00:13:18.608] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:53:0)
[00:13:18.608] Timer tick, asking Buildbucket for the build status
[00:13:18.684] Build 8853920008861840176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:18.684] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:54:0) after 1m0s
[00:14:18.822] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:54:0)
[00:14:18.822] Timer tick, asking Buildbucket for the build status
[00:14:18.946] Build 8853920008861840176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:18.946] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:55:0) after 1m0s
[00:15:18.994] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:55:0)
[00:15:18.994] Timer tick, asking Buildbucket for the build status
[00:15:19.114] Build 8853920008861840176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:19.114] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:56:0) after 1m0s
[00:16:19.214] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:56:0)
[00:16:19.215] Timer tick, asking Buildbucket for the build status
[00:16:19.292] Build 8853920008861840176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:19.292] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:57:0) after 1m0s
[00:17:19.375] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:57:0)
[00:17:19.375] Timer tick, asking Buildbucket for the build status
[00:17:19.495] Build 8853920008861840176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:19.495] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019350071001268288:58:0) after 1m0s
[00:17:34.183] Received PubSub notification, asking Buildbucket for the build status
[00:17:34.348] Build 8853920008861840176: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[00:17:34.348] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1614644253106480",
"created_by": "project:chromium",
"created_ts": "1614640910241967",
"id": "8853920008861840176",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8213c580a81c63b913f9686d66bdb359a492355c\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"jobs\": 500.0, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"8213c580a81c63b913f9686d66bdb359a492355c\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-east1-d-8-58-14cr\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.linux\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 181022, \"got_angle_revision\": \"5b82fa2e842475cd93c6a6119b6e07635054e836\", \"got_dawn_revision\": \"931182485579d2eec3b24c1f2df646870f80da68\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"8213c580a81c63b913f9686d66bdb359a492355c\", \"got_revision_cp\": \"refs/heads/master@{#858760}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"106e01583a3ac2fb950777390dde2dcc31a8f161\", \"got_v8_revision\": \"851ca4314ae4a367bcc4cabb20326029eff18492\", \"got_v8_revision_cp\": \"refs/heads/9.1.7@{#1}\", \"got_webrtc_revision\": \"376cf384ac689eca0bf8d4d1d3a3cf80888d3fbb\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33348}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.linux:linux-clang-tidy-dbg\", \"tryserver.chromium.linux:linux_chromium_dbg_ng\", \"tryserver.chromium.linux:linux_chromium_compile_dbg_ng\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8213c580a81c63b913f9686d66bdb359a492355c\", \"swarm_hashes_refs/heads/master(at){#858760}_without_patch\": {\"absl_hardening_tests\": \"758f028005469f418417e936e4d454ac2cd9ee11\", \"accessibility_unittests\": \"d8c9f687fb635a4da25660d4708650e777c35b1b\", \"angle_unittests\": \"e4a6f05284271afb613293a0306005386d7a7b25\", \"app_shell_unittests\": \"1bb149a778bb06e57f867642f5700120598b4c49\", \"aura_unittests\": \"ee9840831f899d9e439819468872d022d0f3beb7\", \"base_unittests\": \"4d8cb80303ec2f9c82c17f2f5f9d06feaf15ca6e\", \"base_util_unittests\": \"1720a91d40bb738b074ef9367735ccfdc51f0b5e\", \"blink_common_unittests\": \"0b3cb45acd8123f96fc4c85801d6a88538a71312\", \"blink_fuzzer_unittests\": \"af17ce3b689442ebf5950aed742ce07e5cc7364c\", \"blink_heap_unittests\": \"e81c4567a8ee12723eac9af466dbaf528b6a88a8\", \"blink_platform_unittests\": \"8b4ad2fbfbdb1f557bd8b04457f9006b475c7142\", \"blink_python_tests\": \"84db0c6c03c0d6f0ae779ff3511d45c950da93d4\", \"blink_unittests\": \"8d2a77f21a703d1ad97155c8b248077134a095a5\", \"blink_web_tests\": \"97f278549e3f32ff04df3e659b6f88e59303fbcb\", \"boringssl_crypto_tests\": \"61546db7bbf54f5daee6caf4296615e2b71f9057\", \"boringssl_ssl_tests\": \"6c366e7c2d3d20c5428af4ef0657f0812fd1235b\", \"browser_tests\": \"8f0594667b34514b7c3141d43146ea1e6cd507b1\", \"capture_unittests\": \"e48f0dbf6fd691b91f96ed06b8cdc2104625b62d\", \"cast_unittests\": \"1120126fd243180c9a73814a255b0bc6dd6c3ad3\", \"cc_unittests\": \"62388a3040f8a1e195ae948d867701ca3376e838\", \"chrome_app_unittests\": \"e592eb3e236a45a40edb44d4043949a12ed546b4\", \"chromedriver_unittests\": \"284b035fc45a3b4950a7ce1e14561de44b22b39c\", \"color_unittests\": \"2d088d9ef7f52bd3eefc7353b2f413b54722537f\", \"components_browsertests\": \"1b5e4828f205298c223a732fa546d681219f0dea\", \"components_unittests\": \"d270c89cb10fc890bccc33c3249d74cd479eba7e\", \"compositor_unittests\": \"b99b7549dc26779e1262e63688b22c35f5f6fb26\", \"content_browsertests\": \"c4f83c3d0069cfec0025be10ee196b1439211d06\", \"content_shell_crash_test\": \"cc9d289e281eda630eaa6f4a04d9435f399381a0\", \"content_unittests\": \"3e820ed5ce459064aa6b9f92d5a05c2978ce0e4d\", \"crashpad_tests\": \"2ec664eff5b5cf2b3f8a963c34951431c2754fb3\", \"cronet_tests\": \"f502d9078d0da2b880d90abd154c5e7672322ac5\", \"cronet_unittests\": \"79f70f6d29046d20fc31f9f38117516a4492a12a\", \"crypto_unittests\": \"8b51e35f2dd5fced03ef718afcb241f33ea6d01d\", \"dbus_unittests\": \"ee4eb7f622d670e8856eb51e46f1de4a83b4e280\", \"device_unittests\": \"37f71640c18d87bea988c083557bbbf17b9d9bb8\", \"display_unittests\": \"9715b9e40665aae5602111f12345bd4e51f1c03b\", \"events_unittests\": \"3f2d30ef1eb4838483620c1337bb7d578efc85cb\", \"extensions_browsertests\": \"2737493656ae868f7ec92665a4cddd12e21b82c4\", \"extensions_unittests\": \"a36aebf106058dfe750e03d8b905667869ab771e\", \"filesystem_service_unittests\": \"5db8b44e90d9440c2a78eb812f5a95f0d506aa78\", \"flatbuffers_unittests\": \"51ca3d0f331c0b735f966bf9eb546bfaaaf2e81f\", \"gcm_unit_tests\": \"7c7453bdea63847a24053d99102f7697cf412328\", \"gfx_unittests\": \"10c28e7b7014c634294da6c51329f7b2bc33c79d\", \"gin_unittests\": \"5a93dea2b1ada7dc0863e7a4a6ff639e2637833c\", \"google_apis_unittests\": \"420724373a70562993b789aa38d6e8f8846c6fad\", \"gpu_unittests\": \"b3e1ef64da070d77541c805971ab223da3848b23\", \"grit_python_unittests\": \"00738482c42a9310ca0fd96f0709b3d808390403\", \"gwp_asan_unittests\": \"f75ef18136b09781e899ac008f44f4c352f18c47\", \"headless_browsertests\": \"b6ae0a8160fc446e1f0fab638604573d51f394d2\", \"headless_unittests\": \"54d5a7aecffd42921cfacfeeefe194d37c94c27c\", \"interactive_ui_tests\": \"72b486d925aa31917298bdbf2d3759aaca78539a\", \"ipc_tests\": \"f14cf5a996a2011bea3db52ee93264e00d8d8cf8\", \"jingle_unittests\": \"49dba8ec42a8b592bb573fc4748024c1579272e5\", \"latency_unittests\": \"4001c9b9f11d01357f99642be4922def7fc3b8dd\", \"libjingle_xmpp_unittests\": \"d90ed64a074c500209ca87e870a3e00c2d12a1c0\", \"liburlpattern_unittests\": \"c9fe3e460301e923b39133660c08871d0d962b06\", \"media_blink_unittests\": \"8f4acd2f9d98a9987d916334f49327b5467aebf8\", \"media_unittests\": \"57bf983063220f68265b5c2af5adfa2322e83f83\", \"message_center_unittests\": \"ccfad5fb167b58df9974689abfb6ba6a16abd593\", \"metrics_python_tests\": \"a79b760a3a38119c96c163ce2f5e3986469cc538\", \"midi_unittests\": \"be804515eedc14cadc32f07e8a5d648f823c9090\", \"mojo_core_unittests\": \"7644e9ad4751cbab2de8bdd901dac53079edd616\", \"mojo_unittests\": \"7a42fbe5fa6fc807de678ee29a5652f7951750d7\", \"nacl_helper_nonsfi_unittests\": \"88f321e91e6f2a1aac928fbdd862c3f6df9b01bb\", \"nacl_loader_unittests\": \"ed8c899360130493118ac5c0103657c8221cfa37\", \"native_theme_unittests\": \"2265d7a06b6fd76c1b1cd488af17da1a316bf80c\", \"net_unittests\": \"4b7fa9a34cca2e4af4c41676f1af78ec523bb798\", \"openscreen_unittests\": \"f5150d12c36ddd86bb9200affd180e4fa6bda165\", \"pdf_unittests\": \"04fa2f11917adbdd5541fb9696ae63b4d985b034\", \"perfetto_unittests\": \"673b0011eeebc19d645e137bbece299f5ee10c12\", \"ppapi_unittests\": \"fa4a3f98cf6b7223a3114d3d4fb89e465ec8cc54\", \"printing_unittests\": \"e2b836aae296754a36af3cfd1b68d42cb49a4cd7\", \"remoting_unittests\": \"dd8a8e5a8086878762d9e3b57c9ee05359dcc421\", \"sandbox_linux_unittests\": \"44ca935c208f93bf24c938e9fd9c32e4e752e50d\", \"service_manager_unittests\": \"119cfba9e9446e227b939764d57ac5ad4bc9ba43\", \"services_unittests\": \"e302f9e0eb901b0bc9ff56d55fd9381c8df3f412\", \"shell_dialogs_unittests\": \"052bce6ebcc8a46b8acc48502f9955e8852539dc\", \"skia_unittests\": \"3fc3926b3fb7f4fa2eb580781f43fc0dec2b52de\", \"snapshot_unittests\": \"5f87956544f1e246676a5ab31cc170a1f0363693\", \"sql_unittests\": \"96c43a745e17eff053ab575daf76c3c23fa1109a\", \"storage_unittests\": \"2f933b25f3d99df7038b909c78743d8db2abb58a\", \"sync_integration_tests\": \"1fa4de9f34212539e9ad0621aa8f99e8c3eba132\", \"telemetry_gpu_unittests\": \"af45c49c9bab13237b404d452a39f719151824c9\", \"telemetry_perf_unittests\": \"ed15b4862877acd264f1d0a449ae4f56f3209eb2\", \"telemetry_unittests\": \"9ad6f8767bc44afe8ad924a2b0ab878047c00d99\", \"traffic_annotation_auditor_unittests\": \"8c12c11176935802f6527844ca3ea4fefcf09dd8\", \"ui_base_unittests\": \"9ec9e3d509fa8b17ee162f07cb0871a092e2a6dc\", \"ui_touch_selection_unittests\": \"541457f06429f4404a84b6c620cfe65a3355ea81\", \"unit_tests\": \"f18978d0bb5a49afec474077c23c889514bde37e\", \"url_unittests\": \"16b5ade3c23229a03c2df7e7e6d9a2cbc3cc7774\", \"views_perftests\": \"d4648bc96d960275e299a64fe74101b545121033\", \"views_unittests\": \"630428318a0ae5267042d3cc85d161ac8c9ccd2b\", \"viz_unittests\": \"726c9d28092a8534d1ee03dddf4e8a8c2f607974\", \"vr_common_unittests\": \"9a77d8e0b7ae9ffa2031439cddf936e28a442bd9\", \"vr_pixeltests\": \"53874737b544cad89aee6f9598a32d7e061d96f9\", \"webdriver_wpt_tests\": \"b8908b9fde403543ebc6379d16f13f18da315b55\", \"weblayer_browsertests\": \"38f8142c45f4aa3c92e553e546c2973d28691f88\", \"weblayer_unittests\": \"245ed055b27695dab575a9ea6a395a6c97313144\", \"wm_unittests\": \"a80cfa72b155e292a4163c10297aae9349e6cbc3\", \"wtf_unittests\": \"c14d2a3c07c456d704f33afe76f7790110179c48\", \"x11_unittests\": \"52eab9a13ff424527f4c32f11061323223a63a34\", \"xr_browser_tests\": \"763c11008a80239df3c62c0a6f1a24b02c241591\", \"zlib_unittests\": \"2c8e2fe57f57729ff81c7a535dbd15d1b5ed2c82\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_184d6e985834a567368b8f6447128caeb09465b6c98fb38e1d5acc1d51174b3d_v2\", \"builder_61faa0d0c1c079ecf670d8cfa4f78ad3b1687a5534233c7377462c3ee6819708_v2\", \"builder_da70dc51ee1338ac47024d06940b0d2d32d1124aaf9501320cd276ea00b900f0_v2\", \"builder_e21c17032b725bcf1c922976a36352546baf3f39ec148d1e6730a93c0400d8cc_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-east1-d-8-58-14cr\"], \"image\": [\"chrome-xenial-21011100-9488e34e558\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5712-339372b\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1614640914266412",
"status": "COMPLETED",
"status_changed_ts": "1614644253375338",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/181022",
"builder:Linux Builder (dbg)",
"buildset:commit/git/8213c580a81c63b913f9686d66bdb359a492355c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8213c580a81c63b913f9686d66bdb359a492355c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019350071001268288",
"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/8853920008861840176/+/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:5208eaa0dea7a310",
"user_agent:luci-scheduler"
],
"updated_ts": "1614644253379765",
"url": "https://ci.chromium.org/b/8853920008861840176",
"utcnow_ts": "1614644254338366"
}
}
[00:17:34.348] Invocation finished in 55m45.531441874s with status SUCCEEDED