[05:08:10.620] New invocation is queued and will start shortly
[05:08:11.694] Starting the invocation (attempt 1)
[05:08:11.724] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/72f126869bdf269531c08adfe5c07be1b2550633"
[05:08:11.724] Popped gitiles commit info from properties and tags
[05:08:11.724] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:08:11.724] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:08:11.724] Buildbucket request:
{
"requestId": "8946216527826917344",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTiOSDevice"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "72f126869bdf269531c08adfe5c07be1b2550633",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8946216527826917344"
},
{
"key": "scheduler_job_id",
"value": "chromium/ToTiOSDevice"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[05:08:12.367] Scheduled build:
{
"id": "8780786465897275841",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTiOSDevice"
},
"number": 29424,
"createdBy": "project:chromium",
"createTime": "2023-05-18T05:08:11.834559436Z",
"updateTime": "2023-05-18T05:08:11.834559436Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "72f126869bdf269531c08adfe5c07be1b2550633",
"ref": "refs/heads/main"
}
}
}
[05:08:12.367] Task URL: https://cr-buildbucket.appspot.com/build/8780786465897275841
[05:08:12.367] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:2:0) after 4m29s
[05:11:04.697] Received PubSub notification, asking Buildbucket for the build status
[05:11:04.801] Build status: STARTED
[05:12:41.711] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:2:0)
[05:12:41.740] Build status: STARTED
[05:12:41.740] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:4:0) after 2m33s
[05:15:14.879] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:4:0)
[05:15:14.907] Build status: STARTED
[05:15:14.907] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:5:0) after 3m30s
[05:18:45.137] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:5:0)
[05:18:45.158] Build status: STARTED
[05:18:45.158] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:6:0) after 7m30s
[05:26:15.177] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:6:0)
[05:26:15.213] Build status: STARTED
[05:26:15.213] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:7:0) after 5m9s
[05:31:24.258] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:7:0)
[05:31:24.283] Build status: STARTED
[05:31:24.283] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:8:0) after 1m43s
[05:33:07.259] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:8:0)
[05:33:07.276] Build status: STARTED
[05:33:07.276] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:9:0) after 8m2s
[05:41:09.294] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:9:0)
[05:41:09.313] Build status: STARTED
[05:41:09.314] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:10:0) after 6m4s
[05:47:13.335] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:10:0)
[05:47:13.363] Build status: STARTED
[05:47:13.363] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:11:0) after 3m46s
[05:50:59.459] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:11:0)
[05:50:59.551] Build status: STARTED
[05:50:59.551] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:12:0) after 6m47s
[05:57:46.853] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:12:0)
[05:57:46.874] Build status: STARTED
[05:57:46.874] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:13:0) after 1m7s
[05:58:53.916] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:13:0)
[05:58:53.962] Build status: STARTED
[05:58:53.962] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:14:0) after 5m16s
[06:04:09.982] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:14:0)
[06:04:10.019] Build status: STARTED
[06:04:10.019] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:15:0) after 6m56s
[06:11:06.214] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:15:0)
[06:11:06.231] Build status: STARTED
[06:11:06.231] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:16:0) after 4m8s
[06:15:14.416] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:16:0)
[06:15:14.434] Build status: STARTED
[06:15:14.434] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:17:0) after 9m3s
[06:24:17.456] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:17:0)
[06:24:17.474] Build status: STARTED
[06:24:17.474] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:18:0) after 5m10s
[06:29:27.495] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:18:0)
[06:29:27.511] Build status: STARTED
[06:29:27.511] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:19:0) after 1m39s
[06:31:06.578] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:19:0)
[06:31:06.599] Build status: STARTED
[06:31:06.599] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:20:0) after 4m58s
[06:36:04.686] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:20:0)
[06:36:04.737] Build status: STARTED
[06:36:04.737] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:21:0) after 5m7s
[06:41:11.852] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:21:0)
[06:41:11.872] Build status: STARTED
[06:41:11.872] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:22:0) after 5m23s
[06:46:34.902] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:22:0)
[06:46:34.923] Build status: STARTED
[06:46:34.923] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:23:0) after 8m15s
[06:54:49.989] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:23:0)
[06:54:50.029] Build status: STARTED
[06:54:50.029] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:24:0) after 5m10s
[07:00:00.211] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:24:0)
[07:00:00.230] Build status: STARTED
[07:00:00.230] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:25:0) after 5m8s
[07:05:08.386] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:25:0)
[07:05:08.412] Build status: STARTED
[07:05:08.412] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:26:0) after 1m55s
[07:07:03.475] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:26:0)
[07:07:03.632] Build status: STARTED
[07:07:03.632] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:27:0) after 1m34s
[07:08:37.621] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:27:0)
[07:08:37.642] Build status: STARTED
[07:08:37.642] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:28:0) after 2m4s
[07:10:41.777] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:28:0)
[07:10:41.799] Build status: STARTED
[07:10:41.800] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:29:0) after 4m44s
[07:15:25.822] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:29:0)
[07:15:25.840] Build status: STARTED
[07:15:25.840] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:30:0) after 8m49s
[07:24:14.872] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:30:0)
[07:24:14.898] Build status: STARTED
[07:24:14.898] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:31:0) after 8m24s
[07:32:38.963] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:31:0)
[07:32:38.980] Build status: STARTED
[07:32:38.980] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:32:0) after 5m31s
[07:38:10.209] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:32:0)
[07:38:10.227] Build status: STARTED
[07:38:10.227] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:33:0) after 1m0s
[07:39:10.395] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:33:0)
[07:39:10.413] Build status: STARTED
[07:39:10.413] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:34:0) after 2m36s
[07:41:46.388] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:34:0)
[07:41:46.407] Build status: STARTED
[07:41:46.407] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:35:0) after 8m16s
[07:50:02.551] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:35:0)
[07:50:02.569] Build status: STARTED
[07:50:02.569] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:36:0) after 2m28s
[07:52:30.603] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:36:0)
[07:52:30.628] Build status: STARTED
[07:52:30.628] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:37:0) after 2m53s
[07:55:23.708] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:37:0)
[07:55:23.725] Build status: STARTED
[07:55:23.725] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:38:0) after 5m45s
[08:01:08.829] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:38:0)
[08:01:08.853] Build status: STARTED
[08:01:08.854] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:39:0) after 2m12s
[08:03:21.107] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:39:0)
[08:03:21.129] Build status: STARTED
[08:03:21.129] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:40:0) after 5m18s
[08:08:39.180] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:40:0)
[08:08:39.202] Build status: STARTED
[08:08:39.202] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:41:0) after 9m44s
[08:18:23.226] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:41:0)
[08:18:23.246] Build status: STARTED
[08:18:23.246] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:42:0) after 3m23s
[08:21:46.323] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:42:0)
[08:21:46.344] Build status: STARTED
[08:21:46.344] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:43:0) after 8m17s
[08:30:03.384] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:43:0)
[08:30:03.402] Build status: STARTED
[08:30:03.402] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:44:0) after 2m28s
[08:32:31.445] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:44:0)
[08:32:31.472] Build status: STARTED
[08:32:31.472] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:45:0) after 5m30s
[08:38:01.609] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:45:0)
[08:38:01.901] Build status: STARTED
[08:38:01.901] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:46:0) after 6m35s
[08:44:37.006] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:46:0)
[08:44:37.027] Build status: STARTED
[08:44:37.027] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:47:0) after 5m5s
[08:49:42.159] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:47:0)
[08:49:42.178] Build status: STARTED
[08:49:42.178] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:48:0) after 4m50s
[08:54:32.267] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:48:0)
[08:54:32.294] Build status: STARTED
[08:54:32.294] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:49:0) after 4m23s
[08:58:55.458] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:49:0)
[08:58:55.482] Build status: STARTED
[08:58:55.482] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:50:0) after 3m26s
[09:02:21.664] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:50:0)
[09:02:21.687] Build status: STARTED
[09:02:21.687] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:51:0) after 9m25s
[09:11:46.802] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:51:0)
[09:11:46.822] Build status: STARTED
[09:11:46.822] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:52:0) after 6m37s
[09:18:23.840] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:52:0)
[09:18:23.859] Build status: STARTED
[09:18:23.859] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:53:0) after 3m44s
[09:22:07.915] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:53:0)
[09:22:07.934] Build status: STARTED
[09:22:07.934] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:54:0) after 9m41s
[09:31:49.146] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:54:0)
[09:31:49.166] Build status: STARTED
[09:31:49.166] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:55:0) after 4m28s
[09:36:17.265] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:55:0)
[09:36:17.287] Build status: STARTED
[09:36:17.287] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:56:0) after 3m34s
[09:39:51.912] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:56:0)
[09:39:51.930] Build status: STARTED
[09:39:51.930] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:57:0) after 6m10s
[09:46:02.097] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:57:0)
[09:46:02.127] Build status: STARTED
[09:46:02.127] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:58:0) after 5m56s
[09:51:58.165] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:58:0)
[09:51:58.185] Build status: STARTED
[09:51:58.185] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:59:0) after 8m9s
[10:00:07.278] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:59:0)
[10:00:07.294] Build status: STARTED
[10:00:07.294] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:60:0) after 4m32s
[10:04:39.510] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:60:0)
[10:04:39.540] Build status: STARTED
[10:04:39.540] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:61:0) after 7m9s
[10:11:48.762] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:61:0)
[10:11:48.783] Build status: STARTED
[10:11:48.783] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8946216527826917344:62:0) after 9m37s
[10:19:53.999] Received PubSub notification, asking Buildbucket for the build status
[10:19:54.058] Build:
{
"id": "8780786465897275841",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTiOSDevice"
},
"number": 29424,
"createdBy": "project:chromium",
"createTime": "2023-05-18T05:08:11.834559436Z",
"startTime": "2023-05-18T05:11:03.755126Z",
"endTime": "2023-05-18T10:19:53.510055754Z",
"updateTime": "2023-05-18T10:19:53.510055754Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "72f126869bdf269531c08adfe5c07be1b2550633",
"ref": "refs/heads/main"
}
}
}
[10:19:54.058] Invocation finished in 5h11m43.448037298s with status FAILED