[21:13:51.274] New invocation is queued and will start shortly
[21:13:52.533] Starting the invocation (attempt 1)
[21:13:52.562] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ce97b179e84974f62dae3ab406907a0265d903ec"
[21:13:52.562] Popped gitiles commit info from properties and tags
[21:13:52.562] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:13:52.562] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:13:52.562] Buildbucket request:
{
"requestId": "8970707550538053520",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTiOSDevice"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "ce97b179e84974f62dae3ab406907a0265d903ec",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8970707550538053520"
},
{
"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"
}
}
[21:13:53.178] Scheduled build:
{
"id": "8805277488480492513",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTiOSDevice"
},
"number": 25877,
"createdBy": "project:chromium",
"createTime": "2022-08-20T21:13:52.614717991Z",
"updateTime": "2022-08-20T21:13:52.614717991Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "ce97b179e84974f62dae3ab406907a0265d903ec",
"ref": "refs/heads/main"
}
}
}
[21:13:53.178] Task URL: https://cr-buildbucket.appspot.com/build/8805277488480492513
[21:13:53.178] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:2:0) after 4m4s
[21:15:48.974] Received PubSub notification, asking Buildbucket for the build status
[21:15:49.014] Build status: STARTED
[21:17:57.438] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:2:0)
[21:17:57.507] Build status: STARTED
[21:17:57.507] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:4:0) after 6m54s
[21:24:51.497] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:4:0)
[21:24:51.517] Build status: STARTED
[21:24:51.517] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:5:0) after 9m19s
[21:34:10.983] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:5:0)
[21:34:11.034] Build status: STARTED
[21:34:11.034] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:6:0) after 5m51s
[21:40:02.051] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:6:0)
[21:40:02.156] Build status: STARTED
[21:40:02.156] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:7:0) after 9m26s
[21:49:28.146] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:7:0)
[21:49:28.280] Build status: STARTED
[21:49:28.280] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:8:0) after 4m29s
[21:53:57.339] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:8:0)
[21:53:57.359] Build status: STARTED
[21:53:57.359] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:9:0) after 7m42s
[22:01:39.387] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:9:0)
[22:01:39.424] Build status: STARTED
[22:01:39.424] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:10:0) after 8m11s
[22:09:50.440] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:10:0)
[22:09:50.465] Build status: STARTED
[22:09:50.465] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:11:0) after 2m58s
[22:12:48.480] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:11:0)
[22:12:48.500] Build status: STARTED
[22:12:48.500] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:12:0) after 9m35s
[22:22:23.614] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:12:0)
[22:22:23.636] Build status: STARTED
[22:22:23.636] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:13:0) after 5m36s
[22:27:59.656] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:13:0)
[22:27:59.677] Build status: STARTED
[22:27:59.677] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:14:0) after 1m41s
[22:29:40.784] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:14:0)
[22:29:40.947] Build status: STARTED
[22:29:40.947] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:15:0) after 4m10s
[22:33:51.011] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:15:0)
[22:33:51.035] Build status: STARTED
[22:33:51.035] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:16:0) after 1m59s
[22:35:50.087] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:16:0)
[22:35:50.108] Build status: STARTED
[22:35:50.109] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:17:0) after 3m34s
[22:39:24.190] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:17:0)
[22:39:24.217] Build status: STARTED
[22:39:24.217] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:18:0) after 4m51s
[22:44:15.399] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:18:0)
[22:44:15.424] Build status: STARTED
[22:44:15.424] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:19:0) after 4m56s
[22:49:11.457] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:19:0)
[22:49:11.481] Build status: STARTED
[22:49:11.481] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:20:0) after 3m47s
[22:52:58.528] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:20:0)
[22:52:58.547] Build status: STARTED
[22:52:58.547] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:21:0) after 6m52s
[22:59:50.563] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:21:0)
[22:59:50.586] Build status: STARTED
[22:59:50.586] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:22:0) after 4m27s
[23:04:17.693] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:22:0)
[23:04:17.716] Build status: STARTED
[23:04:17.716] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:23:0) after 8m24s
[23:12:41.753] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:23:0)
[23:12:41.780] Build status: STARTED
[23:12:41.780] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:24:0) after 5m16s
[23:17:57.904] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:24:0)
[23:17:58.083] Build status: STARTED
[23:17:58.083] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:25:0) after 1m39s
[23:19:37.368] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:25:0)
[23:19:37.388] Build status: STARTED
[23:19:37.388] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:26:0) after 9m42s
[23:29:19.622] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:26:0)
[23:29:19.646] Build status: STARTED
[23:29:19.646] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:27:0) after 4m38s
[23:33:58.077] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:27:0)
[23:33:58.101] Build status: STARTED
[23:33:58.101] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:28:0) after 3m36s
[23:37:34.335] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:28:0)
[23:37:34.435] Build status: STARTED
[23:37:34.435] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:29:0) after 8m35s
[23:46:09.454] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:29:0)
[23:46:09.497] Build status: STARTED
[23:46:09.497] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:30:0) after 2m28s
[23:48:37.511] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:30:0)
[23:48:37.536] Build status: STARTED
[23:48:37.536] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:31:0) after 6m1s
[23:54:38.551] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:31:0)
[23:54:38.574] Build status: STARTED
[23:54:38.574] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:32:0) after 8m6s
[00:02:44.691] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:32:0)
[00:02:44.716] Build status: STARTED
[00:02:44.716] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:33:0) after 6m58s
[00:09:42.983] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:33:0)
[00:09:43.008] Build status: STARTED
[00:09:43.008] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:34:0) after 6m37s
[00:16:20.052] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:34:0)
[00:16:20.073] Build status: STARTED
[00:16:20.073] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:35:0) after 5m1s
[00:21:21.095] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:35:0)
[00:21:21.118] Build status: STARTED
[00:21:21.118] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:36:0) after 8m20s
[00:29:42.102] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:36:0)
[00:29:42.125] Build status: STARTED
[00:29:42.125] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:37:0) after 5m19s
[00:35:01.199] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:37:0)
[00:35:01.225] Build status: STARTED
[00:35:01.225] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:38:0) after 9m3s
[00:44:04.343] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:38:0)
[00:44:04.381] Build status: STARTED
[00:44:04.381] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:39:0) after 4m57s
[00:49:01.471] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:39:0)
[00:49:01.491] Build status: STARTED
[00:49:01.491] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:40:0) after 7m32s
[00:56:33.521] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:40:0)
[00:56:33.546] Build status: STARTED
[00:56:33.546] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:41:0) after 5m32s
[01:02:05.578] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:41:0)
[01:02:05.647] Build status: STARTED
[01:02:05.647] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:42:0) after 4m56s
[01:07:01.667] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:42:0)
[01:07:01.693] Build status: STARTED
[01:07:01.693] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:43:0) after 4m12s
[01:11:13.715] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:43:0)
[01:11:13.744] Build status: STARTED
[01:11:13.744] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:44:0) after 9m49s
[01:21:02.760] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:44:0)
[01:21:02.784] Build status: STARTED
[01:21:02.784] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:45:0) after 2m9s
[01:23:11.801] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:45:0)
[01:23:11.826] Build status: STARTED
[01:23:11.826] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:46:0) after 6m13s
[01:29:24.890] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:46:0)
[01:29:24.915] Build status: STARTED
[01:29:24.915] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:47:0) after 2m29s
[01:31:54.971] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:47:0)
[01:31:55.039] Build status: STARTED
[01:31:55.039] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:48:0) after 3m57s
[01:35:52.064] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:48:0)
[01:35:52.085] Build status: STARTED
[01:35:52.085] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:49:0) after 9m39s
[01:45:31.101] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:49:0)
[01:45:31.122] Build status: STARTED
[01:45:31.122] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:50:0) after 6m0s
[01:51:31.183] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:50:0)
[01:51:31.212] Build status: STARTED
[01:51:31.212] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8970707550538053520:51:0) after 9m6s
[01:52:16.069] Received PubSub notification, asking Buildbucket for the build status
[01:52:16.088] Build:
{
"id": "8805277488480492513",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTiOSDevice"
},
"number": 25877,
"createdBy": "project:chromium",
"createTime": "2022-08-20T21:13:52.614717991Z",
"startTime": "2022-08-20T21:15:48.449516Z",
"endTime": "2022-08-21T01:52:15.797604005Z",
"updateTime": "2022-08-21T01:52:15.797604005Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "ce97b179e84974f62dae3ab406907a0265d903ec",
"ref": "refs/heads/main"
}
}
}
[01:52:16.088] Invocation finished in 4h38m24.819686497s with status SUCCEEDED