[04:09:36.668] New invocation is queued and will start shortly
[04:09:38.116] Starting the invocation (attempt 1)
[04:09:38.143] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/20e350eddf396826438ae2e5ab666a5e365fd64a"
[04:09:38.143] Popped gitiles commit info from properties and tags
[04:09:38.143] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:09:38.143] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:09:38.143] Buildbucket request:
{
"requestId": "8930999922123970080",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "linux-arm64-rel-cft"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "20e350eddf396826438ae2e5ab666a5e365fd64a",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8930999922123970080"
},
{
"key": "scheduler_job_id",
"value": "chromium/linux-arm64-rel-cft"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[04:09:38.689] Scheduled build:
{
"id": "8765569859847682625",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "linux-arm64-rel-cft"
},
"number": 7180,
"createdBy": "project:chromium",
"createTime": "2023-11-02T04:09:38.206757211Z",
"updateTime": "2023-11-02T04:09:38.206757211Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "20e350eddf396826438ae2e5ab666a5e365fd64a",
"ref": "refs/heads/main"
}
}
}
[04:09:38.689] Task URL: https://cr-buildbucket.appspot.com/build/8765569859847682625
[04:09:38.689] Scheduling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8930999922123970080:2:0) after 6m50s
[04:09:38.886] Received PubSub notification, asking Buildbucket for the build status
[04:09:38.910] Build status: SCHEDULED
[04:10:55.259] Received PubSub notification, asking Buildbucket for the build status
[04:10:55.279] Build status: STARTED
[04:16:28.716] Handling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8930999922123970080:2:0)
[04:16:28.741] Build status: STARTED
[04:16:28.741] Scheduling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8930999922123970080:5:0) after 2m7s
[04:18:35.833] Handling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8930999922123970080:5:0)
[04:18:35.855] Build status: STARTED
[04:18:35.855] Scheduling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8930999922123970080:6:0) after 7m50s
[04:26:25.882] Handling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8930999922123970080:6:0)
[04:26:25.899] Build status: STARTED
[04:26:25.899] Scheduling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8930999922123970080:7:0) after 2m43s
[04:29:08.919] Handling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8930999922123970080:7:0)
[04:29:08.957] Build status: STARTED
[04:29:08.957] Scheduling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8930999922123970080:8:0) after 5m41s
[04:34:49.976] Handling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8930999922123970080:8:0)
[04:34:49.996] Build status: STARTED
[04:34:49.996] Scheduling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8930999922123970080:9:0) after 6m17s
[04:41:07.036] Handling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8930999922123970080:9:0)
[04:41:07.066] Build status: STARTED
[04:41:07.066] Scheduling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8930999922123970080:10:0) after 1m21s
[04:42:28.086] Handling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8930999922123970080:10:0)
[04:42:28.122] Build status: STARTED
[04:42:28.122] Scheduling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8930999922123970080:11:0) after 3m9s
[04:45:37.328] Handling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8930999922123970080:11:0)
[04:45:37.352] Build status: STARTED
[04:45:37.352] Scheduling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8930999922123970080:12:0) after 2m50s
[04:48:27.371] Handling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8930999922123970080:12:0)
[04:48:27.389] Build status: STARTED
[04:48:27.389] Scheduling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8930999922123970080:13:0) after 6m15s
[04:54:42.492] Handling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8930999922123970080:13:0)
[04:54:42.507] Build status: STARTED
[04:54:42.507] Scheduling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8930999922123970080:14:0) after 3m27s
[04:58:09.622] Handling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8930999922123970080:14:0)
[04:58:09.638] Build status: STARTED
[04:58:09.638] Scheduling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8930999922123970080:15:0) after 1m0s
[04:59:09.663] Handling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8930999922123970080:15:0)
[04:59:09.689] Build status: STARTED
[04:59:09.689] Scheduling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8930999922123970080:16:0) after 6m30s
[05:05:39.704] Handling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8930999922123970080:16:0)
[05:05:39.722] Build status: STARTED
[05:05:39.722] Scheduling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8930999922123970080:17:0) after 6m19s
[05:11:58.739] Handling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8930999922123970080:17:0)
[05:11:58.760] Build status: STARTED
[05:11:58.760] Scheduling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8930999922123970080:18:0) after 6m30s
[05:18:28.984] Handling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8930999922123970080:18:0)
[05:18:29.009] Build status: STARTED
[05:18:29.009] Scheduling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8930999922123970080:19:0) after 6m0s
[05:24:29.124] Handling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8930999922123970080:19:0)
[05:24:29.141] Build status: STARTED
[05:24:29.141] Scheduling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8930999922123970080:20:0) after 3m10s
[05:27:39.157] Handling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8930999922123970080:20:0)
[05:27:39.178] Build status: STARTED
[05:27:39.178] Scheduling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8930999922123970080:21:0) after 1m48s
[05:29:27.400] Handling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8930999922123970080:21:0)
[05:29:27.417] Build status: STARTED
[05:29:27.417] Scheduling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8930999922123970080:22:0) after 4m33s
[05:34:00.604] Handling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8930999922123970080:22:0)
[05:34:00.729] Build status: STARTED
[05:34:00.729] Scheduling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8930999922123970080:23:0) after 3m24s
[05:37:24.750] Handling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8930999922123970080:23:0)
[05:37:24.768] Build status: STARTED
[05:37:24.768] Scheduling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8930999922123970080:24:0) after 8m49s
[05:46:13.986] Handling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8930999922123970080:24:0)
[05:46:14.004] Build status: STARTED
[05:46:14.004] Scheduling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8930999922123970080:25:0) after 2m42s
[05:48:56.020] Handling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8930999922123970080:25:0)
[05:48:56.039] Build status: STARTED
[05:48:56.039] Scheduling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8930999922123970080:26:0) after 9m19s
[05:58:15.107] Handling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8930999922123970080:26:0)
[05:58:15.125] Build status: STARTED
[05:58:15.125] Scheduling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8930999922123970080:27:0) after 8m6s
[06:06:21.297] Handling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8930999922123970080:27:0)
[06:06:21.342] Build status: STARTED
[06:06:21.342] Scheduling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8930999922123970080:28:0) after 2m43s
[06:09:04.430] Handling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8930999922123970080:28:0)
[06:09:04.455] Build status: STARTED
[06:09:04.455] Scheduling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8930999922123970080:29:0) after 1m54s
[06:10:58.571] Handling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8930999922123970080:29:0)
[06:10:58.607] Build status: STARTED
[06:10:58.607] Scheduling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8930999922123970080:30:0) after 7m7s
[06:18:05.621] Handling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8930999922123970080:30:0)
[06:18:05.640] Build status: STARTED
[06:18:05.640] Scheduling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8930999922123970080:31:0) after 2m54s
[06:20:59.656] Handling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8930999922123970080:31:0)
[06:20:59.671] Build status: STARTED
[06:20:59.671] Scheduling timer "check-buildbucket-build-status" (chromium/linux-arm64-rel-cft:8930999922123970080:32:0) after 3m30s
[06:22:44.051] Received PubSub notification, asking Buildbucket for the build status
[06:22:44.067] Build:
{
"id": "8765569859847682625",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "linux-arm64-rel-cft"
},
"number": 7180,
"createdBy": "project:chromium",
"createTime": "2023-11-02T04:09:38.206757211Z",
"startTime": "2023-11-02T04:10:52.912534Z",
"endTime": "2023-11-02T06:22:43.123697Z",
"updateTime": "2023-11-02T06:22:43.123697Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "20e350eddf396826438ae2e5ab666a5e365fd64a",
"ref": "refs/heads/main"
}
}
}
[06:22:44.067] Invocation finished in 2h13m7.414761622s with status FAILED