[09:43:42.840] New invocation is queued and will start shortly
[09:43:43.962] Starting the invocation (attempt 1)
[09:43:44.005] Ignoring unrecognized buildset tag "buildset:commit/git/e3b6df712d7ea4c52bffbc8926ff480c669a25ec"
[09:43:44.005] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e3b6df712d7ea4c52bffbc8926ff480c669a25ec"
[09:43:44.005] Popped gitiles commit info from properties and tags
[09:43:44.005] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:43:44.005] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:43:44.005] Buildbucket request:
{
"requestId": "9017680199032504912",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "linux-chromeos-rel"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "e3b6df712d7ea4c52bffbc8926ff480c669a25ec",
"ref": "refs/heads/master"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "9017680199032504912"
},
{
"key": "scheduler_job_id",
"value": "chromium/linux-chromeos-rel"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[09:43:44.518] Scheduled build:
{
"id": "8852250137070755600",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "linux-chromeos-rel"
},
"number": 46289,
"createdBy": "project:chromium",
"createTime": "2021-03-20T09:43:44.079080Z",
"updateTime": "2021-03-20T09:43:44.432821Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "e3b6df712d7ea4c52bffbc8926ff480c669a25ec",
"ref": "refs/heads/master"
}
}
}
[09:43:44.518] Task URL: https://cr-buildbucket.appspot.com/build/8852250137070755600
[09:43:44.518] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:2:0) after 8m11s
[09:51:55.669] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:2:0)
[09:51:55.669] Timer tick, asking Buildbucket for the build status
[09:51:55.848] Build 8852250137070755600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:51:55.848] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:3:0) after 1m0s
[09:52:55.938] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:3:0)
[09:52:55.963] Build status: SCHEDULED
[09:52:55.963] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:4:0) after 2m17s
[09:55:12.984] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:4:0)
[09:55:13.011] Build status: SCHEDULED
[09:55:13.011] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:5:0) after 5m58s
[10:01:11.074] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:5:0)
[10:01:11.123] Build status: SCHEDULED
[10:01:11.123] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:6:0) after 7m5s
[10:08:16.270] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:6:0)
[10:08:16.270] Timer tick, asking Buildbucket for the build status
[10:08:16.337] Build 8852250137070755600: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:08:16.337] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:7:0) after 1m0s
[10:09:16.352] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:7:0)
[10:09:16.380] Build status: SCHEDULED
[10:09:16.380] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:8:0) after 1m48s
[10:11:04.617] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:8:0)
[10:11:04.644] Build status: SCHEDULED
[10:11:04.644] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:9:0) after 3m0s
[10:12:34.954] Received PubSub notification, asking Buildbucket for the build status
[10:12:35.054] Build 8852250137070755600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:04.724] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:9:0)
[10:14:04.725] Timer tick, asking Buildbucket for the build status
[10:14:04.801] Build 8852250137070755600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:04.801] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:11:0) after 1m0s
[10:15:04.828] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:11:0)
[10:15:04.853] Build status: STARTED
[10:15:04.853] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:12:0) after 5m55s
[10:20:59.882] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:12:0)
[10:20:59.908] Build status: STARTED
[10:20:59.908] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:13:0) after 7m49s
[10:28:49.056] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:13:0)
[10:28:49.056] Timer tick, asking Buildbucket for the build status
[10:28:49.158] Build 8852250137070755600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:49.158] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:14:0) after 1m0s
[10:29:49.188] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:14:0)
[10:29:49.188] Timer tick, asking Buildbucket for the build status
[10:29:49.263] Build 8852250137070755600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:49.263] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:15:0) after 1m0s
[10:30:49.284] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:15:0)
[10:30:49.284] Timer tick, asking Buildbucket for the build status
[10:30:49.399] Build 8852250137070755600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:49.399] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:16:0) after 1m0s
[10:31:49.462] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:16:0)
[10:31:49.462] Timer tick, asking Buildbucket for the build status
[10:31:49.535] Build 8852250137070755600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:49.535] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:17:0) after 1m0s
[10:32:49.624] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:17:0)
[10:32:49.624] Timer tick, asking Buildbucket for the build status
[10:32:49.700] Build 8852250137070755600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:49.700] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:18:0) after 1m0s
[10:33:49.887] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:18:0)
[10:33:49.887] Timer tick, asking Buildbucket for the build status
[10:33:49.969] Build 8852250137070755600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:49.969] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:19:0) after 1m0s
[10:34:50.028] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:19:0)
[10:34:50.056] Build status: STARTED
[10:34:50.056] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:20:0) after 7m17s
[10:42:07.078] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:20:0)
[10:42:07.108] Build status: STARTED
[10:42:07.108] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:21:0) after 7m8s
[10:49:15.125] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:21:0)
[10:49:15.169] Build status: STARTED
[10:49:15.169] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:22:0) after 9m7s
[10:58:22.167] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:22:0)
[10:58:22.167] Timer tick, asking Buildbucket for the build status
[10:58:22.238] Build 8852250137070755600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:22.238] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:23:0) after 1m0s
[10:59:22.252] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:23:0)
[10:59:22.252] Timer tick, asking Buildbucket for the build status
[10:59:22.390] Build 8852250137070755600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:22.390] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:24:0) after 1m0s
[11:00:22.414] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:24:0)
[11:00:22.443] Build status: STARTED
[11:00:22.443] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:25:0) after 9m34s
[11:09:56.439] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:25:0)
[11:09:56.465] Build status: STARTED
[11:09:56.465] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:26:0) after 6m23s
[11:16:19.547] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:26:0)
[11:16:19.547] Timer tick, asking Buildbucket for the build status
[11:16:19.896] Build 8852250137070755600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:19.896] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:27:0) after 1m0s
[11:17:20.116] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:27:0)
[11:17:20.116] Timer tick, asking Buildbucket for the build status
[11:17:20.203] Build 8852250137070755600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:20.203] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:28:0) after 1m0s
[11:18:20.236] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:28:0)
[11:18:20.236] Timer tick, asking Buildbucket for the build status
[11:18:20.301] Build 8852250137070755600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:20.301] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:29:0) after 1m0s
[11:19:20.320] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:29:0)
[11:19:20.344] Build status: STARTED
[11:19:20.344] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:30:0) after 2m47s
[11:22:07.361] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:30:0)
[11:22:07.361] Timer tick, asking Buildbucket for the build status
[11:22:07.424] Build 8852250137070755600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:07.424] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:31:0) after 1m0s
[11:23:07.444] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:31:0)
[11:23:07.474] Build status: STARTED
[11:23:07.474] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:32:0) after 2m1s
[11:25:08.569] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:32:0)
[11:25:08.608] Build status: STARTED
[11:25:08.608] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:33:0) after 7m15s
[11:32:23.624] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:33:0)
[11:32:23.673] Build status: STARTED
[11:32:23.673] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:34:0) after 6m26s
[11:38:50.353] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:34:0)
[11:38:50.353] Timer tick, asking Buildbucket for the build status
[11:38:50.685] Build 8852250137070755600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:50.685] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:35:0) after 1m0s
[11:39:50.707] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:35:0)
[11:39:50.734] Build status: STARTED
[11:39:50.734] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:36:0) after 6m17s
[11:46:07.735] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:36:0)
[11:46:07.735] Timer tick, asking Buildbucket for the build status
[11:46:07.833] Build 8852250137070755600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:07.833] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:37:0) after 1m0s
[11:47:08.024] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:37:0)
[11:47:08.024] Timer tick, asking Buildbucket for the build status
[11:47:08.102] Build 8852250137070755600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:08.102] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:38:0) after 1m0s
[11:48:08.122] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:38:0)
[11:48:08.148] Build status: STARTED
[11:48:08.148] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:39:0) after 4m2s
[11:52:10.270] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:39:0)
[11:52:10.300] Build status: STARTED
[11:52:10.300] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:40:0) after 2m31s
[11:54:41.337] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:40:0)
[11:54:41.367] Build status: STARTED
[11:54:41.367] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:41:0) after 3m17s
[11:57:58.388] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:41:0)
[11:57:58.388] Timer tick, asking Buildbucket for the build status
[11:57:58.659] Build 8852250137070755600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:58.659] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:42:0) after 1m0s
[11:58:58.715] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:42:0)
[11:58:58.778] Build status: STARTED
[11:58:58.778] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:43:0) after 6m56s
[12:05:54.954] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:43:0)
[12:05:54.979] Build status: STARTED
[12:05:54.979] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:44:0) after 3m43s
[12:09:38.058] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:44:0)
[12:09:38.086] Build status: STARTED
[12:09:38.086] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:45:0) after 7m59s
[12:17:37.150] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:45:0)
[12:17:37.177] Build status: STARTED
[12:17:37.177] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:46:0) after 4m39s
[12:22:16.436] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:46:0)
[12:22:16.436] Timer tick, asking Buildbucket for the build status
[12:22:16.579] Build 8852250137070755600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:16.579] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:47:0) after 1m0s
[12:23:17.085] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:47:0)
[12:23:17.085] Timer tick, asking Buildbucket for the build status
[12:23:17.208] Build 8852250137070755600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:17.208] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:48:0) after 1m0s
[12:24:17.570] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:48:0)
[12:24:17.619] Build status: STARTED
[12:24:17.619] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:49:0) after 2m0s
[12:26:22.930] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:49:0)
[12:26:22.930] Timer tick, asking Buildbucket for the build status
[12:26:23.876] Build 8852250137070755600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:23.876] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:50:0) after 1m0s
[12:27:23.893] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:50:0)
[12:27:23.893] Timer tick, asking Buildbucket for the build status
[12:27:23.994] Build 8852250137070755600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:23.994] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:51:0) after 1m0s
[12:28:24.013] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:51:0)
[12:28:24.041] Build status: STARTED
[12:28:24.041] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:52:0) after 6m20s
[12:34:44.134] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:52:0)
[12:34:44.161] Build status: STARTED
[12:34:44.161] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:53:0) after 9m34s
[12:44:18.544] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:53:0)
[12:44:18.544] Timer tick, asking Buildbucket for the build status
[12:44:18.678] Build 8852250137070755600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:18.678] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:54:0) after 1m0s
[12:45:18.988] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:54:0)
[12:45:19.013] Build status: STARTED
[12:45:19.013] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:55:0) after 5m21s
[12:50:40.236] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:55:0)
[12:50:40.236] Timer tick, asking Buildbucket for the build status
[12:50:40.340] Build 8852250137070755600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:40.341] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:56:0) after 1m0s
[12:51:40.361] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:56:0)
[12:51:40.408] Build status: STARTED
[12:51:40.408] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:57:0) after 9m7s
[13:00:47.577] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:57:0)
[13:00:47.577] Timer tick, asking Buildbucket for the build status
[13:00:47.686] Build 8852250137070755600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:47.686] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:58:0) after 1m0s
[13:01:47.714] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:58:0)
[13:01:47.714] Timer tick, asking Buildbucket for the build status
[13:01:47.802] Build 8852250137070755600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:47.802] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:59:0) after 1m0s
[13:02:47.995] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:59:0)
[13:02:48.029] Build status: STARTED
[13:02:48.029] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:60:0) after 8m16s
[13:11:04.058] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:60:0)
[13:11:04.058] Timer tick, asking Buildbucket for the build status
[13:11:04.396] Build 8852250137070755600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:04.396] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:61:0) after 1m0s
[13:12:04.584] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:61:0)
[13:12:04.584] Timer tick, asking Buildbucket for the build status
[13:12:04.657] Build 8852250137070755600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:04.657] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:62:0) after 1m0s
[13:13:04.734] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:62:0)
[13:13:04.759] Build status: STARTED
[13:13:04.759] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:63:0) after 1m50s
[13:14:54.787] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:63:0)
[13:14:54.787] Timer tick, asking Buildbucket for the build status
[13:14:54.868] Build 8852250137070755600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:54.868] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:64:0) after 1m0s
[13:15:54.901] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017680199032504912:64:0)
[13:15:54.901] Timer tick, asking Buildbucket for the build status
[13:15:54.980] Build 8852250137070755600: status "COMPLETED", result "CANCELED", failure_reason "", cancelation_reason "TIMEOUT"
[13:15:54.980] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"cancelation_reason": "TIMEOUT",
"completed_ts": "1616246128163121",
"created_by": "project:chromium",
"created_ts": "1616233424079080",
"id": "8852250137070755600",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {}}",
"project": "chromium",
"result": "CANCELED",
"result_details_json": "{\"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\": \"e3b6df712d7ea4c52bffbc8926ff480c669a25ec\", \"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-63-29hh\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 46289, \"got_angle_revision\": \"135385a1ae86bf857be37475f927dcf78c9476b4\", \"got_dawn_revision\": \"853b5d765e715fc11ea28c1d3754e48d38c283b1\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"e3b6df712d7ea4c52bffbc8926ff480c669a25ec\", \"got_revision_cp\": \"refs/heads/master@{#864946}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"a98fb3f8380a3a5c9ae3e0175634ba88c85a434f\", \"got_v8_revision\": \"6092f990f654f2d7a4534ee8c5bbb5c4fb800bdc\", \"got_v8_revision_cp\": \"refs/heads/9.1.170@{#1}\", \"got_webrtc_revision\": \"2bab0efee4d9d163841b6d060c690c381e1768ac\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33511}\", \"is_cached\": false, \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.chromiumos:linux-chromeos-inverse-fieldtrials-fyi-rel\", \"tryserver.chromium.chromiumos:linux-chromeos-rel\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"swarm_hashes_refs/heads/master(at){#864946}_without_patch\": {\"absl_hardening_tests\": \"4f2173246cabe6473e5a3608ba4d7fdda54f6447\", \"accessibility_unittests\": \"8b88df243fbaf56010fef5ad5a5143c4fcc9931e\", \"angle_unittests\": \"3144e95d50d6f4f86c8a6f045a5ac7e60f15a46c\", \"app_list_unittests\": \"27d91f046c30162ade741c4559868d8bef5a721c\", \"app_shell_unittests\": \"1f734e2bb0684ac88775cb69017b62c1bbb91db3\", \"ash_unittests\": \"1aeacc2bd5b699be4b1d34bfc85cc61a4dafa5d8\", \"aura_unittests\": \"11f388426a682c25cb7d4425ec97872848837661\", \"base_unittests\": \"4b23716f79b0d361f416e0b49caf1269fe334f90\", \"base_util_unittests\": \"a27b2ac22ac6db2ec6db757e9be9845ba36a01cf\", \"blink_common_unittests\": \"0bd7be5a5f3944be1ef918431ac7bbbcfed7d701\", \"blink_fuzzer_unittests\": \"2f4b964e0f7bfb0b8fe867c06ebd6c5ad5843d79\", \"blink_heap_unittests\": \"1752fa4ec39cf933240d6b7008675393b96ba684\", \"blink_platform_unittests\": \"f5289e048e761a053553857a9bea94884737e60c\", \"blink_unittests\": \"b8d3f41e67c0eb6065c14d13f4c99065125fe933\", \"boringssl_crypto_tests\": \"f66f9ed76c4893e5fdbf68944d5a9f8ad869a3cf\", \"boringssl_ssl_tests\": \"d709f7cd651505ccc2242d871eb53e55b7589ec7\", \"browser_tests\": \"d1e488d8f0d15990b7983e80fbc74daab79c462a\", \"capture_unittests\": \"71ff1dcf842640d9f5e8645327f1c413002a578c\", \"cast_unittests\": \"4c1b6fd95d76bcc2733fb0484c145aee1ecca7ec\", \"cc_unittests\": \"0047da08bea3ca8538f2a5f0795529dc78d24477\", \"chrome_app_unittests\": \"1ec76753e732ee6fc2d213bf9119b185267859db\", \"chromedriver_unittests\": \"e67d069a6ffd5afd63b472c6fefe6c75eddb71a8\", \"chromeos_components_unittests\": \"af06306f2fe589ac3e823928f673939d942460fc\", \"chromeos_unittests\": \"55c265257da1b10f0c44f9f38f8a15746e287d5e\", \"color_unittests\": \"f6ba28d4d8948a5c0609bb688dd8f1cf9481fe48\", \"components_browsertests\": \"753acba57a5f25c952435f1e6610db06e46c8c5c\", \"components_unittests\": \"c203a23242da2703ce246608b13fd25b3de470e9\", \"compositor_unittests\": \"d2d277b3fa489430757959d977e750265c2dc4af\", \"content_browsertests\": \"898df2e5c1b982ff342bed019b2b1b2050999984\", \"content_unittests\": \"8596ba8291ac0c9a05677a229b9ee44faeee7597\", \"crashpad_tests\": \"e750c28044ef24cc0d97495846d2249815db5e03\", \"crypto_unittests\": \"cdcc5fc4ed1463ac0b80b34f78a3419cb06610da\", \"dbus_unittests\": \"194969710adebe233544801a34e3b44be9d16264\", \"device_unittests\": \"f6c6cd9bf480a98a2a102406b328eaef18ce70b9\", \"display_unittests\": \"01bb1e4adf1eae8d69d003a38fa1995de24cb909\", \"events_unittests\": \"68efea55d1891c06f6b9ffa56c1b9e13861b9f90\", \"exo_unittests\": \"e10398a91f5ea920566c42ba58fdbbe1dd14167a\", \"extensions_browsertests\": \"da8385754459264143476bbbe14c58d111d3a035\", \"extensions_unittests\": \"d4f945c80b07105fb19d02ccdfdf7bbb011a6635\", \"filesystem_service_unittests\": \"a68ade1fcdcae2d643a520fa710133f990f22b75\", \"gcm_unit_tests\": \"42b12a64caf6009232d043ec15ce80d166e007c3\", \"gfx_unittests\": \"e66a121baa2f5b2eeecde8cb02f93f5490d5c7de\", \"gin_unittests\": \"29b37d35685dd303866ffec9fa87139c42cd06a8\", \"gl_unittests_ozone\": \"e46aa5324530fbc75238061da1a081286949bdd0\", \"google_apis_unittests\": \"bbeaa2f05c56d91143aa230cf0d3bf55c45f38ef\", \"gpu_unittests\": \"fbe0379f454fb71c328eeaec0e624bc307aa5b03\", \"gwp_asan_unittests\": \"091c3745d4691c80ba51100661f7a475019f6364\", \"interactive_ui_tests\": \"247e59b4eb2c0594d7b1f25538740876d0d74ef3\", \"ipc_tests\": \"db17c9efca96bcf363092b9e03d5443fbd36178e\", \"jingle_unittests\": \"3efad0942757eebdf4ced17d5a814a3578b064f6\", \"keyboard_unittests\": \"bec864c5929b8c1dd918cc5f245b65b45d4c01a0\", \"latency_unittests\": \"45e9491bba7fc267277347afb06b069b59ba04b4\", \"libjingle_xmpp_unittests\": \"36ca4fdd4cead7fd73eb791584ee43daeb7fba17\", \"liburlpattern_unittests\": \"71207036877b7d093f1ab4b71cf59b0f20020c14\", \"media_blink_unittests\": \"e8196ef6d4c3b62d1766ec944b9dc847326c98d5\", \"media_unittests\": \"84c0b86506b0de09a9f4128bd026bfbc0d2e04c1\", \"message_center_unittests\": \"5583d3bee356949559f8f3360a00e1c28aed70f3\", \"midi_unittests\": \"d3e5404891e475f409c3d46b4ab6101b85959ff1\", \"mojo_core_unittests\": \"8c82745e4fe41bc394baecbcdf8bdc30df79aadd\", \"mojo_unittests\": \"b5beb6b7cbcff96e10c1beb7a672e204b488922f\", \"nacl_helper_nonsfi_unittests\": \"9514cfb9c733fadaa1c5f2df4bfb64ca08d68cc6\", \"nacl_loader_unittests\": \"0af112e0dfb969df7d56c0a06b6b8649c863c390\", \"native_theme_unittests\": \"5d0e1d6ef75f74c47bdea1bc217922fb3094aa07\", \"net_unittests\": \"f035b5e4b384b81c153c270fc00538722fb2bd21\", \"ozone_gl_unittests\": \"0dd3d43d94df5530e86e90d071afd08fdf6af37e\", \"ozone_unittests\": \"6489ece5a23d43880c37a53f0897fdc9a394127e\", \"ozone_x11_unittests\": \"09d45ec5023ac82cc00c0ec14f87c98d65bf9bec\", \"pdf_unittests\": \"adefb4e389361fa95c0151581d5b195324889d77\", \"perfetto_unittests\": \"b948fc14e81e2b0acffa2115d6f162cc88174bb1\", \"ppapi_unittests\": \"70a8b4e3795108862e22ba08692990c4c149c23b\", \"printing_unittests\": \"1d19d2ba2cb4c282b26e16e729576337091f20af\", \"remoting_unittests\": \"083e915da7c7421388ecf627e4b15d6e17bef287\", \"sandbox_linux_unittests\": \"2e450b3d68c03bbafb34934d302c5c9340f52761\", \"service_manager_unittests\": \"4185bc993247422920444f802dcbea6bf65da8d2\", \"services_unittests\": \"1b19fda8e5231e8c0d3ddcfe96b70f5b19aedc10\", \"shell_dialogs_unittests\": \"2acf715e5b0a91a17a88ab0074aa2e7236f58649\", \"shell_encryption_unittests\": \"76c7c5b6c7835448aad9a515d264429e4b72d594\", \"skia_unittests\": \"36f35f1b0535674fbf82bfc9939b171684dc767e\", \"snapshot_unittests\": \"30b514f2149d757397bb82ec73c1c49fc8ef091d\", \"sql_unittests\": \"2b2bc4c4ab34a23bc88d31d7f7ad201050876490\", \"storage_unittests\": \"58397e56dfa35d1e07976e32d3a0944d8b15a942\", \"sync_integration_tests\": \"43e25e3e302d803e0d4603e64719b20ddec20b4d\", \"traffic_annotation_auditor_unittests\": \"804f8ce254ca104508758c4383638a58c7e71244\", \"ui_base_unittests\": \"678601f35c2072dfd9b47f48d830f5086c126315\", \"ui_chromeos_unittests\": \"c8f8602cc120022ab6bff6b8917527657895508d\", \"ui_touch_selection_unittests\": \"0fec6bcb59e806ae15594a86a2375723864e962e\", \"unit_tests\": \"4ec469f8cbd2487634e8c1d8bd279532886145d2\", \"url_unittests\": \"bb687d3ba69857f53c27e8174ad33433b0642d73\", \"usage_time_limit_unittests\": \"35733cdfecd1d3a241d1b81d50df09ac452bb154\", \"views_unittests\": \"3d5c3b83e2b70f0353c0b89376d9416ec6611d98\", \"viz_unittests\": \"030cc01589635672612ff2281f011403a0a60ad1\", \"wayland_client_perftests\": \"d954ba7442f891d9ec45b90a3140bbc3cd79cacd\", \"wayland_client_tests\": \"b7cfb4c158c512a4eda593ffde32e7e45aaad10d\", \"wm_unittests\": \"9ee4d0a067c8fd863fe5cb5c23ec49646a7017f3\", \"wtf_unittests\": \"52c39efc890bd444b63d1576dc51dfc5072b3717\", \"zlib_unittests\": \"cd15604def9e709dd295ca6435efbf03298cdafa\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_093e8a6336a6e6d4670d6f5128e7c73730afadf3bd7f9284d26d8f79182f8ce5_v2\", \"builder_1415e99d1ada386cfb0c84043d2495490d2cdcd28ad7785eb6837a9119e6428f_v2\", \"builder_279625ee090c361ea8cf031374b7b020d1191c526e0ef1068ab634999c69ee70_v2\", \"builder_a1b9c6892397d61c3624774723419f5ae48fcf776e7d2304bd4682bf5cd4c049_v2\", \"builder_db6a6033e460ced730b3940170fe4cf923f58c29fb56731f98470df5edf70e65_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-63-29hh\"], \"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\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1616235153929642",
"status": "COMPLETED",
"status_changed_ts": "1616246128618486",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/46289",
"builder:linux-chromeos-rel",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e3b6df712d7ea4c52bffbc8926ff480c669a25ec",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9017680199032504912",
"scheduler_job_id:chromium/linux-chromeos-rel",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8852250137070755600/+/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:5267d66f2fa46810",
"user_agent:luci-scheduler"
],
"updated_ts": "1616246128623833",
"url": "https://ci.chromium.org/b/8852250137070755600",
"utcnow_ts": "1616246154970183"
}
}
[13:15:54.980] Invocation finished in 3h32m12.155316709s with status FAILED