[18:24:24.660] New invocation is queued and will start shortly
[18:24:25.859] Starting the invocation (attempt 1)
[18:24:25.889] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a6b06277cb02d22c6a9be49a9236ccabfcf8a9cf"
[18:24:25.889] Popped gitiles commit info from properties and tags
[18:24:25.889] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:24:25.889] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:24:25.889] Buildbucket request:
{
"requestId": "8931399127595581040",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Linux CFI"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "a6b06277cb02d22c6a9be49a9236ccabfcf8a9cf",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8931399127595581040"
},
{
"key": "scheduler_job_id",
"value": "chromium/Linux CFI"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[18:24:26.597] Scheduled build:
{
"id": "8765969065591973249",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Linux CFI"
},
"number": 26326,
"createdBy": "project:chromium",
"createTime": "2023-10-28T18:24:25.941373011Z",
"updateTime": "2023-10-28T18:24:25.941373011Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "a6b06277cb02d22c6a9be49a9236ccabfcf8a9cf",
"ref": "refs/heads/main"
}
}
}
[18:24:26.597] Task URL: https://cr-buildbucket.appspot.com/build/8765969065591973249
[18:24:26.597] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:2:0) after 2m56s
[18:24:27.415] Received PubSub notification, asking Buildbucket for the build status
[18:24:27.432] Build status: SCHEDULED
[18:24:28.353] Received PubSub notification, asking Buildbucket for the build status
[18:24:28.385] Build status: STARTED
[18:27:22.609] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:2:0)
[18:27:22.645] Build status: STARTED
[18:27:22.645] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:5:0) after 6m1s
[18:33:23.694] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:5:0)
[18:33:23.729] Build status: STARTED
[18:33:23.729] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:6:0) after 8m43s
[18:42:06.817] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:6:0)
[18:42:06.838] Build status: STARTED
[18:42:06.838] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:7:0) after 4m53s
[18:46:59.855] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:7:0)
[18:46:59.877] Build status: STARTED
[18:46:59.877] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:8:0) after 6m42s
[18:53:41.990] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:8:0)
[18:53:42.012] Build status: STARTED
[18:53:42.012] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:9:0) after 9m42s
[19:03:23.999] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:9:0)
[19:03:24.044] Build status: STARTED
[19:03:24.044] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:10:0) after 2m44s
[19:06:08.059] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:10:0)
[19:06:08.079] Build status: STARTED
[19:06:08.079] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:11:0) after 1m58s
[19:08:06.125] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:11:0)
[19:08:06.157] Build status: STARTED
[19:08:06.157] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:12:0) after 5m1s
[19:13:07.175] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:12:0)
[19:13:07.201] Build status: STARTED
[19:13:07.201] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:13:0) after 9m50s
[19:22:57.540] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:13:0)
[19:22:57.560] Build status: STARTED
[19:22:57.560] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:14:0) after 9m11s
[19:32:08.640] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:14:0)
[19:32:08.656] Build status: STARTED
[19:32:08.656] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:15:0) after 7m3s
[19:39:11.740] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:15:0)
[19:39:11.788] Build status: STARTED
[19:39:11.788] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:16:0) after 4m32s
[19:43:43.808] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:16:0)
[19:43:43.840] Build status: STARTED
[19:43:43.840] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:17:0) after 2m21s
[19:46:04.846] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:17:0)
[19:46:04.866] Build status: STARTED
[19:46:04.866] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:18:0) after 3m38s
[19:49:43.027] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:18:0)
[19:49:43.091] Build status: STARTED
[19:49:43.091] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:19:0) after 3m32s
[19:53:15.287] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:19:0)
[19:53:15.303] Build status: STARTED
[19:53:15.303] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:20:0) after 4m35s
[19:57:50.619] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:20:0)
[19:57:50.640] Build status: STARTED
[19:57:50.640] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:21:0) after 8m46s
[20:06:36.823] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:21:0)
[20:06:36.850] Build status: STARTED
[20:06:36.850] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:22:0) after 6m20s
[20:12:56.874] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:22:0)
[20:12:56.894] Build status: STARTED
[20:12:56.894] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:23:0) after 1m48s
[20:14:45.121] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:23:0)
[20:14:45.144] Build status: STARTED
[20:14:45.144] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:24:0) after 2m46s
[20:17:31.298] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:24:0)
[20:17:31.321] Build status: STARTED
[20:17:31.321] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:25:0) after 9m15s
[20:26:46.380] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:25:0)
[20:26:46.401] Build status: STARTED
[20:26:46.401] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:26:0) after 3m47s
[20:30:33.418] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:26:0)
[20:30:33.452] Build status: STARTED
[20:30:33.452] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:27:0) after 3m53s
[20:34:26.574] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:27:0)
[20:34:26.597] Build status: STARTED
[20:34:26.597] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:28:0) after 5m10s
[20:39:37.013] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:28:0)
[20:39:37.031] Build status: STARTED
[20:39:37.031] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:29:0) after 4m27s
[20:44:04.120] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:29:0)
[20:44:04.165] Build status: STARTED
[20:44:04.165] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:30:0) after 7m56s
[20:52:00.184] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:30:0)
[20:52:00.201] Build status: STARTED
[20:52:00.201] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:31:0) after 5m21s
[20:57:21.175] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:31:0)
[20:57:21.196] Build status: STARTED
[20:57:21.196] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:32:0) after 2m17s
[20:59:38.267] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:32:0)
[20:59:38.285] Build status: STARTED
[20:59:38.285] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:33:0) after 7m27s
[21:07:05.314] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:33:0)
[21:07:05.339] Build status: STARTED
[21:07:05.339] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:34:0) after 4m7s
[21:11:12.357] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:34:0)
[21:11:12.374] Build status: STARTED
[21:11:12.374] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:35:0) after 3m51s
[21:15:03.428] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:35:0)
[21:15:03.445] Build status: STARTED
[21:15:03.445] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:36:0) after 3m40s
[21:18:43.466] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:36:0)
[21:18:43.482] Build status: STARTED
[21:18:43.482] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:37:0) after 9m34s
[21:28:17.505] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:37:0)
[21:28:17.523] Build status: STARTED
[21:28:17.523] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:38:0) after 1m33s
[21:29:50.641] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:38:0)
[21:29:50.659] Build status: STARTED
[21:29:50.659] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:39:0) after 3m15s
[21:33:05.763] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:39:0)
[21:33:05.798] Build status: STARTED
[21:33:05.798] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:40:0) after 9m13s
[21:42:19.105] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:40:0)
[21:42:19.135] Build status: STARTED
[21:42:19.136] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:41:0) after 3m2s
[21:45:21.153] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:41:0)
[21:45:21.306] Build status: STARTED
[21:45:21.306] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:42:0) after 2m7s
[21:47:28.307] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:42:0)
[21:47:28.378] Build status: STARTED
[21:47:28.378] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:43:0) after 2m0s
[21:49:28.395] Handling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:43:0)
[21:49:28.420] Build status: STARTED
[21:49:28.420] Scheduling timer "check-buildbucket-build-status" (chromium/Linux CFI:8931399127595581040:44:0) after 9m44s
[21:53:21.661] Received PubSub notification, asking Buildbucket for the build status
[21:53:21.693] Build:
{
"id": "8765969065591973249",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Linux CFI"
},
"number": 26326,
"createdBy": "project:chromium",
"createTime": "2023-10-28T18:24:25.941373011Z",
"startTime": "2023-10-28T18:24:27.527582Z",
"endTime": "2023-10-28T21:53:21.351167304Z",
"updateTime": "2023-10-28T21:53:21.351167304Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "a6b06277cb02d22c6a9be49a9236ccabfcf8a9cf",
"ref": "refs/heads/main"
}
}
}
[21:53:21.693] Invocation finished in 3h28m57.047044403s with status SUCCEEDED