[14:32:17.185] New invocation is queued and will start shortly
[14:32:18.416] Starting the invocation (attempt 1)
[14:32:18.445] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a400d12dce59d88e152ee51ed487829dd9f0beb8"
[14:32:18.445] Popped gitiles commit info from properties and tags
[14:32:18.445] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:32:18.445] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:32:18.445] Buildbucket request:
{
"requestId": "8950620288485757152",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Linux (dbg)"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "a400d12dce59d88e152ee51ed487829dd9f0beb8",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8950620288485757152"
},
{
"key": "scheduler_job_id",
"value": "chromium/Deterministic Linux (dbg)"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[14:32:18.960] Scheduled build:
{
"id": "8785190226434014305",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Linux (dbg)"
},
"number": 29874,
"createdBy": "project:chromium",
"createTime": "2023-03-30T14:32:18.514485103Z",
"updateTime": "2023-03-30T14:32:18.514485103Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "a400d12dce59d88e152ee51ed487829dd9f0beb8",
"ref": "refs/heads/main"
}
}
}
[14:32:18.961] Task URL: https://cr-buildbucket.appspot.com/build/8785190226434014305
[14:32:18.961] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:2:0) after 4m53s
[14:32:34.908] Received PubSub notification, asking Buildbucket for the build status
[14:32:34.940] Build status: STARTED
[14:37:12.107] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:2:0)
[14:37:12.127] Build status: STARTED
[14:37:12.127] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:4:0) after 4m33s
[14:41:45.325] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:4:0)
[14:41:45.361] Build status: STARTED
[14:41:45.361] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:5:0) after 6m45s
[14:48:30.440] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:5:0)
[14:48:30.501] Build status: STARTED
[14:48:30.501] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:6:0) after 4m29s
[14:52:59.568] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:6:0)
[14:52:59.588] Build status: STARTED
[14:52:59.588] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:7:0) after 1m39s
[14:54:38.619] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:7:0)
[14:54:38.643] Build status: STARTED
[14:54:38.643] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:8:0) after 2m49s
[14:57:27.664] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:8:0)
[14:57:27.686] Build status: STARTED
[14:57:27.686] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:9:0) after 9m42s
[15:07:09.719] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:9:0)
[15:07:09.748] Build status: STARTED
[15:07:09.748] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:10:0) after 1m26s
[15:08:35.750] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:10:0)
[15:08:35.769] Build status: STARTED
[15:08:35.769] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:11:0) after 4m12s
[15:12:47.944] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:11:0)
[15:12:47.975] Build status: STARTED
[15:12:47.975] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:12:0) after 3m54s
[15:16:41.997] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:12:0)
[15:16:42.018] Build status: STARTED
[15:16:42.018] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:13:0) after 5m30s
[15:22:12.518] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:13:0)
[15:22:12.588] Build status: STARTED
[15:22:12.588] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:14:0) after 6m2s
[15:28:14.719] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:14:0)
[15:28:14.741] Build status: STARTED
[15:28:14.741] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:15:0) after 2m25s
[15:30:39.918] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:15:0)
[15:30:39.951] Build status: STARTED
[15:30:39.951] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:16:0) after 8m26s
[15:39:06.182] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:16:0)
[15:39:06.227] Build status: STARTED
[15:39:06.227] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:17:0) after 4m53s
[15:43:59.338] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:17:0)
[15:43:59.359] Build status: STARTED
[15:43:59.359] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:18:0) after 5m8s
[15:49:07.391] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:18:0)
[15:49:07.447] Build status: STARTED
[15:49:07.447] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:19:0) after 2m20s
[15:51:27.484] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:19:0)
[15:51:27.505] Build status: STARTED
[15:51:27.505] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:20:0) after 3m45s
[15:55:12.557] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:20:0)
[15:55:12.583] Build status: STARTED
[15:55:12.583] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:21:0) after 4m4s
[15:59:16.997] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:21:0)
[15:59:17.020] Build status: STARTED
[15:59:17.020] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:22:0) after 5m26s
[16:04:43.044] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:22:0)
[16:04:43.066] Build status: STARTED
[16:04:43.066] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:23:0) after 9m22s
[16:14:05.091] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:23:0)
[16:14:05.116] Build status: STARTED
[16:14:05.116] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:24:0) after 9m46s
[16:23:51.692] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:24:0)
[16:23:51.738] Build status: STARTED
[16:23:51.738] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:25:0) after 4m44s
[16:28:35.970] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:25:0)
[16:28:35.992] Build status: STARTED
[16:28:35.993] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:26:0) after 6m26s
[16:35:02.347] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:26:0)
[16:35:02.376] Build status: STARTED
[16:35:02.376] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:27:0) after 8m15s
[16:43:17.440] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:27:0)
[16:43:17.458] Build status: STARTED
[16:43:17.458] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:28:0) after 3m33s
[16:46:50.479] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:28:0)
[16:46:50.516] Build status: STARTED
[16:46:50.516] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:29:0) after 7m5s
[16:53:55.727] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:29:0)
[16:53:55.751] Build status: STARTED
[16:53:55.751] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:30:0) after 9m35s
[17:03:30.788] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:30:0)
[17:03:30.811] Build status: STARTED
[17:03:30.811] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:31:0) after 9m51s
[17:13:21.785] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:31:0)
[17:13:21.861] Build status: STARTED
[17:13:21.862] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:32:0) after 7m59s
[17:21:20.909] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:32:0)
[17:21:20.938] Build status: STARTED
[17:21:20.938] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:33:0) after 2m24s
[17:23:45.006] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:33:0)
[17:23:45.054] Build status: STARTED
[17:23:45.054] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:34:0) after 5m23s
[17:29:08.085] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:34:0)
[17:29:08.122] Build status: STARTED
[17:29:08.122] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:35:0) after 7m25s
[17:36:33.143] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:35:0)
[17:36:33.163] Build status: STARTED
[17:36:33.163] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:36:0) after 2m41s
[17:39:14.307] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:36:0)
[17:39:14.348] Build status: STARTED
[17:39:14.348] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:37:0) after 3m9s
[17:42:23.468] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:37:0)
[17:42:23.488] Build status: STARTED
[17:42:23.488] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:38:0) after 5m5s
[17:47:28.695] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:38:0)
[17:47:28.725] Build status: STARTED
[17:47:28.725] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:39:0) after 6m5s
[17:53:33.756] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:39:0)
[17:53:33.811] Build status: STARTED
[17:53:33.811] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:40:0) after 1m40s
[17:55:14.029] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:40:0)
[17:55:14.050] Build status: STARTED
[17:55:14.050] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:41:0) after 8m14s
[18:03:28.598] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:41:0)
[18:03:28.624] Build status: STARTED
[18:03:28.624] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:42:0) after 6m41s
[18:10:09.911] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:42:0)
[18:10:09.939] Build status: STARTED
[18:10:09.939] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8950620288485757152:43:0) after 3m22s
[18:11:05.953] Received PubSub notification, asking Buildbucket for the build status
[18:11:05.977] Build:
{
"id": "8785190226434014305",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Linux (dbg)"
},
"number": 29874,
"createdBy": "project:chromium",
"createTime": "2023-03-30T14:32:18.514485103Z",
"startTime": "2023-03-30T14:32:34.047653Z",
"endTime": "2023-03-30T18:11:05.060800863Z",
"updateTime": "2023-03-30T18:11:05.060800863Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "a400d12dce59d88e152ee51ed487829dd9f0beb8",
"ref": "refs/heads/main"
}
}
}
[18:11:05.977] Invocation finished in 3h38m48.803353678s with status SUCCEEDED