[21:04:26.251] New invocation is queued and will start shortly
[21:04:27.850] Starting the invocation (attempt 1)
[21:04:27.892] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9ef544dbcf48efe2595fa291993a1fce3a6a365d"
[21:04:27.892] Popped gitiles commit info from properties and tags
[21:04:27.892] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:04:27.892] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:04:27.892] Buildbucket request:
{
"requestId": "8924775481047344320",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "android-official"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "9ef544dbcf48efe2595fa291993a1fce3a6a365d",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8924775481047344320"
},
{
"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"
}
}
[21:04:28.461] Scheduled build:
{
"id": "8759345418607500529",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "android-official"
},
"number": 18545,
"createdBy": "project:chromium",
"createTime": "2024-01-09T21:04:27.951563166Z",
"updateTime": "2024-01-09T21:04:27.951563166Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "9ef544dbcf48efe2595fa291993a1fce3a6a365d",
"ref": "refs/heads/main"
}
}
}
[21:04:28.461] Task URL: https://cr-buildbucket.appspot.com/build/8759345418607500529
[21:04:28.461] Scheduling timer "check-buildbucket-build-status" (chromium/android-official:8924775481047344320:2:0) after 8m10s
[21:04:29.581] Received PubSub notification, asking Buildbucket for the build status
[21:04:29.619] Build status: STARTED
[21:04:30.065] Received PubSub notification, asking Buildbucket for the build status
[21:04:30.117] Build status: STARTED
[21:12:38.487] Handling timer "check-buildbucket-build-status" (chromium/android-official:8924775481047344320:2:0)
[21:12:38.508] Build status: STARTED
[21:12:38.508] Scheduling timer "check-buildbucket-build-status" (chromium/android-official:8924775481047344320:5:0) after 5m19s
[21:17:57.532] Handling timer "check-buildbucket-build-status" (chromium/android-official:8924775481047344320:5:0)
[21:17:57.558] Build status: STARTED
[21:17:57.558] Scheduling timer "check-buildbucket-build-status" (chromium/android-official:8924775481047344320:6:0) after 9m1s
[21:26:58.789] Handling timer "check-buildbucket-build-status" (chromium/android-official:8924775481047344320:6:0)
[21:26:58.808] Build status: STARTED
[21:26:58.808] Scheduling timer "check-buildbucket-build-status" (chromium/android-official:8924775481047344320:7:0) after 2m29s
[21:29:27.827] Handling timer "check-buildbucket-build-status" (chromium/android-official:8924775481047344320:7:0)
[21:29:27.912] Build status: STARTED
[21:29:27.912] Scheduling timer "check-buildbucket-build-status" (chromium/android-official:8924775481047344320:8:0) after 5m21s
[21:34:49.292] Handling timer "check-buildbucket-build-status" (chromium/android-official:8924775481047344320:8:0)
[21:34:49.316] Build status: STARTED
[21:34:49.316] Scheduling timer "check-buildbucket-build-status" (chromium/android-official:8924775481047344320:9:0) after 2m46s
[21:37:35.649] Handling timer "check-buildbucket-build-status" (chromium/android-official:8924775481047344320:9:0)
[21:37:35.667] Build status: STARTED
[21:37:35.667] Scheduling timer "check-buildbucket-build-status" (chromium/android-official:8924775481047344320:10:0) after 8m41s
[21:46:16.750] Handling timer "check-buildbucket-build-status" (chromium/android-official:8924775481047344320:10:0)
[21:46:16.779] Build status: STARTED
[21:46:16.779] Scheduling timer "check-buildbucket-build-status" (chromium/android-official:8924775481047344320:11:0) after 1m57s
[21:48:13.868] Handling timer "check-buildbucket-build-status" (chromium/android-official:8924775481047344320:11:0)
[21:48:13.891] Build status: STARTED
[21:48:13.891] Scheduling timer "check-buildbucket-build-status" (chromium/android-official:8924775481047344320:12:0) after 5m16s
[21:53:29.934] Handling timer "check-buildbucket-build-status" (chromium/android-official:8924775481047344320:12:0)
[21:53:29.954] Build status: STARTED
[21:53:29.954] Scheduling timer "check-buildbucket-build-status" (chromium/android-official:8924775481047344320:13:0) after 5m25s
[21:58:54.986] Handling timer "check-buildbucket-build-status" (chromium/android-official:8924775481047344320:13:0)
[21:58:55.003] Build status: STARTED
[21:58:55.003] Scheduling timer "check-buildbucket-build-status" (chromium/android-official:8924775481047344320:14:0) after 1m55s
[22:00:50.237] Handling timer "check-buildbucket-build-status" (chromium/android-official:8924775481047344320:14:0)
[22:00:50.259] Build status: STARTED
[22:00:50.259] Scheduling timer "check-buildbucket-build-status" (chromium/android-official:8924775481047344320:15:0) after 7m54s
[22:08:44.573] Handling timer "check-buildbucket-build-status" (chromium/android-official:8924775481047344320:15:0)
[22:08:44.606] Build status: STARTED
[22:08:44.606] Scheduling timer "check-buildbucket-build-status" (chromium/android-official:8924775481047344320:16:0) after 3m17s
[22:12:01.732] Handling timer "check-buildbucket-build-status" (chromium/android-official:8924775481047344320:16:0)
[22:12:01.748] Build status: STARTED
[22:12:01.748] Scheduling timer "check-buildbucket-build-status" (chromium/android-official:8924775481047344320:17:0) after 3m1s
[22:15:02.803] Handling timer "check-buildbucket-build-status" (chromium/android-official:8924775481047344320:17:0)
[22:15:02.826] Build status: STARTED
[22:15:02.826] Scheduling timer "check-buildbucket-build-status" (chromium/android-official:8924775481047344320:18:0) after 1m2s
[22:16:04.862] Handling timer "check-buildbucket-build-status" (chromium/android-official:8924775481047344320:18:0)
[22:16:04.879] Build status: STARTED
[22:16:04.879] Scheduling timer "check-buildbucket-build-status" (chromium/android-official:8924775481047344320:19:0) after 3m35s
[22:19:39.907] Handling timer "check-buildbucket-build-status" (chromium/android-official:8924775481047344320:19:0)
[22:19:39.923] Build status: STARTED
[22:19:39.923] Scheduling timer "check-buildbucket-build-status" (chromium/android-official:8924775481047344320:20:0) after 1m13s
[22:20:52.946] Handling timer "check-buildbucket-build-status" (chromium/android-official:8924775481047344320:20:0)
[22:20:52.971] Build status: STARTED
[22:20:52.971] Scheduling timer "check-buildbucket-build-status" (chromium/android-official:8924775481047344320:21:0) after 3m2s
[22:23:54.987] Handling timer "check-buildbucket-build-status" (chromium/android-official:8924775481047344320:21:0)
[22:23:55.041] Build status: STARTED
[22:23:55.041] Scheduling timer "check-buildbucket-build-status" (chromium/android-official:8924775481047344320:22:0) after 9m6s
[22:28:11.830] Received PubSub notification, asking Buildbucket for the build status
[22:28:11.853] Build:
{
"id": "8759345418607500529",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "android-official"
},
"number": 18545,
"createdBy": "project:chromium",
"createTime": "2024-01-09T21:04:27.951563166Z",
"startTime": "2024-01-09T21:04:28.927286Z",
"endTime": "2024-01-09T22:28:11.316989Z",
"updateTime": "2024-01-09T22:28:11.316989Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "9ef544dbcf48efe2595fa291993a1fce3a6a365d",
"ref": "refs/heads/main"
}
}
}
[22:28:11.853] Invocation finished in 1h23m45.612842569s with status SUCCEEDED