[20:24:07.459] New invocation is queued and will start shortly
[20:24:08.551] Starting the invocation (attempt 1)
[20:24:08.582] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0c49d13d4a7888cea1a6b7d8706e483066ce81c9"
[20:24:08.582] Popped gitiles commit info from properties and tags
[20:24:08.582] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:24:08.583] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:24:08.583] Buildbucket request:
{
"requestId": "8925140405199083664",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Linux (dbg)"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "0c49d13d4a7888cea1a6b7d8706e483066ce81c9",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8925140405199083664"
},
{
"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"
}
}
[20:24:09.230] Scheduled build:
{
"id": "8759710343198419025",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Linux (dbg)"
},
"number": 32610,
"createdBy": "project:chromium",
"createTime": "2024-01-05T20:24:08.741130039Z",
"updateTime": "2024-01-05T20:24:08.741130039Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "0c49d13d4a7888cea1a6b7d8706e483066ce81c9",
"ref": "refs/heads/main"
}
}
}
[20:24:09.230] Task URL: https://cr-buildbucket.appspot.com/build/8759710343198419025
[20:24:09.230] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8925140405199083664:2:0) after 5m57s
[20:24:10.145] Received PubSub notification, asking Buildbucket for the build status
[20:24:10.171] Build status: SCHEDULED
[20:25:19.391] Received PubSub notification, asking Buildbucket for the build status
[20:25:19.419] Build status: STARTED
[20:30:06.272] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8925140405199083664:2:0)
[20:30:06.378] Build status: STARTED
[20:30:06.378] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8925140405199083664:5:0) after 7m35s
[20:37:41.413] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8925140405199083664:5:0)
[20:37:41.454] Build status: STARTED
[20:37:41.454] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8925140405199083664:6:0) after 7m34s
[20:45:15.521] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8925140405199083664:6:0)
[20:45:15.550] Build status: STARTED
[20:45:15.550] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8925140405199083664:7:0) after 3m41s
[20:48:56.715] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8925140405199083664:7:0)
[20:48:56.806] Build status: STARTED
[20:48:56.806] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8925140405199083664:8:0) after 4m41s
[20:53:37.885] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8925140405199083664:8:0)
[20:53:37.910] Build status: STARTED
[20:53:37.910] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8925140405199083664:9:0) after 3m49s
[20:57:27.451] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8925140405199083664:9:0)
[20:57:27.516] Build status: STARTED
[20:57:27.516] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8925140405199083664:10:0) after 6m34s
[21:04:01.532] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8925140405199083664:10:0)
[21:04:01.547] Build status: STARTED
[21:04:01.547] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8925140405199083664:11:0) after 7m23s
[21:11:24.575] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8925140405199083664:11:0)
[21:11:24.702] Build status: STARTED
[21:11:24.702] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8925140405199083664:12:0) after 4m30s
[21:15:54.788] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8925140405199083664:12:0)
[21:15:54.823] Build status: STARTED
[21:15:54.823] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8925140405199083664:13:0) after 4m8s
[21:20:03.088] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8925140405199083664:13:0)
[21:20:03.112] Build status: STARTED
[21:20:03.112] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8925140405199083664:14:0) after 2m21s
[21:22:24.131] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8925140405199083664:14:0)
[21:22:24.149] Build status: STARTED
[21:22:24.149] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8925140405199083664:15:0) after 1m14s
[21:23:38.184] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8925140405199083664:15:0)
[21:23:38.222] Build status: STARTED
[21:23:38.222] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8925140405199083664:16:0) after 9m45s
[21:33:23.239] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8925140405199083664:16:0)
[21:33:23.317] Build status: STARTED
[21:33:23.317] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8925140405199083664:17:0) after 6m6s
[21:39:29.352] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8925140405199083664:17:0)
[21:39:29.409] Build status: STARTED
[21:39:29.409] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8925140405199083664:18:0) after 9m51s
[21:49:20.425] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8925140405199083664:18:0)
[21:49:20.450] Build status: STARTED
[21:49:20.450] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8925140405199083664:19:0) after 3m42s
[21:53:02.486] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8925140405199083664:19:0)
[21:53:02.607] Build status: STARTED
[21:53:02.607] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8925140405199083664:20:0) after 3m28s
[21:56:30.626] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8925140405199083664:20:0)
[21:56:30.647] Build status: STARTED
[21:56:30.647] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8925140405199083664:21:0) after 9m15s
[22:05:46.417] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8925140405199083664:21:0)
[22:05:46.473] Build status: STARTED
[22:05:46.473] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8925140405199083664:22:0) after 5m22s
[22:11:08.529] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8925140405199083664:22:0)
[22:11:08.547] Build status: STARTED
[22:11:08.547] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8925140405199083664:23:0) after 9m32s
[22:20:41.436] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8925140405199083664:23:0)
[22:20:41.456] Build status: STARTED
[22:20:41.456] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8925140405199083664:24:0) after 3m55s
[22:24:37.101] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8925140405199083664:24:0)
[22:24:37.120] Build status: STARTED
[22:24:37.121] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8925140405199083664:25:0) after 8m29s
[22:33:06.208] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8925140405199083664:25:0)
[22:33:06.229] Build status: STARTED
[22:33:06.229] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8925140405199083664:26:0) after 6m24s
[22:39:30.442] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8925140405199083664:26:0)
[22:39:30.468] Build status: STARTED
[22:39:30.469] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8925140405199083664:27:0) after 4m9s
[22:43:39.499] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8925140405199083664:27:0)
[22:43:39.522] Build status: STARTED
[22:43:39.522] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8925140405199083664:28:0) after 9m32s
[22:53:11.546] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8925140405199083664:28:0)
[22:53:11.562] Build status: STARTED
[22:53:11.562] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8925140405199083664:29:0) after 6m4s
[22:59:15.588] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8925140405199083664:29:0)
[22:59:15.608] Build status: STARTED
[22:59:15.608] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8925140405199083664:30:0) after 5m22s
[23:04:37.637] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8925140405199083664:30:0)
[23:04:37.656] Build status: STARTED
[23:04:37.656] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8925140405199083664:31:0) after 2m48s
[23:07:25.840] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8925140405199083664:31:0)
[23:07:25.873] Build status: STARTED
[23:07:25.873] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8925140405199083664:32:0) after 3m13s
[23:10:38.921] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8925140405199083664:32:0)
[23:10:38.944] Build status: STARTED
[23:10:38.944] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8925140405199083664:33:0) after 8m37s
[23:19:16.177] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8925140405199083664:33:0)
[23:19:16.198] Build status: STARTED
[23:19:16.198] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8925140405199083664:34:0) after 5m44s
[23:25:00.326] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8925140405199083664:34:0)
[23:25:00.349] Build status: STARTED
[23:25:00.349] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8925140405199083664:35:0) after 4m27s
[23:29:27.474] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8925140405199083664:35:0)
[23:29:27.493] Build status: STARTED
[23:29:27.493] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8925140405199083664:36:0) after 4m19s
[23:33:46.618] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8925140405199083664:36:0)
[23:33:46.637] Build status: STARTED
[23:33:46.638] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8925140405199083664:37:0) after 5m53s
[23:37:21.614] Received PubSub notification, asking Buildbucket for the build status
[23:37:21.636] Build:
{
"id": "8759710343198419025",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Linux (dbg)"
},
"number": 32610,
"createdBy": "project:chromium",
"createTime": "2024-01-05T20:24:08.741130039Z",
"startTime": "2024-01-05T20:25:15.950323Z",
"endTime": "2024-01-05T23:37:20.883613Z",
"updateTime": "2024-01-05T23:37:20.883613Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "0c49d13d4a7888cea1a6b7d8706e483066ce81c9",
"ref": "refs/heads/main"
}
}
}
[23:37:21.636] Invocation finished in 3h13m14.187409043s with status FAILED