[15:33:16.481] New invocation is queued and will start shortly
[15:33:17.791] Starting the invocation (attempt 1)
[15:33:17.841] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3ad4e38455361ea9d7bc96dbdc548ab47493b2d9"
[15:33:17.841] Popped gitiles commit info from properties and tags
[15:33:17.841] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:33:17.841] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:33:17.841] Buildbucket request:
{
"requestId": "8987398819798020896",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTiOS"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "3ad4e38455361ea9d7bc96dbdc548ab47493b2d9",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8987398819798020896"
},
{
"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"
}
}
[15:33:18.430] Scheduled build:
{
"id": "8821968757606004865",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTiOS"
},
"number": 20353,
"createdBy": "project:chromium",
"createTime": "2022-02-17T15:33:17.940179243Z",
"updateTime": "2022-02-17T15:33:17.940179243Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "3ad4e38455361ea9d7bc96dbdc548ab47493b2d9",
"ref": "refs/heads/main"
}
}
}
[15:33:18.430] Task URL: https://cr-buildbucket.appspot.com/build/8821968757606004865
[15:33:18.430] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:2:0) after 1m51s
[15:35:09.461] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:2:0)
[15:35:09.510] Build status: SCHEDULED
[15:35:09.510] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:3:0) after 1m40s
[15:35:10.343] Received PubSub notification, asking Buildbucket for the build status
[15:35:10.383] Build status: STARTED
[15:36:49.526] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:3:0)
[15:36:49.544] Build status: STARTED
[15:36:49.544] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:5:0) after 2m42s
[15:39:31.735] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:5:0)
[15:39:31.767] Build status: STARTED
[15:39:31.767] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:6:0) after 1m45s
[15:41:16.789] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:6:0)
[15:41:16.818] Build status: STARTED
[15:41:16.818] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:7:0) after 6m39s
[15:47:55.834] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:7:0)
[15:47:55.871] Build status: STARTED
[15:47:55.871] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:8:0) after 4m9s
[15:52:04.894] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:8:0)
[15:52:04.911] Build status: STARTED
[15:52:04.911] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:9:0) after 9m9s
[16:01:13.952] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:9:0)
[16:01:14.045] Build status: STARTED
[16:01:14.045] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:10:0) after 8m46s
[16:10:00.073] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:10:0)
[16:10:00.095] Build status: STARTED
[16:10:00.095] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:11:0) after 6m20s
[16:16:20.408] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:11:0)
[16:16:20.434] Build status: STARTED
[16:16:20.434] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:12:0) after 4m34s
[16:20:54.455] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:12:0)
[16:20:54.476] Build status: STARTED
[16:20:54.476] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:13:0) after 2m40s
[16:23:34.567] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:13:0)
[16:23:34.600] Build status: STARTED
[16:23:34.600] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:14:0) after 7m35s
[16:31:09.623] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:14:0)
[16:31:09.645] Build status: STARTED
[16:31:09.645] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:15:0) after 2m50s
[16:33:59.753] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:15:0)
[16:33:59.782] Build status: STARTED
[16:33:59.782] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:16:0) after 5m27s
[16:39:26.928] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:16:0)
[16:39:26.947] Build status: STARTED
[16:39:26.947] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:17:0) after 3m7s
[16:42:34.125] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:17:0)
[16:42:34.145] Build status: STARTED
[16:42:34.145] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:18:0) after 5m37s
[16:48:11.187] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:18:0)
[16:48:11.212] Build status: STARTED
[16:48:11.212] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:19:0) after 7m35s
[16:55:46.234] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:19:0)
[16:55:46.256] Build status: STARTED
[16:55:46.256] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:20:0) after 5m8s
[17:00:54.277] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:20:0)
[17:00:54.308] Build status: STARTED
[17:00:54.308] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:21:0) after 4m4s
[17:04:58.509] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:21:0)
[17:04:58.536] Build status: STARTED
[17:04:58.536] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:22:0) after 1m27s
[17:06:25.703] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:22:0)
[17:06:25.740] Build status: STARTED
[17:06:25.740] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:23:0) after 4m56s
[17:11:21.874] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:23:0)
[17:11:21.894] Build status: STARTED
[17:11:21.894] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:24:0) after 3m37s
[17:14:59.025] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:24:0)
[17:14:59.049] Build status: STARTED
[17:14:59.049] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:25:0) after 8m36s
[17:23:35.175] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:25:0)
[17:23:35.210] Build status: STARTED
[17:23:35.210] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:26:0) after 4m13s
[17:27:48.339] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:26:0)
[17:27:48.362] Build status: STARTED
[17:27:48.362] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:27:0) after 5m7s
[17:32:55.504] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:27:0)
[17:32:55.575] Build status: STARTED
[17:32:55.575] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:28:0) after 2m11s
[17:35:06.745] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:28:0)
[17:35:06.768] Build status: STARTED
[17:35:06.768] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:29:0) after 3m52s
[17:38:58.954] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:29:0)
[17:38:58.977] Build status: STARTED
[17:38:58.977] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:30:0) after 1m51s
[17:40:50.047] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:30:0)
[17:40:50.152] Build status: STARTED
[17:40:50.152] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:31:0) after 2m15s
[17:43:05.473] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:31:0)
[17:43:05.505] Build status: STARTED
[17:43:05.505] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:32:0) after 4m45s
[17:47:50.582] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:32:0)
[17:47:50.599] Build status: STARTED
[17:47:50.600] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:33:0) after 1m15s
[17:49:05.585] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:33:0)
[17:49:05.607] Build status: STARTED
[17:49:05.607] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:34:0) after 6m3s
[17:55:08.818] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:34:0)
[17:55:08.894] Build status: STARTED
[17:55:08.894] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:35:0) after 1m22s
[17:56:30.921] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:35:0)
[17:56:30.951] Build status: STARTED
[17:56:30.951] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:36:0) after 5m3s
[18:01:34.165] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:36:0)
[18:01:34.200] Build status: STARTED
[18:01:34.200] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:37:0) after 8m20s
[18:09:54.238] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:37:0)
[18:09:54.259] Build status: STARTED
[18:09:54.259] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:38:0) after 8m46s
[18:18:40.324] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:38:0)
[18:18:40.344] Build status: STARTED
[18:18:40.344] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:39:0) after 9m57s
[18:28:37.371] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:39:0)
[18:28:37.431] Build status: STARTED
[18:28:37.431] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:40:0) after 7m2s
[18:35:39.501] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:40:0)
[18:35:39.524] Build status: STARTED
[18:35:39.524] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:41:0) after 3m42s
[18:39:21.548] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:41:0)
[18:39:21.848] Build status: STARTED
[18:39:21.848] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:42:0) after 7m33s
[18:46:54.956] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:42:0)
[18:46:54.974] Build status: STARTED
[18:46:54.974] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:43:0) after 4m44s
[18:51:38.995] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:43:0)
[18:51:39.019] Build status: STARTED
[18:51:39.019] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:44:0) after 4m13s
[18:55:52.135] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:44:0)
[18:55:52.173] Build status: STARTED
[18:55:52.173] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:45:0) after 2m33s
[18:58:25.466] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:45:0)
[18:58:25.510] Build status: STARTED
[18:58:25.510] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:46:0) after 6m25s
[19:04:50.617] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:46:0)
[19:04:50.638] Build status: STARTED
[19:04:50.638] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:47:0) after 7m7s
[19:11:57.802] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:47:0)
[19:11:57.962] Build status: STARTED
[19:11:57.962] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:48:0) after 9m42s
[19:21:40.283] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:48:0)
[19:21:40.303] Build status: STARTED
[19:21:40.303] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:49:0) after 9m20s
[19:31:00.969] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:49:0)
[19:31:00.988] Build status: STARTED
[19:31:00.988] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:50:0) after 8m45s
[19:39:46.084] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:50:0)
[19:39:46.114] Build status: STARTED
[19:39:46.114] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:51:0) after 5m27s
[19:45:13.430] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:51:0)
[19:45:13.449] Build status: STARTED
[19:45:13.449] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:52:0) after 5m4s
[19:50:17.611] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:52:0)
[19:50:17.629] Build status: STARTED
[19:50:17.629] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:53:0) after 1m4s
[19:51:21.743] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:53:0)
[19:51:21.762] Build status: STARTED
[19:51:21.762] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:54:0) after 2m50s
[19:54:11.779] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:54:0)
[19:54:11.822] Build status: STARTED
[19:54:11.822] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:55:0) after 4m35s
[19:58:47.628] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:55:0)
[19:58:47.669] Build status: STARTED
[19:58:47.669] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:56:0) after 2m49s
[20:01:36.756] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:56:0)
[20:01:36.795] Build status: STARTED
[20:01:36.795] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:57:0) after 3m33s
[20:05:09.821] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:57:0)
[20:05:09.860] Build status: STARTED
[20:05:09.860] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:58:0) after 7m1s
[20:12:10.950] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:58:0)
[20:12:10.980] Build status: STARTED
[20:12:10.980] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:59:0) after 3m39s
[20:15:49.996] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:59:0)
[20:15:50.019] Build status: STARTED
[20:15:50.019] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:60:0) after 3m23s
[20:19:13.364] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:60:0)
[20:19:13.387] Build status: STARTED
[20:19:13.388] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:8987398819798020896:61:0) after 6m28s
[20:20:45.942] Received PubSub notification, asking Buildbucket for the build status
[20:20:45.979] Build:
{
"id": "8821968757606004865",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTiOS"
},
"number": 20353,
"createdBy": "project:chromium",
"createTime": "2022-02-17T15:33:17.940179243Z",
"startTime": "2022-02-17T15:35:09.436447Z",
"endTime": "2022-02-17T20:20:45.434310059Z",
"updateTime": "2022-02-17T20:20:45.434310059Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "3ad4e38455361ea9d7bc96dbdc548ab47493b2d9",
"ref": "refs/heads/main"
}
}
}
[20:20:45.979] Invocation finished in 4h47m29.513356717s with status SUCCEEDED