[05:38:09.476] New invocation is queued and will start shortly
[05:38:10.713] Starting the invocation (attempt 1)
[05:38:10.752] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e65bd9509e1d2aa7d6e6ca5fa1d370f062bb27e1"
[05:38:10.752] Popped gitiles commit info from properties and tags
[05:38:10.752] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:38:10.752] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:38:10.752] Buildbucket request:
{
"requestId": "9017695648083122128",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "linux-chromeos-dbg"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "e65bd9509e1d2aa7d6e6ca5fa1d370f062bb27e1",
"ref": "refs/heads/master"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "9017695648083122128"
},
{
"key": "scheduler_job_id",
"value": "chromium/linux-chromeos-dbg"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[05:38:11.312] Scheduled build:
{
"id": "8852265585981108096",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "linux-chromeos-dbg"
},
"number": 23057,
"createdBy": "project:chromium",
"createTime": "2021-03-20T05:38:10.850448Z",
"updateTime": "2021-03-20T05:38:11.189346Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "e65bd9509e1d2aa7d6e6ca5fa1d370f062bb27e1",
"ref": "refs/heads/master"
}
}
}
[05:38:11.312] Task URL: https://cr-buildbucket.appspot.com/build/8852265585981108096
[05:38:11.312] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:2:0) after 3m50s
[05:42:01.329] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:2:0)
[05:42:01.366] Build status: SCHEDULED
[05:42:01.366] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:3:0) after 4m12s
[05:46:13.830] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:3:0)
[05:46:13.857] Build status: SCHEDULED
[05:46:13.857] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:4:0) after 2m17s
[05:48:30.936] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:4:0)
[05:48:30.960] Build status: SCHEDULED
[05:48:30.960] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:5:0) after 9m45s
[05:58:16.442] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:5:0)
[05:58:16.442] Timer tick, asking Buildbucket for the build status
[05:58:16.723] Build 8852265585981108096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:58:16.723] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:6:0) after 1m0s
[05:59:16.837] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:6:0)
[05:59:16.837] Timer tick, asking Buildbucket for the build status
[05:59:16.905] Build 8852265585981108096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:59:16.905] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:7:0) after 1m0s
[06:00:17.116] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:7:0)
[06:00:17.116] Timer tick, asking Buildbucket for the build status
[06:00:17.269] Build 8852265585981108096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:00:17.269] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:8:0) after 1m0s
[06:01:34.927] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:8:0)
[06:01:34.927] Timer tick, asking Buildbucket for the build status
[06:01:34.989] Build 8852265585981108096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:01:34.989] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:9:0) after 1m0s
[06:02:35.056] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:9:0)
[06:02:35.056] Timer tick, asking Buildbucket for the build status
[06:02:35.253] Build 8852265585981108096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:02:35.253] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:10:0) after 1m0s
[06:03:35.329] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:10:0)
[06:03:35.356] Build status: SCHEDULED
[06:03:35.356] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:11:0) after 3m34s
[06:07:09.390] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:11:0)
[06:07:09.427] Build status: SCHEDULED
[06:07:09.427] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:12:0) after 9m21s
[06:16:30.446] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:12:0)
[06:16:30.446] Timer tick, asking Buildbucket for the build status
[06:16:30.572] Build 8852265585981108096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:16:30.572] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:13:0) after 1m0s
[06:17:30.591] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:13:0)
[06:17:30.591] Timer tick, asking Buildbucket for the build status
[06:17:30.694] Build 8852265585981108096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:17:30.694] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:14:0) after 1m0s
[06:18:30.814] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:14:0)
[06:18:30.814] Timer tick, asking Buildbucket for the build status
[06:18:30.889] Build 8852265585981108096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:18:30.889] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:15:0) after 1m0s
[06:19:31.015] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:15:0)
[06:19:31.042] Build status: SCHEDULED
[06:19:31.042] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:16:0) after 8m18s
[06:27:49.059] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:16:0)
[06:27:49.082] Build status: SCHEDULED
[06:27:49.082] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:17:0) after 4m36s
[06:28:41.643] Received PubSub notification, asking Buildbucket for the build status
[06:28:41.737] Build 8852265585981108096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:25.214] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:17:0)
[06:32:25.214] Timer tick, asking Buildbucket for the build status
[06:32:25.282] Build 8852265585981108096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:25.282] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:19:0) after 1m0s
[06:33:25.350] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:19:0)
[06:33:25.382] Build status: STARTED
[06:33:25.382] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:20:0) after 6m36s
[06:40:01.401] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:20:0)
[06:40:01.401] Timer tick, asking Buildbucket for the build status
[06:40:01.480] Build 8852265585981108096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:01.480] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:21:0) after 1m0s
[06:41:01.507] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:21:0)
[06:41:01.507] Timer tick, asking Buildbucket for the build status
[06:41:01.576] Build 8852265585981108096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:01.576] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:22:0) after 1m0s
[06:42:02.384] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:22:0)
[06:42:02.384] Timer tick, asking Buildbucket for the build status
[06:42:02.718] Build 8852265585981108096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:02.718] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:23:0) after 1m0s
[06:43:02.903] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:23:0)
[06:43:02.903] Timer tick, asking Buildbucket for the build status
[06:43:02.979] Build 8852265585981108096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:02.979] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:24:0) after 1m0s
[06:44:03.011] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:24:0)
[06:44:03.011] Timer tick, asking Buildbucket for the build status
[06:44:03.095] Build 8852265585981108096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:03.095] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:25:0) after 1m0s
[06:45:03.279] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:25:0)
[06:45:03.306] Build status: STARTED
[06:45:03.306] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:26:0) after 7m17s
[06:52:20.325] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:26:0)
[06:52:20.326] Timer tick, asking Buildbucket for the build status
[06:52:20.415] Build 8852265585981108096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:20.415] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:27:0) after 1m0s
[06:53:20.441] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:27:0)
[06:53:20.466] Build status: STARTED
[06:53:20.467] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:28:0) after 1m26s
[06:54:46.587] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:28:0)
[06:54:46.587] Timer tick, asking Buildbucket for the build status
[06:54:46.678] Build 8852265585981108096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:46.678] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:29:0) after 1m0s
[06:55:46.659] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:29:0)
[06:55:46.659] Timer tick, asking Buildbucket for the build status
[06:55:46.836] Build 8852265585981108096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:46.836] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:30:0) after 1m0s
[06:56:46.862] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:30:0)
[06:56:46.862] Timer tick, asking Buildbucket for the build status
[06:56:46.929] Build 8852265585981108096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:46.929] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:31:0) after 1m0s
[06:57:47.005] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:31:0)
[06:57:47.005] Timer tick, asking Buildbucket for the build status
[06:57:47.199] Build 8852265585981108096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:47.199] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:32:0) after 1m0s
[06:58:47.287] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:32:0)
[06:58:47.314] Build status: STARTED
[06:58:47.314] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:33:0) after 3m34s
[07:02:21.451] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:33:0)
[07:02:21.451] Timer tick, asking Buildbucket for the build status
[07:02:21.533] Build 8852265585981108096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:21.533] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:34:0) after 1m0s
[07:03:21.552] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:34:0)
[07:03:21.580] Build status: STARTED
[07:03:21.580] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:35:0) after 5m12s
[07:08:33.698] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:35:0)
[07:08:33.731] Build status: STARTED
[07:08:33.731] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:36:0) after 6m22s
[07:14:55.881] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:36:0)
[07:14:55.881] Timer tick, asking Buildbucket for the build status
[07:14:56.168] Build 8852265585981108096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:56.168] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:37:0) after 1m0s
[07:15:56.188] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:37:0)
[07:15:56.188] Timer tick, asking Buildbucket for the build status
[07:15:56.600] Build 8852265585981108096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:56.600] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:38:0) after 1m0s
[07:16:56.669] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:38:0)
[07:16:56.669] Timer tick, asking Buildbucket for the build status
[07:16:57.116] Build 8852265585981108096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:57.116] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:39:0) after 1m0s
[07:17:57.137] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:39:0)
[07:17:57.137] Timer tick, asking Buildbucket for the build status
[07:17:57.202] Build 8852265585981108096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:57.202] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:40:0) after 1m0s
[07:18:57.224] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:40:0)
[07:18:57.224] Timer tick, asking Buildbucket for the build status
[07:18:57.544] Build 8852265585981108096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:57.544] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:41:0) after 1m0s
[07:19:57.611] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:41:0)
[07:19:57.635] Build status: STARTED
[07:19:57.635] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:42:0) after 4m51s
[07:24:48.802] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:42:0)
[07:24:48.802] Timer tick, asking Buildbucket for the build status
[07:24:48.945] Build 8852265585981108096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:48.945] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:43:0) after 1m0s
[07:25:48.964] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:43:0)
[07:25:48.989] Build status: STARTED
[07:25:48.989] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:44:0) after 8m28s
[07:34:17.359] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:44:0)
[07:34:17.359] Timer tick, asking Buildbucket for the build status
[07:34:17.598] Build 8852265585981108096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:17.598] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:45:0) after 1m0s
[07:35:17.620] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:45:0)
[07:35:17.657] Build status: STARTED
[07:35:17.657] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:46:0) after 5m26s
[07:40:43.765] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:46:0)
[07:40:43.800] Build status: STARTED
[07:40:43.800] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:47:0) after 9m59s
[07:50:42.908] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:47:0)
[07:50:42.954] Build status: STARTED
[07:50:42.954] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:48:0) after 1m20s
[07:52:02.974] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:48:0)
[07:52:02.974] Timer tick, asking Buildbucket for the build status
[07:52:03.588] Build 8852265585981108096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:03.588] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:49:0) after 1m0s
[07:53:03.607] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:49:0)
[07:53:03.634] Build status: STARTED
[07:53:03.634] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:50:0) after 3m24s
[07:56:27.664] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:50:0)
[07:56:27.690] Build status: STARTED
[07:56:27.690] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:51:0) after 7m16s
[08:03:43.852] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:51:0)
[08:03:43.852] Timer tick, asking Buildbucket for the build status
[08:03:44.031] Build 8852265585981108096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:44.031] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:52:0) after 1m0s
[08:04:44.020] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:52:0)
[08:04:44.046] Build status: STARTED
[08:04:44.046] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:53:0) after 9m36s
[08:14:20.069] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:53:0)
[08:14:20.069] Timer tick, asking Buildbucket for the build status
[08:14:20.227] Build 8852265585981108096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:20.227] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:54:0) after 1m0s
[08:15:20.344] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:54:0)
[08:15:20.373] Build status: STARTED
[08:15:20.373] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:55:0) after 3m25s
[08:18:45.658] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:55:0)
[08:18:45.658] Timer tick, asking Buildbucket for the build status
[08:18:45.924] Build 8852265585981108096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:45.924] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:56:0) after 1m0s
[08:19:46.273] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:56:0)
[08:19:46.273] Timer tick, asking Buildbucket for the build status
[08:19:46.351] Build 8852265585981108096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:46.351] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:57:0) after 1m0s
[08:20:46.344] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:57:0)
[08:20:46.344] Timer tick, asking Buildbucket for the build status
[08:20:46.424] Build 8852265585981108096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:46.424] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:58:0) after 1m0s
[08:21:46.443] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:58:0)
[08:21:46.443] Timer tick, asking Buildbucket for the build status
[08:21:46.677] Build 8852265585981108096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:46.677] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:59:0) after 1m0s
[08:22:46.987] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:59:0)
[08:22:47.012] Build status: STARTED
[08:22:47.012] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017695648083122128:60:0) after 6m11s
[08:27:26.066] Received PubSub notification, asking Buildbucket for the build status
[08:27:26.136] Build 8852265585981108096: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[08:27:26.136] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1616228845805082",
"created_by": "project:chromium",
"created_ts": "1616218690850448",
"failure_reason": "BUILD_FAILURE",
"id": "8852265585981108096",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- TopControlsSlideControllerTest.TestIntermediateSliding\"}, \"properties\": {\"$build/goma\": {\"enable_ats\": true, \"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\": \"e65bd9509e1d2aa7d6e6ca5fa1d370f062bb27e1\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-central1-b-8-32-un0b\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-dbg\", \"buildnumber\": 23057, \"got_angle_revision\": \"d5bc8a27abec7e86ea555f816e6dfa3947a2ffa5\", \"got_dawn_revision\": \"853b5d765e715fc11ea28c1d3754e48d38c283b1\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"e65bd9509e1d2aa7d6e6ca5fa1d370f062bb27e1\", \"got_revision_cp\": \"refs/heads/master@{#864934}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"a98fb3f8380a3a5c9ae3e0175634ba88c85a434f\", \"got_v8_revision\": \"66377e9eee38ae5d2bec601ec40a6e7e792169bf\", \"got_v8_revision_cp\": \"refs/heads/9.1.169@{#1}\", \"got_webrtc_revision\": \"490c1503d92696894e9c88c2a40a24ed78abe0a8\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33510}\", \"is_cached\": false, \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.chromiumos:linux-chromeos-dbg\", \"tryserver.chromium.chromiumos:linux-chromeos-compile-dbg\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"swarm_hashes_refs/heads/master(at){#864934}_without_patch\": {\"absl_hardening_tests\": \"71fe836dbb6c777aed97ab81f00876fc71d0061e\", \"accessibility_unittests\": \"74b09c5cddf3aae01699eac0de2e3a0e56058aa7\", \"angle_unittests\": \"fd18b82f09459cf2c40d6555c4719992f7139699\", \"app_list_unittests\": \"9fcb3493fce94f43b0d2e86b96f0a5bad7a2b30b\", \"app_shell_unittests\": \"4451aab965cc32982654ee89bc87091b7b7e728c\", \"ash_unittests\": \"5d6ab4c17e4d3da6bb942e45b13f1eb77fb179de\", \"aura_unittests\": \"efaca4a4ed12540c795d6b8fad0780915dd39c24\", \"base_unittests\": \"8a0ead53fb751a15ac3b25d3de1f6b186a36d1b6\", \"base_util_unittests\": \"bf484710129bdc13180867cc99757b7e436359db\", \"blink_common_unittests\": \"11f2687f6b81939e08b80fdaa8a7ed83d6fdf0f6\", \"blink_fuzzer_unittests\": \"1341c14bb759e11aeecd9524e3ad737d68f5cad2\", \"blink_heap_unittests\": \"47a7efb39aeeabebddd3d61f10039a62555bb9eb\", \"blink_platform_unittests\": \"631eb2a6a0af28efb4d824da508790fd1eba383b\", \"blink_unittests\": \"58b39fda74430f560d2d32d40b5eea2206a34513\", \"boringssl_crypto_tests\": \"f3571250516c7ab97dbc75dc9d42e449b906b22d\", \"boringssl_ssl_tests\": \"d4e40c652c6a47f81ab99772b57a620de6d0b9fd\", \"browser_tests\": \"59afe0e3ddda487ccc29c09714553863c18f238a\", \"capture_unittests\": \"b3026f9f4c13107b16c43757341c0df38239bbc5\", \"cast_unittests\": \"7f6696ace43c0d59148cf8015076a75472fc3ae9\", \"cc_unittests\": \"b283fb6e874cdcec04c45bb8ee28101b8970528b\", \"chrome_app_unittests\": \"21157eb7b6e792560a13cc17dae761047fa59949\", \"chromedriver_unittests\": \"aaf3f8cad4ecff74c7ea37f0bef634d1f18a87c9\", \"chromeos_components_unittests\": \"acc76bcc898d6cce132a716291efd125f003aef2\", \"chromeos_unittests\": \"29ecf8f030840b6941501538eb18e54836244075\", \"color_unittests\": \"4ad2250fa5156558e81ace6bbc454ba7af0a7b18\", \"components_browsertests\": \"3952707bce15a91316e681b700a571459b156a1f\", \"components_unittests\": \"48b6118ad8264543dba18048f23aabce8ad8ae0f\", \"compositor_unittests\": \"3832b8787f4ba87ec02ccba767b14fd190188781\", \"content_browsertests\": \"2e25d71632b006550dbc526a973e7571c48e9804\", \"content_unittests\": \"bbc85d944f250b679203566ce40747d0b9f60e95\", \"crashpad_tests\": \"133a1bba186ff39ecce562c6f115d5bf6936e7bc\", \"crypto_unittests\": \"61a76a6bd35308f1e004c4b1ce6c03a56ae184fe\", \"dbus_unittests\": \"7e91d7314094e3381edf9d7c9c0302742e346d1c\", \"device_unittests\": \"ef97b99ea1d1aa535afc6f340254bf2cfae0d278\", \"display_unittests\": \"702750820ddbc381dee1f801c5b9f9dba80e2ba1\", \"events_unittests\": \"d9980400c8957506258c5e4634db8cacc47fa22b\", \"exo_unittests\": \"f0f60e9fbfd16dd1501da7f32b2225cab6efba04\", \"extensions_browsertests\": \"16de7d18e9972d39d456e48218c9f2ec1cbcbca8\", \"extensions_unittests\": \"8c23d52697c14626e25b040f301a25f5fc28b241\", \"filesystem_service_unittests\": \"803e52969c71015587e577f2b0bd60253cf7f542\", \"gcm_unit_tests\": \"b4107f694712faf01baea1a9449cc783c1d39117\", \"gfx_unittests\": \"8505726ebc841d23b0310e032fe90e43a1b7ec96\", \"gin_unittests\": \"8a2eb359e57cb37e9527a678c9818f6f1e19c907\", \"gl_unittests_ozone\": \"1311441828520cc7e4092d6021e01d3411bd1f40\", \"google_apis_unittests\": \"38360be6336fc74d983091bb550f522c56a2ecd0\", \"gpu_unittests\": \"d7f5c5096507989a4e50832f002fab851e2256c2\", \"gwp_asan_unittests\": \"a67367dbbf227468cb7d6d269f150034526ecf0b\", \"interactive_ui_tests\": \"55039d42a9fd0e359e282876e40450b8a4149c7d\", \"ipc_tests\": \"9f1ca16129c8b4501d367a58a8a91384f528b61f\", \"jingle_unittests\": \"821fa640a608d96573304cea44b0493c414173a0\", \"keyboard_unittests\": \"2a1fa6e2c665ec452b7ebe276f99128a39b16b75\", \"latency_unittests\": \"3c8ced059c2ccb6f7c8ea0a79085561d8864d598\", \"libjingle_xmpp_unittests\": \"86c6f74f5af736636fec6039ea9d5457906aab74\", \"liburlpattern_unittests\": \"5ee2cd06a78b0a3a4fdd123f29749fc5c4869db2\", \"media_blink_unittests\": \"16101845a38347a4158ee2b62a38630727e1a3a6\", \"media_unittests\": \"fbcfdc18f24587ff07d24a8cad1b02c13e911f40\", \"message_center_unittests\": \"4d023c682ed06f493fdb3e6727d83f430a6e5ae7\", \"midi_unittests\": \"50a73865f89e476d77c173c16dfaa5a95b53322f\", \"mojo_core_unittests\": \"a21982f70fc307987168d02b2a570b8f35a0f7f9\", \"mojo_unittests\": \"58eead296e5218fb27a5a04fbb14a0ceceda7caf\", \"nacl_helper_nonsfi_unittests\": \"bd9a34ae3684d1ac0e6bb3fba7f0c0e064b69208\", \"nacl_loader_unittests\": \"3cf089eb2e8bd251b8ea5f1f3161beae22228abb\", \"native_theme_unittests\": \"29c5560d45ccfa00573856ffa5f891014952effb\", \"net_unittests\": \"830adb5294438f9b2c6090a1c311fa60cb8eef0d\", \"ozone_gl_unittests\": \"af342278bd033f92f726e0013726550169c28447\", \"ozone_unittests\": \"23f85f7d599f8ef7a0b019d12a3fe60f41c6be3a\", \"ozone_x11_unittests\": \"03322268352eed1e1d2edfb763b9ac29368ad58a\", \"pdf_unittests\": \"4d9e836cdd40253d00c8e3d31eab4397d59e5b95\", \"perfetto_unittests\": \"6e3d2e2266caa7c9bea1593434914c038d4dfa35\", \"ppapi_unittests\": \"d5ea34e20a148cb655c4e2172588092e4781de59\", \"printing_unittests\": \"4a951cbaa6ee4233c46c99f8b49be6ccb2948751\", \"remoting_unittests\": \"4b186e2e42543762a44231bbed7a74c121901e23\", \"sandbox_linux_unittests\": \"df6c94d023fbedf714a4c75da537b6d52d6c9303\", \"service_manager_unittests\": \"26680c4060aefa284b2ce164b48d8caf241ed27c\", \"services_unittests\": \"559376bc7fcb52fd47dcf526e97f4b38005944f1\", \"shell_dialogs_unittests\": \"b5b84cd575a2ab754a8af7f24c704aeb59b582ae\", \"shell_encryption_unittests\": \"0206e1ddb60b6db222d938716bf3221a18254e9b\", \"skia_unittests\": \"f5a3d2cd00b5cb3aacb0a00c7c194146aea26f88\", \"snapshot_unittests\": \"4e783a78fe7de2d8a892e132ace142ec8e705dec\", \"sql_unittests\": \"c8f2412c6a0fa8720e226c3f6a9a6af39a6bc7eb\", \"storage_unittests\": \"da50805b9eb45ed6912bdf378dcbc16c1c4b2be1\", \"sync_integration_tests\": \"3a2755e6c28c4f96bc5af1417ea7fc5bd2ee6f10\", \"traffic_annotation_auditor_unittests\": \"35db18915ea8d0d38499f21c8d4333591ab0d441\", \"ui_base_unittests\": \"e0fc4e770540e24f22e7387d80365e9290e43444\", \"ui_chromeos_unittests\": \"b92cb1bdb879a2d75d61ad526136fd0f737e86c7\", \"ui_touch_selection_unittests\": \"1f77972d0d35d248a55e8e6a065c42a43294f443\", \"unit_tests\": \"9d0db92d85349ebd9f748439ded817d91ba11cb8\", \"url_unittests\": \"881e71b0dd23b655b69f8b8149636f493eb34953\", \"usage_time_limit_unittests\": \"3199e63fcd6472c20fb3a5347d07f9794af7a330\", \"views_unittests\": \"815f1825fe47d9e1978d03f2fcf9433f22a7906d\", \"viz_unittests\": \"081b4409899b0148446a061c32982a922f2c2b20\", \"wayland_client_tests\": \"66329a7ef426ddc430a3ab510452de96302060e9\", \"wm_unittests\": \"e7e354c7cc87d5b9a8c72cbf99a66b89c11535fa\", \"wtf_unittests\": \"7d61a871e2cb1bb4ff5cb1409aeae509daf5b17f\", \"zlib_unittests\": \"9545e0cbd9b92446a15aea82b29fe7d74f5b8772\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_45c5b54513fd8d251ddf48af87bd70552077e6f51953669d04eaa4296b96a344_v2\", \"builder_5315cf154f9c1ae6b2621094599bc107171a849e1c0f232ec66a504ee800a364_v2\", \"builder_8e7ad9a10a7772feba5b42dda56992e918fe88f23579c3ddaf696dde66ee4238_v2\", \"builder_a16dce727623db190129e730193443d0ae3080534f372fd612ff56e110b9028d_v2\", \"builder_b224075beee246a5ffe9706a23163fae567137407ac5f1b69c50b4dcd22edd9b_v2\", \"builder_e7fd4b7a9e211f8d968af60263c508e13e49814167aff980c2bf239c05ea48ef_v2\", \"builder_f11fafa89e130b21aaaf89babe10260b305cabf64cf9dbedf786af2782bba9d2_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-8-32-un0b\"], \"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\", \"2.7\", \"2.7.12\"], \"server_version\": [\"5776-cb9e157\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- TopControlsSlideControllerTest.TestIntermediateSliding\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1616221720913282",
"status": "COMPLETED",
"status_changed_ts": "1616221721312498",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/23057",
"builder:linux-chromeos-dbg",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e65bd9509e1d2aa7d6e6ca5fa1d370f062bb27e1",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9017695648083122128",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8852265585981108096/+/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:5266f59fe5682b10",
"user_agent:luci-scheduler"
],
"updated_ts": "1616228845805384",
"url": "https://ci.chromium.org/b/8852265585981108096",
"utcnow_ts": "1616228846128461"
}
}
[08:27:26.136] Invocation finished in 2h49m16.674044138s with status FAILED