[03:15:51.306] New invocation is queued and will start shortly
[03:15:56.341] Starting the invocation (attempt 1)
[03:15:56.372] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a3497c9a9b521f06e732b1461f39d56786647abc"
[03:15:56.372] Popped gitiles commit info from properties and tags
[03:15:56.372] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:15:56.372] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:15:56.372] Buildbucket request:
{
"requestId": "8959813139473597184",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTiOS"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "a3497c9a9b521f06e732b1461f39d56786647abc",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8959813139473597184"
},
{
"key": "scheduler_job_id",
"value": "chromium/ToTiOS"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[03:15:56.780] Scheduled build:
{
"id": "8794383073432480337",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTiOS"
},
"number": 24612,
"createdBy": "project:chromium",
"createTime": "2022-12-19T03:15:56.437698241Z",
"updateTime": "2022-12-19T03:15:56.437698241Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "a3497c9a9b521f06e732b1461f39d56786647abc",
"ref": "refs/heads/main"
}
}
}
[03:15:56.780] Task URL: https://cr-buildbucket.appspot.com/build/8794383073432480337
[03:15:56.780] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:2:0) after 8m51s
[03:18:15.437] Received PubSub notification, asking Buildbucket for the build status
[03:18:15.462] Build status: STARTED
[03:24:47.799] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:2:0)
[03:24:47.819] Build status: STARTED
[03:24:47.819] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:4:0) after 2m21s
[03:27:08.838] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:4:0)
[03:27:08.862] Build status: STARTED
[03:27:08.862] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:5:0) after 9m14s
[03:36:22.900] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:5:0)
[03:36:22.935] Build status: STARTED
[03:36:22.935] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:6:0) after 6m22s
[03:42:44.973] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:6:0)
[03:42:44.994] Build status: STARTED
[03:42:44.994] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:7:0) after 8m1s
[03:50:46.119] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:7:0)
[03:50:46.149] Build status: STARTED
[03:50:46.149] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:8:0) after 8m2s
[03:58:48.208] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:8:0)
[03:58:48.237] Build status: STARTED
[03:58:48.237] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:9:0) after 5m12s
[04:04:00.297] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:9:0)
[04:04:00.330] Build status: STARTED
[04:04:00.330] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:10:0) after 4m13s
[04:08:13.759] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:10:0)
[04:08:13.781] Build status: STARTED
[04:08:13.781] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:11:0) after 5m0s
[04:13:13.801] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:11:0)
[04:13:13.823] Build status: STARTED
[04:13:13.823] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:12:0) after 6m7s
[04:19:21.172] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:12:0)
[04:19:21.195] Build status: STARTED
[04:19:21.195] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:13:0) after 8m25s
[04:27:46.220] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:13:0)
[04:27:46.241] Build status: STARTED
[04:27:46.241] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:14:0) after 1m48s
[04:29:34.246] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:14:0)
[04:29:34.268] Build status: STARTED
[04:29:34.268] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:15:0) after 2m9s
[04:31:43.395] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:15:0)
[04:31:43.416] Build status: STARTED
[04:31:43.416] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:16:0) after 8m25s
[04:40:08.435] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:16:0)
[04:40:08.473] Build status: STARTED
[04:40:08.473] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:17:0) after 8m41s
[04:48:49.510] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:17:0)
[04:48:49.535] Build status: STARTED
[04:48:49.535] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:18:0) after 1m5s
[04:49:54.662] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:18:0)
[04:49:54.687] Build status: STARTED
[04:49:54.687] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:19:0) after 6m23s
[04:56:17.723] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:19:0)
[04:56:17.750] Build status: STARTED
[04:56:17.750] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:20:0) after 4m21s
[05:00:38.772] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:20:0)
[05:00:38.878] Build status: STARTED
[05:00:38.878] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:21:0) after 8m33s
[05:09:12.116] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:21:0)
[05:09:12.141] Build status: STARTED
[05:09:12.141] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:22:0) after 8m40s
[05:17:52.261] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:22:0)
[05:17:52.308] Build status: STARTED
[05:17:52.308] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:23:0) after 9m31s
[05:27:23.657] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:23:0)
[05:27:23.695] Build status: STARTED
[05:27:23.695] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:24:0) after 9m20s
[05:36:43.697] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:24:0)
[05:36:43.722] Build status: STARTED
[05:36:43.722] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:25:0) after 9m17s
[05:46:00.740] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:25:0)
[05:46:00.764] Build status: STARTED
[05:46:00.764] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:26:0) after 3m22s
[05:49:22.779] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:26:0)
[05:49:22.810] Build status: STARTED
[05:49:22.810] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:27:0) after 6m34s
[05:55:57.002] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:27:0)
[05:55:57.024] Build status: STARTED
[05:55:57.024] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:28:0) after 6m37s
[06:02:34.051] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:28:0)
[06:02:34.074] Build status: STARTED
[06:02:34.074] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:29:0) after 7m52s
[06:10:26.103] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:29:0)
[06:10:26.130] Build status: STARTED
[06:10:26.130] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:30:0) after 2m12s
[06:12:38.400] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:30:0)
[06:12:38.425] Build status: STARTED
[06:12:38.425] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:31:0) after 6m31s
[06:19:09.443] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:31:0)
[06:19:09.461] Build status: STARTED
[06:19:09.461] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:32:0) after 2m20s
[06:21:29.582] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:32:0)
[06:21:29.646] Build status: STARTED
[06:21:29.646] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:33:0) after 5m24s
[06:26:53.736] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:33:0)
[06:26:53.761] Build status: STARTED
[06:26:53.761] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:34:0) after 2m8s
[06:29:01.849] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:34:0)
[06:29:01.893] Build status: STARTED
[06:29:01.894] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:35:0) after 8m50s
[06:37:51.871] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:35:0)
[06:37:51.892] Build status: STARTED
[06:37:51.892] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:36:0) after 2m32s
[06:40:23.910] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:36:0)
[06:40:23.933] Build status: STARTED
[06:40:23.933] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:37:0) after 5m28s
[06:45:51.959] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:37:0)
[06:45:51.982] Build status: STARTED
[06:45:51.982] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:38:0) after 9m57s
[06:55:49.140] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:38:0)
[06:55:49.171] Build status: STARTED
[06:55:49.171] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:39:0) after 3m9s
[06:58:58.193] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:39:0)
[06:58:58.226] Build status: STARTED
[06:58:58.226] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:40:0) after 9m7s
[07:08:05.251] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:40:0)
[07:08:05.273] Build status: STARTED
[07:08:05.273] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:41:0) after 2m30s
[07:10:35.367] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:41:0)
[07:10:35.391] Build status: STARTED
[07:10:35.391] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:42:0) after 2m10s
[07:12:45.853] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:42:0)
[07:12:45.880] Build status: STARTED
[07:12:45.880] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:43:0) after 8m45s
[07:21:30.965] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:43:0)
[07:21:30.992] Build status: STARTED
[07:21:30.992] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:44:0) after 6m7s
[07:27:38.015] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:44:0)
[07:27:38.041] Build status: STARTED
[07:27:38.041] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:45:0) after 3m10s
[07:30:48.065] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:45:0)
[07:30:48.086] Build status: STARTED
[07:30:48.086] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:46:0) after 5m53s
[07:36:41.162] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:46:0)
[07:36:41.187] Build status: STARTED
[07:36:41.187] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:47:0) after 2m32s
[07:39:13.311] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:47:0)
[07:39:13.338] Build status: STARTED
[07:39:13.338] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:48:0) after 2m46s
[07:41:59.359] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:48:0)
[07:41:59.382] Build status: STARTED
[07:41:59.382] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:49:0) after 9m48s
[07:51:47.409] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:49:0)
[07:51:47.437] Build status: STARTED
[07:51:47.437] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:50:0) after 6m0s
[07:57:47.459] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:50:0)
[07:57:47.481] Build status: STARTED
[07:57:47.481] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:51:0) after 9m57s
[08:07:44.510] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:51:0)
[08:07:44.536] Build status: STARTED
[08:07:44.536] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:52:0) after 5m47s
[08:13:31.559] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:52:0)
[08:13:31.616] Build status: STARTED
[08:13:31.616] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:53:0) after 1m4s
[08:14:35.638] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:53:0)
[08:14:35.661] Build status: STARTED
[08:14:35.662] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:54:0) after 3m3s
[08:17:38.696] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:54:0)
[08:17:38.719] Build status: STARTED
[08:17:38.719] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:55:0) after 2m18s
[08:19:56.720] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:55:0)
[08:19:56.744] Build status: STARTED
[08:19:56.744] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:56:0) after 9m10s
[08:29:06.881] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:56:0)
[08:29:06.919] Build status: STARTED
[08:29:06.919] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:57:0) after 9m41s
[08:38:48.058] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:57:0)
[08:38:48.088] Build status: STARTED
[08:38:48.088] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:58:0) after 9m56s
[08:48:44.251] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:58:0)
[08:48:44.270] Build status: STARTED
[08:48:44.270] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:59:0) after 9m17s
[08:58:01.289] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:59:0)
[08:58:01.313] Build status: STARTED
[08:58:01.313] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:60:0) after 9m44s
[09:07:45.616] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:60:0)
[09:07:45.640] Build status: STARTED
[09:07:45.640] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:61:0) after 4m10s
[09:11:55.892] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:61:0)
[09:11:55.916] Build status: STARTED
[09:11:55.916] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:62:0) after 9m39s
[09:21:35.062] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:62:0)
[09:21:35.257] Build status: STARTED
[09:21:35.257] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:63:0) after 7m59s
[09:29:34.368] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:63:0)
[09:29:34.410] Build status: STARTED
[09:29:34.410] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:64:0) after 4m37s
[09:34:11.438] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:64:0)
[09:34:11.489] Build status: STARTED
[09:34:11.489] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:65:0) after 7m34s
[09:41:45.511] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:65:0)
[09:41:45.591] Build status: STARTED
[09:41:45.591] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8959813139473597184:66:0) after 8m52s
[09:46:37.576] Received PubSub notification, asking Buildbucket for the build status
[09:46:37.600] Build:
{
"id": "8794383073432480337",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTiOS"
},
"number": 24612,
"createdBy": "project:chromium",
"createTime": "2022-12-19T03:15:56.437698241Z",
"startTime": "2022-12-19T03:18:14.918782Z",
"endTime": "2022-12-19T09:46:37.395985933Z",
"updateTime": "2022-12-19T09:46:37.395985933Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "a3497c9a9b521f06e732b1461f39d56786647abc",
"ref": "refs/heads/main"
}
}
}
[09:46:37.600] Invocation finished in 6h30m46.308208898s with status SUCCEEDED