[11:53:49.019] New invocation is queued and will start shortly
[11:53:50.393] Starting the invocation (attempt 1)
[11:53:50.429] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d0745866ae7b7e3b8b81a2b7d5e660fcca9e33d5"
[11:53:50.429] Popped gitiles commit info from properties and tags
[11:53:50.429] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:53:50.430] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:53:50.430] Buildbucket request:
{
"requestId": "9000730380945297024",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTiOS"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "d0745866ae7b7e3b8b81a2b7d5e660fcca9e33d5",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "9000730380945297024"
},
{
"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"
}
}
[11:53:51.057] Scheduled build:
{
"id": "8835300318714488417",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTiOS"
},
"number": 19425,
"createdBy": "project:chromium",
"createTime": "2021-09-23T11:53:50.514084696Z",
"updateTime": "2021-09-23T11:53:50.895056521Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "d0745866ae7b7e3b8b81a2b7d5e660fcca9e33d5",
"ref": "refs/heads/main"
}
}
}
[11:53:51.057] Task URL: https://cr-buildbucket.appspot.com/build/8835300318714488417
[11:53:51.057] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:2:0) after 3m43s
[11:55:22.926] Received PubSub notification, asking Buildbucket for the build status
[11:55:22.957] Build status: STARTED
[11:57:34.116] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:2:0)
[11:57:34.149] Build status: STARTED
[11:57:34.149] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:4:0) after 5m9s
[12:02:43.169] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:4:0)
[12:02:43.200] Build status: STARTED
[12:02:43.200] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:5:0) after 4m38s
[12:07:21.187] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:5:0)
[12:07:21.218] Build status: STARTED
[12:07:21.218] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:6:0) after 8m8s
[12:15:29.236] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:6:0)
[12:15:29.268] Build status: STARTED
[12:15:29.268] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:7:0) after 9m22s
[12:24:51.302] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:7:0)
[12:24:51.459] Build status: STARTED
[12:24:51.459] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:8:0) after 6m1s
[12:30:52.465] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:8:0)
[12:30:52.497] Build status: STARTED
[12:30:52.497] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:9:0) after 8m59s
[12:39:51.526] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:9:0)
[12:39:51.561] Build status: STARTED
[12:39:51.561] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:10:0) after 3m46s
[12:43:37.628] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:10:0)
[12:43:37.953] Build status: STARTED
[12:43:37.953] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:11:0) after 4m8s
[12:47:46.142] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:11:0)
[12:47:46.170] Build status: STARTED
[12:47:46.171] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:12:0) after 1m15s
[12:49:01.364] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:12:0)
[12:49:01.402] Build status: STARTED
[12:49:01.402] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:13:0) after 1m13s
[12:50:14.602] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:13:0)
[12:50:14.632] Build status: STARTED
[12:50:14.632] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:14:0) after 9m32s
[12:59:46.986] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:14:0)
[12:59:47.028] Build status: STARTED
[12:59:47.028] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:15:0) after 9m28s
[13:09:15.048] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:15:0)
[13:09:15.078] Build status: STARTED
[13:09:15.078] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:16:0) after 8m17s
[13:17:32.129] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:16:0)
[13:17:32.164] Build status: STARTED
[13:17:32.164] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:17:0) after 9m41s
[13:27:13.289] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:17:0)
[13:27:13.317] Build status: STARTED
[13:27:13.317] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:18:0) after 8m19s
[13:35:32.365] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:18:0)
[13:35:32.392] Build status: STARTED
[13:35:32.392] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:19:0) after 3m5s
[13:38:37.434] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:19:0)
[13:38:37.467] Build status: STARTED
[13:38:37.467] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:20:0) after 1m49s
[13:40:26.698] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:20:0)
[13:40:26.763] Build status: STARTED
[13:40:26.763] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:21:0) after 4m25s
[13:44:51.819] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:21:0)
[13:44:51.863] Build status: STARTED
[13:44:51.863] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:22:0) after 5m13s
[13:50:05.108] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:22:0)
[13:50:05.164] Build status: STARTED
[13:50:05.164] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:23:0) after 1m15s
[13:51:20.290] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:23:0)
[13:51:20.330] Build status: STARTED
[13:51:20.331] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:24:0) after 8m28s
[13:59:48.461] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:24:0)
[13:59:48.508] Build status: STARTED
[13:59:48.508] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:25:0) after 2m52s
[14:02:40.768] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:25:0)
[14:02:40.819] Build status: STARTED
[14:02:40.819] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:26:0) after 4m22s
[14:07:02.846] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:26:0)
[14:07:03.062] Build status: STARTED
[14:07:03.062] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:27:0) after 7m9s
[14:14:12.135] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:27:0)
[14:14:12.178] Build status: STARTED
[14:14:12.178] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:28:0) after 5m32s
[14:19:44.344] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:28:0)
[14:19:44.381] Build status: STARTED
[14:19:44.381] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:29:0) after 1m28s
[14:21:12.504] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:29:0)
[14:21:12.535] Build status: STARTED
[14:21:12.535] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:30:0) after 9m24s
[14:30:36.649] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:30:0)
[14:30:36.678] Build status: STARTED
[14:30:36.678] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:31:0) after 6m46s
[14:37:23.264] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:31:0)
[14:37:23.307] Build status: STARTED
[14:37:23.307] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:32:0) after 5m25s
[14:42:48.324] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:32:0)
[14:42:48.355] Build status: STARTED
[14:42:48.355] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:33:0) after 6m15s
[14:49:03.456] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:33:0)
[14:49:03.509] Build status: STARTED
[14:49:03.509] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:34:0) after 1m23s
[14:50:26.532] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:34:0)
[14:50:26.581] Build status: STARTED
[14:50:26.581] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:35:0) after 6m25s
[14:56:51.679] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:35:0)
[14:56:51.710] Build status: STARTED
[14:56:51.710] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:36:0) after 3m37s
[15:00:28.833] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:36:0)
[15:00:28.869] Build status: STARTED
[15:00:28.869] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:37:0) after 8m41s
[15:09:09.960] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:37:0)
[15:09:09.994] Build status: STARTED
[15:09:09.994] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:38:0) after 6m32s
[15:15:42.100] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:38:0)
[15:15:42.146] Build status: STARTED
[15:15:42.146] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:39:0) after 5m56s
[15:21:38.265] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:39:0)
[15:21:38.310] Build status: STARTED
[15:21:38.310] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:40:0) after 4m51s
[15:26:30.039] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:40:0)
[15:26:30.071] Build status: STARTED
[15:26:30.071] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:41:0) after 6m38s
[15:33:08.093] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:41:0)
[15:33:08.151] Build status: STARTED
[15:33:08.151] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:42:0) after 4m50s
[15:37:58.275] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:42:0)
[15:37:58.303] Build status: STARTED
[15:37:58.303] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:43:0) after 4m46s
[15:42:44.327] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:43:0)
[15:42:44.376] Build status: STARTED
[15:42:44.376] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:44:0) after 9m29s
[15:52:13.435] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:44:0)
[15:52:13.476] Build status: STARTED
[15:52:13.477] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:45:0) after 4m1s
[15:56:14.501] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:45:0)
[15:56:14.534] Build status: STARTED
[15:56:14.534] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:46:0) after 6m10s
[16:02:24.773] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:46:0)
[16:02:24.821] Build status: STARTED
[16:02:24.821] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:47:0) after 9m8s
[16:11:32.838] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:47:0)
[16:11:32.876] Build status: STARTED
[16:11:32.876] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:48:0) after 7m5s
[16:18:37.928] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:48:0)
[16:18:37.959] Build status: STARTED
[16:18:37.959] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:49:0) after 2m15s
[16:20:52.984] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:49:0)
[16:20:53.081] Build status: STARTED
[16:20:53.081] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9000730380945297024:50:0) after 7m7s
[16:23:42.790] Received PubSub notification, asking Buildbucket for the build status
[16:23:42.819] Build:
{
"id": "8835300318714488417",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTiOS"
},
"number": 19425,
"createdBy": "project:chromium",
"createTime": "2021-09-23T11:53:50.514084696Z",
"startTime": "2021-09-23T11:55:22.314112Z",
"endTime": "2021-09-23T16:23:42.478719695Z",
"updateTime": "2021-09-23T16:23:42.479227758Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "d0745866ae7b7e3b8b81a2b7d5e660fcca9e33d5",
"ref": "refs/heads/main"
}
}
}
[16:23:42.819] Invocation finished in 4h29m53.816044295s with status SUCCEEDED