[02:49:13.648] New invocation is queued and will start shortly
[02:49:15.329] Starting the invocation (attempt 1)
[02:49:15.355] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/641e514336b1e85eaa2d712deafac6adb35525a0"
[02:49:15.355] Popped gitiles commit info from properties and tags
[02:49:15.355] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:49:15.355] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:49:15.355] Buildbucket request:
{
"requestId": "8939521094261720896",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "linux-official"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "641e514336b1e85eaa2d712deafac6adb35525a0",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8939521094261720896"
},
{
"key": "scheduler_job_id",
"value": "chromium/linux-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[02:49:16.076] Scheduled build:
{
"id": "8774091031717728721",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "linux-official"
},
"number": 7888,
"createdBy": "project:chromium",
"createTime": "2023-07-31T02:49:15.448348284Z",
"updateTime": "2023-07-31T02:49:15.448348284Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "641e514336b1e85eaa2d712deafac6adb35525a0",
"ref": "refs/heads/main"
}
}
}
[02:49:16.076] Task URL: https://cr-buildbucket.appspot.com/build/8774091031717728721
[02:49:16.076] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:2:0) after 2m25s
[02:49:16.888] Received PubSub notification, asking Buildbucket for the build status
[02:49:16.904] Build status: SCHEDULED
[02:49:56.454] Received PubSub notification, asking Buildbucket for the build status
[02:49:56.485] Build status: STARTED
[02:51:41.134] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:2:0)
[02:51:41.186] Build status: STARTED
[02:51:41.186] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:5:0) after 7m10s
[02:58:51.241] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:5:0)
[02:58:51.271] Build status: STARTED
[02:58:51.271] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:6:0) after 3m19s
[03:02:10.286] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:6:0)
[03:02:10.308] Build status: STARTED
[03:02:10.308] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:7:0) after 7m48s
[03:09:58.323] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:7:0)
[03:09:58.349] Build status: STARTED
[03:09:58.349] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:8:0) after 7m55s
[03:17:53.415] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:8:0)
[03:17:53.515] Build status: STARTED
[03:17:53.515] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:9:0) after 7m24s
[03:25:17.535] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:9:0)
[03:25:17.572] Build status: STARTED
[03:25:17.572] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:10:0) after 9m6s
[03:34:23.709] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:10:0)
[03:34:23.727] Build status: STARTED
[03:34:23.727] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:11:0) after 4m21s
[03:38:44.746] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:11:0)
[03:38:44.772] Build status: STARTED
[03:38:44.772] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:12:0) after 3m29s
[03:42:13.764] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:12:0)
[03:42:13.782] Build status: STARTED
[03:42:13.782] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:13:0) after 6m49s
[03:49:02.804] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:13:0)
[03:49:02.843] Build status: STARTED
[03:49:02.843] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:14:0) after 3m36s
[03:52:38.859] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:14:0)
[03:52:38.879] Build status: STARTED
[03:52:38.879] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:15:0) after 6m58s
[03:59:37.503] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:15:0)
[03:59:37.534] Build status: STARTED
[03:59:37.534] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:16:0) after 7m56s
[04:07:33.628] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:16:0)
[04:07:33.709] Build status: STARTED
[04:07:33.709] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:17:0) after 5m5s
[04:12:38.766] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:17:0)
[04:12:38.790] Build status: STARTED
[04:12:38.790] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:18:0) after 3m48s
[04:16:26.840] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:18:0)
[04:16:26.863] Build status: STARTED
[04:16:26.863] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:19:0) after 2m17s
[04:18:43.881] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:19:0)
[04:18:44.016] Build status: STARTED
[04:18:44.016] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:20:0) after 2m9s
[04:20:53.041] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:20:0)
[04:20:53.073] Build status: STARTED
[04:20:53.073] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:21:0) after 7m8s
[04:28:01.114] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:21:0)
[04:28:01.141] Build status: STARTED
[04:28:01.141] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:22:0) after 9m53s
[04:37:54.173] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:22:0)
[04:37:54.201] Build status: STARTED
[04:37:54.201] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:23:0) after 4m10s
[04:42:04.255] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:23:0)
[04:42:04.279] Build status: STARTED
[04:42:04.279] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:24:0) after 7m48s
[04:49:52.312] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:24:0)
[04:49:52.378] Build status: STARTED
[04:49:52.378] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:25:0) after 1m12s
[04:51:04.598] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:25:0)
[04:51:04.615] Build status: STARTED
[04:51:04.615] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:26:0) after 5m22s
[04:56:26.663] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:26:0)
[04:56:26.680] Build status: STARTED
[04:56:26.680] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:27:0) after 5m47s
[05:02:13.698] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:27:0)
[05:02:13.717] Build status: STARTED
[05:02:13.717] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:28:0) after 5m12s
[05:07:25.979] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:28:0)
[05:07:25.998] Build status: STARTED
[05:07:25.998] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:29:0) after 7m34s
[05:15:00.023] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:29:0)
[05:15:00.046] Build status: STARTED
[05:15:00.046] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:30:0) after 3m44s
[05:18:44.061] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:30:0)
[05:18:44.086] Build status: STARTED
[05:18:44.086] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:31:0) after 1m32s
[05:20:16.138] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:31:0)
[05:20:16.161] Build status: STARTED
[05:20:16.161] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:32:0) after 2m13s
[05:22:29.310] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:32:0)
[05:22:29.333] Build status: STARTED
[05:22:29.333] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:33:0) after 1m45s
[05:24:14.354] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:33:0)
[05:24:14.377] Build status: STARTED
[05:24:14.377] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:34:0) after 2m44s
[05:26:58.416] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:34:0)
[05:26:58.434] Build status: STARTED
[05:26:58.435] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:35:0) after 2m58s
[05:29:56.668] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:35:0)
[05:29:56.689] Build status: STARTED
[05:29:56.689] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:36:0) after 9m50s
[05:39:46.885] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:36:0)
[05:39:46.902] Build status: STARTED
[05:39:46.902] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:37:0) after 5m14s
[05:45:01.092] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:37:0)
[05:45:01.114] Build status: STARTED
[05:45:01.114] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:38:0) after 3m41s
[05:48:42.147] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:38:0)
[05:48:42.173] Build status: STARTED
[05:48:42.173] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:39:0) after 6m10s
[05:54:52.189] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:39:0)
[05:54:52.209] Build status: STARTED
[05:54:52.210] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:40:0) after 8m32s
[06:03:24.316] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:40:0)
[06:03:24.335] Build status: STARTED
[06:03:24.335] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:41:0) after 5m3s
[06:08:27.560] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:41:0)
[06:08:27.582] Build status: STARTED
[06:08:27.582] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:42:0) after 4m20s
[06:12:47.633] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:42:0)
[06:12:47.656] Build status: STARTED
[06:12:47.656] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:43:0) after 4m14s
[06:17:01.778] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:43:0)
[06:17:01.798] Build status: STARTED
[06:17:01.798] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:44:0) after 8m51s
[06:25:52.837] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:44:0)
[06:25:52.864] Build status: STARTED
[06:25:52.864] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:45:0) after 2m25s
[06:28:17.976] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:45:0)
[06:28:18.021] Build status: STARTED
[06:28:18.021] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:46:0) after 3m29s
[06:31:47.138] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:46:0)
[06:31:47.158] Build status: STARTED
[06:31:47.158] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:47:0) after 3m56s
[06:35:43.315] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:47:0)
[06:35:43.335] Build status: STARTED
[06:35:43.335] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:48:0) after 7m48s
[06:43:31.479] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:48:0)
[06:43:31.505] Build status: STARTED
[06:43:31.505] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:49:0) after 3m43s
[06:47:14.520] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:49:0)
[06:47:14.543] Build status: STARTED
[06:47:14.543] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:50:0) after 2m39s
[06:49:53.565] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:50:0)
[06:49:53.596] Build status: STARTED
[06:49:53.596] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:51:0) after 2m25s
[06:52:18.803] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:51:0)
[06:52:18.856] Build status: STARTED
[06:52:18.856] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8939521094261720896:52:0) after 3m52s
[06:53:32.672] Received PubSub notification, asking Buildbucket for the build status
[06:53:32.692] Build:
{
"id": "8774091031717728721",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "linux-official"
},
"number": 7888,
"createdBy": "project:chromium",
"createTime": "2023-07-31T02:49:15.448348284Z",
"startTime": "2023-07-31T02:49:55.772837Z",
"endTime": "2023-07-31T06:53:32.373150077Z",
"updateTime": "2023-07-31T06:53:32.373150077Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "641e514336b1e85eaa2d712deafac6adb35525a0",
"ref": "refs/heads/main"
}
}
}
[06:53:32.692] Invocation finished in 4h4m19.053989745s with status SUCCEEDED