[11:04:22.153] New invocation is queued and will start shortly
[11:04:23.272] Starting the invocation (attempt 1)
[11:04:23.302] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/97f8021ddcd80800efb577ae441ac9fdcb17d878"
[11:04:23.302] Popped gitiles commit info from properties and tags
[11:04:23.302] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:04:23.302] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:04:23.303] Buildbucket request:
{
"requestId": "8956703366260612416",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "android-official"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "97f8021ddcd80800efb577ae441ac9fdcb17d878",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8956703366260612416"
},
{
"key": "scheduler_job_id",
"value": "chromium/android-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[11:04:23.768] Scheduled build:
{
"id": "8791273304327751697",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "android-official"
},
"number": 13596,
"createdBy": "project:chromium",
"createTime": "2023-01-22T11:04:23.362986854Z",
"updateTime": "2023-01-22T11:04:23.362986854Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "97f8021ddcd80800efb577ae441ac9fdcb17d878",
"ref": "refs/heads/main"
}
}
}
[11:04:23.768] Task URL: https://cr-buildbucket.appspot.com/build/8791273304327751697
[11:04:23.768] Scheduling timer "check-buildbucket-build-status" (chromium/android-official:8956703366260612416:2:0) after 8m58s
[11:09:16.086] Received PubSub notification, asking Buildbucket for the build status
[11:09:16.107] Build status: STARTED
[11:13:21.786] Handling timer "check-buildbucket-build-status" (chromium/android-official:8956703366260612416:2:0)
[11:13:21.806] Build status: STARTED
[11:13:21.806] Scheduling timer "check-buildbucket-build-status" (chromium/android-official:8956703366260612416:4:0) after 2m10s
[11:15:31.822] Handling timer "check-buildbucket-build-status" (chromium/android-official:8956703366260612416:4:0)
[11:15:31.851] Build status: STARTED
[11:15:31.851] Scheduling timer "check-buildbucket-build-status" (chromium/android-official:8956703366260612416:5:0) after 8m10s
[11:23:41.865] Handling timer "check-buildbucket-build-status" (chromium/android-official:8956703366260612416:5:0)
[11:23:42.014] Build status: STARTED
[11:23:42.014] Scheduling timer "check-buildbucket-build-status" (chromium/android-official:8956703366260612416:6:0) after 1m53s
[11:25:35.034] Handling timer "check-buildbucket-build-status" (chromium/android-official:8956703366260612416:6:0)
[11:25:35.057] Build status: STARTED
[11:25:35.057] Scheduling timer "check-buildbucket-build-status" (chromium/android-official:8956703366260612416:7:0) after 2m42s
[11:28:17.077] Handling timer "check-buildbucket-build-status" (chromium/android-official:8956703366260612416:7:0)
[11:28:17.127] Build status: STARTED
[11:28:17.127] Scheduling timer "check-buildbucket-build-status" (chromium/android-official:8956703366260612416:8:0) after 7m22s
[11:35:39.163] Handling timer "check-buildbucket-build-status" (chromium/android-official:8956703366260612416:8:0)
[11:35:39.187] Build status: STARTED
[11:35:39.187] Scheduling timer "check-buildbucket-build-status" (chromium/android-official:8956703366260612416:9:0) after 3m35s
[11:39:14.360] Handling timer "check-buildbucket-build-status" (chromium/android-official:8956703366260612416:9:0)
[11:39:14.383] Build status: STARTED
[11:39:14.383] Scheduling timer "check-buildbucket-build-status" (chromium/android-official:8956703366260612416:10:0) after 4m44s
[11:43:58.401] Handling timer "check-buildbucket-build-status" (chromium/android-official:8956703366260612416:10:0)
[11:43:58.421] Build status: STARTED
[11:43:58.421] Scheduling timer "check-buildbucket-build-status" (chromium/android-official:8956703366260612416:11:0) after 9m38s
[11:53:36.440] Handling timer "check-buildbucket-build-status" (chromium/android-official:8956703366260612416:11:0)
[11:53:36.462] Build status: STARTED
[11:53:36.462] Scheduling timer "check-buildbucket-build-status" (chromium/android-official:8956703366260612416:12:0) after 2m40s
[11:56:16.578] Handling timer "check-buildbucket-build-status" (chromium/android-official:8956703366260612416:12:0)
[11:56:16.600] Build status: STARTED
[11:56:16.600] Scheduling timer "check-buildbucket-build-status" (chromium/android-official:8956703366260612416:13:0) after 8m0s
[12:04:16.703] Handling timer "check-buildbucket-build-status" (chromium/android-official:8956703366260612416:13:0)
[12:04:16.733] Build status: STARTED
[12:04:16.733] Scheduling timer "check-buildbucket-build-status" (chromium/android-official:8956703366260612416:14:0) after 3m26s
[12:07:42.848] Handling timer "check-buildbucket-build-status" (chromium/android-official:8956703366260612416:14:0)
[12:07:42.868] Build status: STARTED
[12:07:42.868] Scheduling timer "check-buildbucket-build-status" (chromium/android-official:8956703366260612416:15:0) after 2m4s
[12:09:46.886] Handling timer "check-buildbucket-build-status" (chromium/android-official:8956703366260612416:15:0)
[12:09:46.908] Build status: STARTED
[12:09:46.908] Scheduling timer "check-buildbucket-build-status" (chromium/android-official:8956703366260612416:16:0) after 6m28s
[12:16:15.011] Handling timer "check-buildbucket-build-status" (chromium/android-official:8956703366260612416:16:0)
[12:16:15.047] Build status: STARTED
[12:16:15.047] Scheduling timer "check-buildbucket-build-status" (chromium/android-official:8956703366260612416:17:0) after 4m54s
[12:21:09.065] Handling timer "check-buildbucket-build-status" (chromium/android-official:8956703366260612416:17:0)
[12:21:09.089] Build status: STARTED
[12:21:09.089] Scheduling timer "check-buildbucket-build-status" (chromium/android-official:8956703366260612416:18:0) after 4m45s
[12:25:54.113] Handling timer "check-buildbucket-build-status" (chromium/android-official:8956703366260612416:18:0)
[12:25:54.133] Build status: STARTED
[12:25:54.133] Scheduling timer "check-buildbucket-build-status" (chromium/android-official:8956703366260612416:19:0) after 8m34s
[12:34:28.100] Handling timer "check-buildbucket-build-status" (chromium/android-official:8956703366260612416:19:0)
[12:34:28.120] Build status: STARTED
[12:34:28.120] Scheduling timer "check-buildbucket-build-status" (chromium/android-official:8956703366260612416:20:0) after 9m51s
[12:44:19.228] Handling timer "check-buildbucket-build-status" (chromium/android-official:8956703366260612416:20:0)
[12:44:19.248] Build status: STARTED
[12:44:19.248] Scheduling timer "check-buildbucket-build-status" (chromium/android-official:8956703366260612416:21:0) after 9m13s
[12:53:32.452] Handling timer "check-buildbucket-build-status" (chromium/android-official:8956703366260612416:21:0)
[12:53:32.473] Build status: STARTED
[12:53:32.473] Scheduling timer "check-buildbucket-build-status" (chromium/android-official:8956703366260612416:22:0) after 2m21s
[12:55:53.539] Handling timer "check-buildbucket-build-status" (chromium/android-official:8956703366260612416:22:0)
[12:55:53.562] Build status: STARTED
[12:55:53.562] Scheduling timer "check-buildbucket-build-status" (chromium/android-official:8956703366260612416:23:0) after 5m22s
[13:01:15.582] Handling timer "check-buildbucket-build-status" (chromium/android-official:8956703366260612416:23:0)
[13:01:15.612] Build status: STARTED
[13:01:15.612] Scheduling timer "check-buildbucket-build-status" (chromium/android-official:8956703366260612416:24:0) after 6m38s
[13:07:53.833] Handling timer "check-buildbucket-build-status" (chromium/android-official:8956703366260612416:24:0)
[13:07:53.855] Build status: STARTED
[13:07:53.855] Scheduling timer "check-buildbucket-build-status" (chromium/android-official:8956703366260612416:25:0) after 9m40s
[13:17:33.881] Handling timer "check-buildbucket-build-status" (chromium/android-official:8956703366260612416:25:0)
[13:17:33.907] Build status: STARTED
[13:17:33.907] Scheduling timer "check-buildbucket-build-status" (chromium/android-official:8956703366260612416:26:0) after 7m4s
[13:24:37.925] Handling timer "check-buildbucket-build-status" (chromium/android-official:8956703366260612416:26:0)
[13:24:37.951] Build status: STARTED
[13:24:37.951] Scheduling timer "check-buildbucket-build-status" (chromium/android-official:8956703366260612416:27:0) after 1m34s
[13:26:12.035] Handling timer "check-buildbucket-build-status" (chromium/android-official:8956703366260612416:27:0)
[13:26:12.056] Build status: STARTED
[13:26:12.056] Scheduling timer "check-buildbucket-build-status" (chromium/android-official:8956703366260612416:28:0) after 4m17s
[13:30:29.074] Handling timer "check-buildbucket-build-status" (chromium/android-official:8956703366260612416:28:0)
[13:30:29.120] Build status: STARTED
[13:30:29.120] Scheduling timer "check-buildbucket-build-status" (chromium/android-official:8956703366260612416:29:0) after 3m36s
[13:34:05.189] Handling timer "check-buildbucket-build-status" (chromium/android-official:8956703366260612416:29:0)
[13:34:05.213] Build status: STARTED
[13:34:05.213] Scheduling timer "check-buildbucket-build-status" (chromium/android-official:8956703366260612416:30:0) after 4m4s
[13:38:09.231] Handling timer "check-buildbucket-build-status" (chromium/android-official:8956703366260612416:30:0)
[13:38:09.252] Build status: STARTED
[13:38:09.253] Scheduling timer "check-buildbucket-build-status" (chromium/android-official:8956703366260612416:31:0) after 8m51s
[13:43:03.043] Received PubSub notification, asking Buildbucket for the build status
[13:43:03.068] Build:
{
"id": "8791273304327751697",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "android-official"
},
"number": 13596,
"createdBy": "project:chromium",
"createTime": "2023-01-22T11:04:23.362986854Z",
"startTime": "2023-01-22T11:09:11.839326Z",
"endTime": "2023-01-22T13:43:02.892183326Z",
"updateTime": "2023-01-22T13:43:02.892183326Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "97f8021ddcd80800efb577ae441ac9fdcb17d878",
"ref": "refs/heads/main"
}
}
}
[13:43:03.068] Invocation finished in 2h38m40.933180262s with status SUCCEEDED