[21:40:46.425] New invocation is queued and will start shortly
[21:40:47.659] Starting the invocation (attempt 1)
[21:40:47.685] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/90cb34ea3e5d6bbd4f7ff2deac0c2b85454d53d8"
[21:40:47.685] Popped gitiles commit info from properties and tags
[21:40:47.685] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:40:47.685] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:40:47.685] Buildbucket request:
{
"requestId": "8902305147305933264",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "mac-official"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "90cb34ea3e5d6bbd4f7ff2deac0c2b85454d53d8",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8902305147305933264"
},
{
"key": "scheduler_job_id",
"value": "chromium/mac-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[21:40:48.382] Scheduled build:
{
"id": "8736875085221350785",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "mac-official"
},
"number": 9434,
"createdBy": "project:chromium",
"createTime": "2024-09-13T21:40:47.782595688Z",
"updateTime": "2024-09-13T21:40:47.782595688Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "90cb34ea3e5d6bbd4f7ff2deac0c2b85454d53d8",
"ref": "refs/heads/main"
}
}
}
[21:40:48.382] Task URL: https://cr-buildbucket.appspot.com/build/8736875085221350785
[21:40:48.382] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8902305147305933264:2:0) after 4m34s
[21:40:49.362] Received PubSub notification, asking Buildbucket for the build status
[21:40:49.392] Build status: SCHEDULED
[21:41:42.190] Received PubSub notification, asking Buildbucket for the build status
[21:41:42.218] Build status: STARTED
[21:45:22.441] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8902305147305933264:2:0)
[21:45:22.577] Build status: STARTED
[21:45:22.577] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8902305147305933264:5:0) after 9m34s
[21:54:56.591] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8902305147305933264:5:0)
[21:54:56.616] Build status: STARTED
[21:54:56.616] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8902305147305933264:6:0) after 1m59s
[21:56:55.687] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8902305147305933264:6:0)
[21:56:55.717] Build status: STARTED
[21:56:55.717] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8902305147305933264:7:0) after 5m20s
[22:02:15.744] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8902305147305933264:7:0)
[22:02:15.824] Build status: STARTED
[22:02:15.824] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8902305147305933264:8:0) after 7m18s
[22:09:33.930] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8902305147305933264:8:0)
[22:09:33.956] Build status: STARTED
[22:09:33.956] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8902305147305933264:9:0) after 5m5s
[22:14:39.102] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8902305147305933264:9:0)
[22:14:39.118] Build status: STARTED
[22:14:39.118] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8902305147305933264:10:0) after 6m34s
[22:21:13.178] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8902305147305933264:10:0)
[22:21:13.201] Build status: STARTED
[22:21:13.201] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8902305147305933264:11:0) after 8m57s
[22:30:10.225] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8902305147305933264:11:0)
[22:30:10.241] Build status: STARTED
[22:30:10.241] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8902305147305933264:12:0) after 8m28s
[22:38:38.270] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8902305147305933264:12:0)
[22:38:38.295] Build status: STARTED
[22:38:38.295] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8902305147305933264:13:0) after 5m12s
[22:43:50.351] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8902305147305933264:13:0)
[22:43:50.371] Build status: STARTED
[22:43:50.371] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8902305147305933264:14:0) after 5m4s
[22:48:54.396] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8902305147305933264:14:0)
[22:48:54.421] Build status: STARTED
[22:48:54.421] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8902305147305933264:15:0) after 8m56s
[22:57:50.473] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8902305147305933264:15:0)
[22:57:50.492] Build status: STARTED
[22:57:50.492] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8902305147305933264:16:0) after 5m9s
[23:02:59.474] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8902305147305933264:16:0)
[23:02:59.519] Build status: STARTED
[23:02:59.519] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8902305147305933264:17:0) after 8m40s
[23:11:39.501] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8902305147305933264:17:0)
[23:11:39.532] Build status: STARTED
[23:11:39.532] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8902305147305933264:18:0) after 9m46s
[23:21:25.561] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8902305147305933264:18:0)
[23:21:25.576] Build status: STARTED
[23:21:25.576] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8902305147305933264:19:0) after 3m50s
[23:25:15.633] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8902305147305933264:19:0)
[23:25:15.648] Build status: STARTED
[23:25:15.649] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8902305147305933264:20:0) after 3m45s
[23:29:00.735] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8902305147305933264:20:0)
[23:29:00.785] Build status: STARTED
[23:29:00.786] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8902305147305933264:21:0) after 9m1s
[23:38:01.855] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8902305147305933264:21:0)
[23:38:01.876] Build status: STARTED
[23:38:01.876] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8902305147305933264:22:0) after 1m19s
[23:39:20.902] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8902305147305933264:22:0)
[23:39:20.999] Build status: STARTED
[23:39:20.999] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8902305147305933264:23:0) after 8m12s
[23:47:33.043] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8902305147305933264:23:0)
[23:47:33.079] Build status: STARTED
[23:47:33.079] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8902305147305933264:24:0) after 9m51s
[23:57:24.079] Handling timer "check-buildbucket-build-status" (chromium/mac-official:8902305147305933264:24:0)
[23:57:24.097] Build status: STARTED
[23:57:24.097] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:8902305147305933264:25:0) after 4m15s
[23:59:20.926] Received PubSub notification, asking Buildbucket for the build status
[23:59:20.945] Build:
{
"id": "8736875085221350785",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "mac-official"
},
"number": 9434,
"createdBy": "project:chromium",
"createTime": "2024-09-13T21:40:47.782595688Z",
"startTime": "2024-09-13T21:41:41.891896068Z",
"endTime": "2024-09-13T23:59:20.587059394Z",
"updateTime": "2024-09-13T23:59:20.587059394Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "90cb34ea3e5d6bbd4f7ff2deac0c2b85454d53d8",
"ref": "refs/heads/main"
}
}
}
[23:59:20.945] Invocation finished in 2h18m34.53347795s with status SUCCEEDED