[05:55:08.752] New invocation is queued and will start shortly
[05:55:09.915] Starting the invocation (attempt 1)
[05:55:09.952] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/57e8b5fa66aa45714849b31a816a039869b011cc"
[05:55:09.952] Popped gitiles commit info from properties and tags
[05:55:09.952] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:55:09.952] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:55:09.953] Buildbucket request:
{
"requestId": "8973120871827522944",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTAndroid x86"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "57e8b5fa66aa45714849b31a816a039869b011cc",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8973120871827522944"
},
{
"key": "scheduler_job_id",
"value": "chromium/ToTAndroid x86"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[05:55:10.437] Scheduled build:
{
"id": "8807690809827873553",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTAndroid x86"
},
"number": 5334,
"createdBy": "project:chromium",
"createTime": "2022-07-25T05:55:10.027771365Z",
"updateTime": "2022-07-25T05:55:10.027771365Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "57e8b5fa66aa45714849b31a816a039869b011cc",
"ref": "refs/heads/main"
}
}
}
[05:55:10.437] Task URL: https://cr-buildbucket.appspot.com/build/8807690809827873553
[05:55:10.437] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:2:0) after 4m42s
[05:57:06.667] Received PubSub notification, asking Buildbucket for the build status
[05:57:06.701] Build status: STARTED
[05:59:52.907] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:2:0)
[05:59:52.930] Build status: STARTED
[05:59:52.930] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:4:0) after 7m8s
[06:07:01.355] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:4:0)
[06:07:01.388] Build status: STARTED
[06:07:01.388] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:5:0) after 2m14s
[06:09:15.574] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:5:0)
[06:09:15.599] Build status: STARTED
[06:09:15.599] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:6:0) after 7m14s
[06:16:29.736] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:6:0)
[06:16:29.758] Build status: STARTED
[06:16:29.758] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:7:0) after 2m49s
[06:19:18.781] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:7:0)
[06:19:18.800] Build status: STARTED
[06:19:18.800] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:8:0) after 1m40s
[06:20:59.715] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:8:0)
[06:20:59.741] Build status: STARTED
[06:20:59.741] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:9:0) after 7m54s
[06:28:54.043] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:9:0)
[06:28:54.075] Build status: STARTED
[06:28:54.076] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:10:0) after 4m59s
[06:33:53.093] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:10:0)
[06:33:53.112] Build status: STARTED
[06:33:53.112] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:11:0) after 8m49s
[06:42:42.690] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:11:0)
[06:42:42.710] Build status: STARTED
[06:42:42.710] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:12:0) after 3m51s
[06:46:33.729] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:12:0)
[06:46:33.776] Build status: STARTED
[06:46:33.776] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:13:0) after 8m30s
[06:55:03.870] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:13:0)
[06:55:03.893] Build status: STARTED
[06:55:03.893] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:14:0) after 7m38s
[07:02:41.916] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:14:0)
[07:02:41.936] Build status: STARTED
[07:02:41.937] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:15:0) after 4m54s
[07:07:35.976] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:15:0)
[07:07:36.000] Build status: STARTED
[07:07:36.000] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:16:0) after 1m32s
[07:09:08.025] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:16:0)
[07:09:08.054] Build status: STARTED
[07:09:08.055] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:17:0) after 8m15s
[07:17:23.098] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:17:0)
[07:17:23.138] Build status: STARTED
[07:17:23.138] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:18:0) after 3m6s
[07:20:29.344] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:18:0)
[07:20:29.367] Build status: STARTED
[07:20:29.367] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:19:0) after 3m55s
[07:24:25.118] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:19:0)
[07:24:25.139] Build status: STARTED
[07:24:25.139] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:20:0) after 9m29s
[07:33:54.167] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:20:0)
[07:33:54.222] Build status: STARTED
[07:33:54.222] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:21:0) after 3m28s
[07:37:22.334] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:21:0)
[07:37:22.355] Build status: STARTED
[07:37:22.355] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:22:0) after 9m18s
[07:46:40.746] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:22:0)
[07:46:40.770] Build status: STARTED
[07:46:40.770] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:23:0) after 1m16s
[07:47:57.080] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:23:0)
[07:47:57.132] Build status: STARTED
[07:47:57.132] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:24:0) after 7m57s
[07:55:54.414] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:24:0)
[07:55:54.436] Build status: STARTED
[07:55:54.437] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:25:0) after 3m29s
[07:59:23.465] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:25:0)
[07:59:23.516] Build status: STARTED
[07:59:23.516] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:26:0) after 2m55s
[08:02:19.447] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:26:0)
[08:02:19.531] Build status: STARTED
[08:02:19.531] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:27:0) after 5m26s
[08:07:45.553] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:27:0)
[08:07:45.594] Build status: STARTED
[08:07:45.594] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:28:0) after 2m55s
[08:10:40.814] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:28:0)
[08:10:40.844] Build status: STARTED
[08:10:40.844] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:29:0) after 4m1s
[08:14:41.979] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:29:0)
[08:14:42.001] Build status: STARTED
[08:14:42.001] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:30:0) after 5m5s
[08:19:47.021] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:30:0)
[08:19:47.041] Build status: STARTED
[08:19:47.041] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:31:0) after 2m13s
[08:22:00.063] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:31:0)
[08:22:00.094] Build status: STARTED
[08:22:00.094] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:32:0) after 4m22s
[08:26:22.118] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:32:0)
[08:26:22.154] Build status: STARTED
[08:26:22.154] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:33:0) after 6m33s
[08:32:55.231] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:33:0)
[08:32:55.254] Build status: STARTED
[08:32:55.254] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:34:0) after 1m14s
[08:34:09.398] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:34:0)
[08:34:09.422] Build status: STARTED
[08:34:09.422] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:35:0) after 7m18s
[08:41:27.459] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:35:0)
[08:41:27.480] Build status: STARTED
[08:41:27.480] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:36:0) after 7m42s
[08:49:09.598] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:36:0)
[08:49:09.626] Build status: STARTED
[08:49:09.626] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:37:0) after 3m55s
[08:53:04.603] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:37:0)
[08:53:04.626] Build status: STARTED
[08:53:04.626] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:38:0) after 6m40s
[08:59:44.646] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:38:0)
[08:59:44.733] Build status: STARTED
[08:59:44.733] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:39:0) after 1m41s
[09:01:25.757] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:39:0)
[09:01:25.779] Build status: STARTED
[09:01:25.779] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:40:0) after 4m23s
[09:05:48.799] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:40:0)
[09:05:48.830] Build status: STARTED
[09:05:48.830] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:41:0) after 6m44s
[09:12:32.850] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:41:0)
[09:12:32.874] Build status: STARTED
[09:12:32.874] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:42:0) after 4m46s
[09:17:18.950] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:42:0)
[09:17:18.971] Build status: STARTED
[09:17:18.971] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:43:0) after 4m5s
[09:21:23.989] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:43:0)
[09:21:24.057] Build status: STARTED
[09:21:24.057] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:44:0) after 4m13s
[09:25:37.048] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:44:0)
[09:25:37.072] Build status: STARTED
[09:25:37.072] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:45:0) after 7m41s
[09:33:18.092] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:45:0)
[09:33:18.117] Build status: STARTED
[09:33:18.117] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:46:0) after 8m43s
[09:42:01.218] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:46:0)
[09:42:01.241] Build status: STARTED
[09:42:01.241] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:47:0) after 2m17s
[09:44:18.346] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:47:0)
[09:44:18.368] Build status: STARTED
[09:44:18.368] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:48:0) after 9m50s
[09:54:08.487] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:48:0)
[09:54:08.540] Build status: STARTED
[09:54:08.540] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:49:0) after 7m52s
[10:02:00.679] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:49:0)
[10:02:00.702] Build status: STARTED
[10:02:00.702] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:50:0) after 7m35s
[10:09:35.826] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:50:0)
[10:09:35.851] Build status: STARTED
[10:09:35.851] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:51:0) after 3m13s
[10:12:48.874] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:51:0)
[10:12:48.898] Build status: STARTED
[10:12:48.898] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:52:0) after 5m50s
[10:18:39.068] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:52:0)
[10:18:39.105] Build status: STARTED
[10:18:39.105] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:53:0) after 6m15s
[10:24:54.166] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:53:0)
[10:24:54.296] Build status: STARTED
[10:24:54.297] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:54:0) after 3m58s
[10:28:52.415] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:54:0)
[10:28:52.451] Build status: STARTED
[10:28:52.451] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:55:0) after 9m35s
[10:38:27.678] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:55:0)
[10:38:27.698] Build status: STARTED
[10:38:27.698] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:8973120871827522944:56:0) after 3m17s
[10:41:44.043] Received PubSub notification, asking Buildbucket for the build status
[10:41:44.067] Build:
{
"id": "8807690809827873553",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTAndroid x86"
},
"number": 5334,
"createdBy": "project:chromium",
"createTime": "2022-07-25T05:55:10.027771365Z",
"startTime": "2022-07-25T05:57:05.358933Z",
"endTime": "2022-07-25T10:41:43.544140280Z",
"updateTime": "2022-07-25T10:41:43.544140280Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "57e8b5fa66aa45714849b31a816a039869b011cc",
"ref": "refs/heads/main"
}
}
}
[10:41:44.067] Invocation finished in 4h46m35.330333147s with status SUCCEEDED