[00:29:21.192] New invocation is queued and will start shortly
[00:29:22.691] Starting the invocation (attempt 1)
[00:29:22.728] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/914872608472bed1cdd653221f09fa60cce52509"
[00:29:22.728] Popped gitiles commit info from properties and tags
[00:29:22.728] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:29:22.730] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:29:22.730] Buildbucket request:
{
"requestId": "8962994508692057104",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTAndroid (dbg)"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "914872608472bed1cdd653221f09fa60cce52509",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8962994508692057104"
},
{
"key": "scheduler_job_id",
"value": "chromium/ToTAndroid (dbg)"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[00:29:23.217] Scheduled build:
{
"id": "8797564446308561409",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTAndroid (dbg)"
},
"number": 18318,
"createdBy": "project:chromium",
"createTime": "2022-11-14T00:29:22.834239831Z",
"updateTime": "2022-11-14T00:29:22.834239831Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "914872608472bed1cdd653221f09fa60cce52509",
"ref": "refs/heads/main"
}
}
}
[00:29:23.217] Task URL: https://cr-buildbucket.appspot.com/build/8797564446308561409
[00:29:23.217] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:2:0) after 5m18s
[00:29:53.618] Received PubSub notification, asking Buildbucket for the build status
[00:29:53.656] Build status: STARTED
[00:34:41.246] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:2:0)
[00:34:41.276] Build status: STARTED
[00:34:41.276] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:4:0) after 3m13s
[00:37:54.303] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:4:0)
[00:37:54.332] Build status: STARTED
[00:37:54.332] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:5:0) after 3m54s
[00:41:48.363] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:5:0)
[00:41:48.405] Build status: STARTED
[00:41:48.405] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:6:0) after 4m54s
[00:46:42.432] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:6:0)
[00:46:42.453] Build status: STARTED
[00:46:42.453] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:7:0) after 9m58s
[00:56:40.628] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:7:0)
[00:56:40.688] Build status: STARTED
[00:56:40.689] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:8:0) after 6m10s
[01:02:50.712] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:8:0)
[01:02:50.741] Build status: STARTED
[01:02:50.741] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:9:0) after 6m35s
[01:09:26.012] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:9:0)
[01:09:26.038] Build status: STARTED
[01:09:26.038] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:10:0) after 6m22s
[01:15:48.063] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:10:0)
[01:15:48.089] Build status: STARTED
[01:15:48.089] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:11:0) after 8m55s
[01:24:43.143] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:11:0)
[01:24:43.165] Build status: STARTED
[01:24:43.165] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:12:0) after 2m53s
[01:27:36.188] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:12:0)
[01:27:36.219] Build status: STARTED
[01:27:36.219] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:13:0) after 8m34s
[01:36:10.246] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:13:0)
[01:36:10.270] Build status: STARTED
[01:36:10.270] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:14:0) after 4m13s
[01:40:23.409] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:14:0)
[01:40:23.440] Build status: STARTED
[01:40:23.440] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:15:0) after 6m26s
[01:46:49.689] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:15:0)
[01:46:49.736] Build status: STARTED
[01:46:49.736] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:16:0) after 1m38s
[01:48:27.775] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:16:0)
[01:48:27.834] Build status: STARTED
[01:48:27.834] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:17:0) after 6m44s
[01:55:11.858] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:17:0)
[01:55:11.921] Build status: STARTED
[01:55:11.921] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:18:0) after 5m2s
[02:00:13.965] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:18:0)
[02:00:14.048] Build status: STARTED
[02:00:14.048] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:19:0) after 8m50s
[02:09:04.087] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:19:0)
[02:09:04.108] Build status: STARTED
[02:09:04.108] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:20:0) after 9m37s
[02:18:41.247] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:20:0)
[02:18:41.272] Build status: STARTED
[02:18:41.272] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:21:0) after 9m16s
[02:27:57.452] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:21:0)
[02:27:57.483] Build status: STARTED
[02:27:57.483] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:22:0) after 8m33s
[02:36:30.748] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:22:0)
[02:36:30.780] Build status: STARTED
[02:36:30.780] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:23:0) after 2m12s
[02:38:42.857] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:23:0)
[02:38:42.889] Build status: STARTED
[02:38:42.889] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:24:0) after 9m4s
[02:47:46.918] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:24:0)
[02:47:46.947] Build status: STARTED
[02:47:46.947] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:25:0) after 4m32s
[02:52:18.971] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:25:0)
[02:52:18.994] Build status: STARTED
[02:52:18.994] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:26:0) after 5m29s
[02:57:48.022] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:26:0)
[02:57:48.053] Build status: STARTED
[02:57:48.053] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:27:0) after 4m25s
[03:02:13.215] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:27:0)
[03:02:13.357] Build status: STARTED
[03:02:13.357] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:28:0) after 8m54s
[03:11:07.419] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:28:0)
[03:11:07.444] Build status: STARTED
[03:11:07.444] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:29:0) after 4m19s
[03:15:26.466] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:29:0)
[03:15:26.488] Build status: STARTED
[03:15:26.488] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:30:0) after 6m21s
[03:21:47.524] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:30:0)
[03:21:47.546] Build status: STARTED
[03:21:47.546] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:31:0) after 7m38s
[03:29:26.011] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:31:0)
[03:29:26.056] Build status: STARTED
[03:29:26.056] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:32:0) after 2m8s
[03:31:34.079] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:32:0)
[03:31:34.156] Build status: STARTED
[03:31:34.156] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:33:0) after 5m6s
[03:36:40.390] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:33:0)
[03:36:40.413] Build status: STARTED
[03:36:40.413] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:34:0) after 8m1s
[03:44:41.437] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:34:0)
[03:44:41.464] Build status: STARTED
[03:44:41.464] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:35:0) after 1m36s
[03:46:17.612] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:35:0)
[03:46:17.644] Build status: STARTED
[03:46:17.644] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:36:0) after 8m35s
[03:54:52.666] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:36:0)
[03:54:52.689] Build status: STARTED
[03:54:52.689] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:37:0) after 2m19s
[03:57:11.764] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:37:0)
[03:57:11.786] Build status: STARTED
[03:57:11.786] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:38:0) after 7m27s
[04:04:39.013] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:38:0)
[04:04:39.077] Build status: STARTED
[04:04:39.077] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:39:0) after 5m51s
[04:10:30.191] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:39:0)
[04:10:30.227] Build status: STARTED
[04:10:30.227] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:40:0) after 6m22s
[04:16:52.251] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:40:0)
[04:16:52.277] Build status: STARTED
[04:16:52.277] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:41:0) after 1m27s
[04:18:19.344] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:41:0)
[04:18:19.379] Build status: STARTED
[04:18:19.379] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:42:0) after 2m38s
[04:20:57.665] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:42:0)
[04:20:57.689] Build status: STARTED
[04:20:57.689] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:43:0) after 1m28s
[04:22:25.755] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:43:0)
[04:22:25.779] Build status: STARTED
[04:22:25.779] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:44:0) after 9m19s
[04:31:45.183] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:44:0)
[04:31:45.219] Build status: STARTED
[04:31:45.219] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:45:0) after 8m1s
[04:39:46.478] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:45:0)
[04:39:46.500] Build status: STARTED
[04:39:46.500] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:46:0) after 1m10s
[04:40:56.573] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:46:0)
[04:40:56.596] Build status: STARTED
[04:40:56.596] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:47:0) after 1m52s
[04:42:48.619] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:47:0)
[04:42:48.645] Build status: STARTED
[04:42:48.645] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:48:0) after 9m43s
[04:52:31.674] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:48:0)
[04:52:31.704] Build status: STARTED
[04:52:31.704] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:49:0) after 2m55s
[04:55:26.735] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:49:0)
[04:55:26.761] Build status: STARTED
[04:55:26.761] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:50:0) after 4m37s
[05:00:03.784] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:50:0)
[05:00:03.857] Build status: STARTED
[05:00:03.857] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:51:0) after 9m7s
[05:09:10.921] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:51:0)
[05:09:10.949] Build status: STARTED
[05:09:10.949] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:52:0) after 1m51s
[05:11:02.095] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:52:0)
[05:11:02.126] Build status: STARTED
[05:11:02.126] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:53:0) after 2m57s
[05:13:59.253] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:53:0)
[05:13:59.280] Build status: STARTED
[05:13:59.280] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:54:0) after 4m13s
[05:18:12.417] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:54:0)
[05:18:12.441] Build status: STARTED
[05:18:12.441] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:55:0) after 7m25s
[05:25:37.469] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:55:0)
[05:25:37.499] Build status: STARTED
[05:25:37.499] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:56:0) after 8m43s
[05:34:20.622] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:56:0)
[05:34:20.659] Build status: STARTED
[05:34:20.659] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:57:0) after 3m39s
[05:37:59.702] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:57:0)
[05:37:59.732] Build status: STARTED
[05:37:59.732] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:58:0) after 6m48s
[05:44:47.769] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:58:0)
[05:44:47.793] Build status: STARTED
[05:44:47.793] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:59:0) after 8m24s
[05:53:12.261] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:59:0)
[05:53:12.315] Build status: STARTED
[05:53:12.315] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):8962994508692057104:60:0) after 7m17s
[05:59:26.452] Received PubSub notification, asking Buildbucket for the build status
[05:59:26.478] Build:
{
"id": "8797564446308561409",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTAndroid (dbg)"
},
"number": 18318,
"createdBy": "project:chromium",
"createTime": "2022-11-14T00:29:22.834239831Z",
"startTime": "2022-11-14T00:29:52.311585Z",
"endTime": "2022-11-14T05:59:26.217770945Z",
"updateTime": "2022-11-14T05:59:26.217770945Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "914872608472bed1cdd653221f09fa60cce52509",
"ref": "refs/heads/main"
}
}
}
[05:59:26.478] Invocation finished in 5h30m5.300414531s with status SUCCEEDED