[20:52:13.532] New invocation is queued and will start shortly
[20:52:14.663] Starting the invocation (attempt 1)
[20:52:14.711] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/082fe2c7354846206c3940dd621a26967fb5b0f5"
[20:52:14.711] Popped gitiles commit info from properties and tags
[20:52:14.711] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:52:14.712] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:52:14.712] Buildbucket request:
{
"requestId": "8982939501793657904",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "linux-official"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "082fe2c7354846206c3940dd621a26967fb5b0f5",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8982939501793657904"
},
{
"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"
}
}
[20:52:15.472] Scheduled build:
{
"id": "8817509439749729185",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "linux-official"
},
"number": 4718,
"createdBy": "project:chromium",
"createTime": "2022-04-07T20:52:14.849411349Z",
"updateTime": "2022-04-07T20:52:14.849411349Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "082fe2c7354846206c3940dd621a26967fb5b0f5",
"ref": "refs/heads/main"
}
}
}
[20:52:15.472] Task URL: https://cr-buildbucket.appspot.com/build/8817509439749729185
[20:52:15.472] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:2:0) after 3m50s
[20:52:20.679] Received PubSub notification, asking Buildbucket for the build status
[20:52:20.709] Build status: STARTED
[20:56:06.338] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:2:0)
[20:56:06.446] Build status: STARTED
[20:56:06.446] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:4:0) after 7m15s
[21:03:21.470] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:4:0)
[21:03:21.537] Build status: STARTED
[21:03:21.537] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:5:0) after 6m37s
[21:09:58.922] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:5:0)
[21:09:58.969] Build status: STARTED
[21:09:58.969] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:6:0) after 5m7s
[21:15:06.218] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:6:0)
[21:15:06.244] Build status: STARTED
[21:15:06.244] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:7:0) after 8m8s
[21:23:14.269] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:7:0)
[21:23:14.336] Build status: STARTED
[21:23:14.336] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:8:0) after 2m54s
[21:26:08.459] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:8:0)
[21:26:08.483] Build status: STARTED
[21:26:08.483] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:9:0) after 3m14s
[21:29:22.677] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:9:0)
[21:29:22.700] Build status: STARTED
[21:29:22.700] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:10:0) after 3m26s
[21:32:48.723] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:10:0)
[21:32:48.755] Build status: STARTED
[21:32:48.755] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:11:0) after 7m14s
[21:40:03.010] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:11:0)
[21:40:03.033] Build status: STARTED
[21:40:03.033] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:12:0) after 3m34s
[21:43:37.056] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:12:0)
[21:43:37.077] Build status: STARTED
[21:43:37.077] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:13:0) after 2m32s
[21:46:09.443] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:13:0)
[21:46:09.474] Build status: STARTED
[21:46:09.474] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:14:0) after 8m2s
[21:54:11.503] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:14:0)
[21:54:11.525] Build status: STARTED
[21:54:11.525] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:15:0) after 5m57s
[22:00:08.564] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:15:0)
[22:00:08.585] Build status: STARTED
[22:00:08.585] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:16:0) after 3m38s
[22:03:46.661] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:16:0)
[22:03:46.696] Build status: STARTED
[22:03:46.696] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:17:0) after 3m3s
[22:06:49.832] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:17:0)
[22:06:49.863] Build status: STARTED
[22:06:49.863] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:18:0) after 9m19s
[22:16:08.934] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:18:0)
[22:16:08.974] Build status: STARTED
[22:16:08.974] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:19:0) after 2m21s
[22:18:30.088] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:19:0)
[22:18:30.113] Build status: STARTED
[22:18:30.113] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:20:0) after 8m58s
[22:27:28.213] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:20:0)
[22:27:28.242] Build status: STARTED
[22:27:28.242] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:21:0) after 5m52s
[22:33:20.272] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:21:0)
[22:33:20.298] Build status: STARTED
[22:33:20.298] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:22:0) after 2m43s
[22:36:03.336] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:22:0)
[22:36:03.364] Build status: STARTED
[22:36:03.364] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:23:0) after 6m14s
[22:42:17.643] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:23:0)
[22:42:17.670] Build status: STARTED
[22:42:17.670] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:24:0) after 8m42s
[22:50:59.775] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:24:0)
[22:50:59.814] Build status: STARTED
[22:50:59.814] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:25:0) after 5m15s
[22:56:14.894] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:25:0)
[22:56:14.917] Build status: STARTED
[22:56:14.917] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:26:0) after 9m55s
[23:06:10.076] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:26:0)
[23:06:10.098] Build status: STARTED
[23:06:10.098] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:27:0) after 6m42s
[23:12:52.250] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:27:0)
[23:12:52.270] Build status: STARTED
[23:12:52.270] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:28:0) after 4m46s
[23:17:38.381] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:28:0)
[23:17:38.609] Build status: STARTED
[23:17:38.609] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:29:0) after 5m36s
[23:23:14.832] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:29:0)
[23:23:14.853] Build status: STARTED
[23:23:14.853] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:30:0) after 3m25s
[23:26:39.921] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:30:0)
[23:26:39.940] Build status: STARTED
[23:26:39.940] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:31:0) after 7m12s
[23:33:52.229] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:31:0)
[23:33:52.251] Build status: STARTED
[23:33:52.251] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:32:0) after 4m57s
[23:38:49.401] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:32:0)
[23:38:49.424] Build status: STARTED
[23:38:49.424] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:33:0) after 5m29s
[23:44:18.716] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:33:0)
[23:44:18.735] Build status: STARTED
[23:44:18.735] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:34:0) after 9m4s
[23:53:22.860] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:34:0)
[23:53:22.890] Build status: STARTED
[23:53:22.890] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:35:0) after 3m33s
[23:56:55.911] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:35:0)
[23:56:55.932] Build status: STARTED
[23:56:55.932] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:36:0) after 5m15s
[00:02:10.999] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:36:0)
[00:02:11.018] Build status: STARTED
[00:02:11.018] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:37:0) after 2m31s
[00:04:42.181] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:37:0)
[00:04:42.209] Build status: STARTED
[00:04:42.209] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:38:0) after 9m25s
[00:14:07.261] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:38:0)
[00:14:07.290] Build status: STARTED
[00:14:07.291] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:39:0) after 1m19s
[00:15:26.322] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:39:0)
[00:15:26.350] Build status: STARTED
[00:15:26.350] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:40:0) after 9m39s
[00:25:05.374] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:40:0)
[00:25:05.396] Build status: STARTED
[00:25:05.396] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:41:0) after 3m2s
[00:28:07.436] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:41:0)
[00:28:08.366] Build status: STARTED
[00:28:08.366] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:42:0) after 4m59s
[00:33:07.390] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:42:0)
[00:33:07.408] Build status: STARTED
[00:33:07.408] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:43:0) after 7m23s
[00:40:30.443] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:43:0)
[00:40:30.468] Build status: STARTED
[00:40:30.468] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:44:0) after 3m31s
[00:44:01.507] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:44:0)
[00:44:01.528] Build status: STARTED
[00:44:01.528] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:45:0) after 3m19s
[00:47:20.603] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:45:0)
[00:47:20.634] Build status: STARTED
[00:47:20.634] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:46:0) after 9m44s
[00:57:04.713] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:46:0)
[00:57:04.736] Build status: STARTED
[00:57:04.736] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:47:0) after 4m59s
[01:02:03.788] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:47:0)
[01:02:03.822] Build status: STARTED
[01:02:03.822] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:48:0) after 6m23s
[01:08:26.903] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:48:0)
[01:08:26.923] Build status: STARTED
[01:08:26.923] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:49:0) after 7m17s
[01:15:44.062] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:49:0)
[01:15:44.106] Build status: STARTED
[01:15:44.106] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:50:0) after 9m13s
[01:24:57.379] Handling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:50:0)
[01:24:57.404] Build status: STARTED
[01:24:57.404] Scheduling timer "check-buildbucket-build-status" (chromium/linux-official:8982939501793657904:51:0) after 6m25s
[01:31:05.703] Received PubSub notification, asking Buildbucket for the build status
[01:31:05.723] Build:
{
"id": "8817509439749729185",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "linux-official"
},
"number": 4718,
"createdBy": "project:chromium",
"createTime": "2022-04-07T20:52:14.849411349Z",
"startTime": "2022-04-07T20:52:20.067040Z",
"endTime": "2022-04-08T01:31:04.924383412Z",
"updateTime": "2022-04-08T01:31:04.924383412Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "082fe2c7354846206c3940dd621a26967fb5b0f5",
"ref": "refs/heads/main"
}
}
}
[01:31:05.724] Invocation finished in 4h38m52.207816332s with status SUCCEEDED