[15:21:57.318] New invocation is queued and will start shortly
[15:21:58.514] Starting the invocation (attempt 1)
[15:21:58.542] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/304c6707e228734989fbd97d7474e88f97677763"
[15:21:58.542] Popped gitiles commit info from properties and tags
[15:21:58.542] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:21:58.542] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:21:58.542] Buildbucket request:
{
"requestId": "8896893162513407856",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "fuchsia-x64-cast-receiver-dbg"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "304c6707e228734989fbd97d7474e88f97677763",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8896893162513407856"
},
{
"key": "scheduler_job_id",
"value": "chromium/fuchsia-x64-cast-receiver-dbg"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[15:21:59.100] Scheduled build:
{
"id": "8731463100481850273",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "fuchsia-x64-cast-receiver-dbg"
},
"number": 2624,
"createdBy": "project:chromium",
"createTime": "2024-11-12T15:21:58.629427012Z",
"updateTime": "2024-11-12T15:21:58.629427012Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "304c6707e228734989fbd97d7474e88f97677763",
"ref": "refs/heads/main"
}
}
}
[15:21:59.100] Task URL: https://cr-buildbucket.appspot.com/build/8731463100481850273
[15:21:59.100] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8896893162513407856:2:0) after 6m10s
[15:22:00.148] Received PubSub notification, asking Buildbucket for the build status
[15:22:00.220] Build status: SCHEDULED
[15:28:09.480] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8896893162513407856:2:0)
[15:28:09.510] Build status: SCHEDULED
[15:28:09.510] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8896893162513407856:4:0) after 2m35s
[15:30:44.523] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8896893162513407856:4:0)
[15:30:44.596] Build status: SCHEDULED
[15:30:44.596] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8896893162513407856:5:0) after 9m55s
[15:40:39.990] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8896893162513407856:5:0)
[15:40:40.010] Build status: SCHEDULED
[15:40:40.010] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8896893162513407856:6:0) after 3m37s
[15:44:17.277] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8896893162513407856:6:0)
[15:44:17.313] Build status: SCHEDULED
[15:44:17.313] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8896893162513407856:7:0) after 7m37s
[15:51:54.329] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8896893162513407856:7:0)
[15:51:54.352] Build status: SCHEDULED
[15:51:54.352] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8896893162513407856:8:0) after 3m55s
[15:55:49.370] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8896893162513407856:8:0)
[15:55:49.393] Build status: SCHEDULED
[15:55:49.393] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8896893162513407856:9:0) after 9m32s
[16:05:21.521] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8896893162513407856:9:0)
[16:05:21.545] Build status: SCHEDULED
[16:05:21.545] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8896893162513407856:10:0) after 8m12s
[16:13:33.746] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8896893162513407856:10:0)
[16:13:33.770] Build status: SCHEDULED
[16:13:33.771] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8896893162513407856:11:0) after 2m43s
[16:14:50.453] Received PubSub notification, asking Buildbucket for the build status
[16:14:50.474] Build status: STARTED
[16:16:16.878] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8896893162513407856:11:0)
[16:16:16.895] Build status: STARTED
[16:16:16.895] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8896893162513407856:13:0) after 4m7s
[16:20:24.448] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8896893162513407856:13:0)
[16:20:24.464] Build status: STARTED
[16:20:24.464] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8896893162513407856:14:0) after 7m0s
[16:27:24.654] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8896893162513407856:14:0)
[16:27:24.715] Build status: STARTED
[16:27:24.715] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8896893162513407856:15:0) after 6m8s
[16:33:33.000] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8896893162513407856:15:0)
[16:33:33.032] Build status: STARTED
[16:33:33.032] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8896893162513407856:16:0) after 8m47s
[16:42:20.052] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8896893162513407856:16:0)
[16:42:20.071] Build status: STARTED
[16:42:20.072] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8896893162513407856:17:0) after 8m46s
[16:51:06.148] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8896893162513407856:17:0)
[16:51:06.216] Build status: STARTED
[16:51:06.216] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8896893162513407856:18:0) after 7m23s
[16:58:29.244] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8896893162513407856:18:0)
[16:58:29.262] Build status: STARTED
[16:58:29.262] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8896893162513407856:19:0) after 2m7s
[17:00:36.322] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8896893162513407856:19:0)
[17:00:36.341] Build status: STARTED
[17:00:36.341] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8896893162513407856:20:0) after 5m47s
[17:06:23.376] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8896893162513407856:20:0)
[17:06:23.398] Build status: STARTED
[17:06:23.398] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8896893162513407856:21:0) after 5m25s
[17:11:48.418] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8896893162513407856:21:0)
[17:11:48.439] Build status: STARTED
[17:11:48.439] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8896893162513407856:22:0) after 2m8s
[17:13:56.486] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8896893162513407856:22:0)
[17:13:56.524] Build status: STARTED
[17:13:56.524] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8896893162513407856:23:0) after 7m3s
[17:20:59.544] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8896893162513407856:23:0)
[17:20:59.563] Build status: STARTED
[17:20:59.563] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8896893162513407856:24:0) after 9m19s
[17:30:18.580] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8896893162513407856:24:0)
[17:30:18.606] Build status: STARTED
[17:30:18.606] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8896893162513407856:25:0) after 1m50s
[17:32:08.713] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8896893162513407856:25:0)
[17:32:08.742] Build status: STARTED
[17:32:08.742] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8896893162513407856:26:0) after 2m26s
[17:34:34.812] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8896893162513407856:26:0)
[17:34:34.830] Build status: STARTED
[17:34:34.830] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8896893162513407856:27:0) after 5m47s
[17:40:21.899] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8896893162513407856:27:0)
[17:40:21.992] Build status: STARTED
[17:40:21.992] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8896893162513407856:28:0) after 7m15s
[17:47:37.160] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8896893162513407856:28:0)
[17:47:37.183] Build status: STARTED
[17:47:37.183] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8896893162513407856:29:0) after 9m14s
[17:55:23.473] Received PubSub notification, asking Buildbucket for the build status
[17:55:24.384] Build:
{
"id": "8731463100481850273",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "fuchsia-x64-cast-receiver-dbg"
},
"number": 2624,
"createdBy": "project:chromium",
"createTime": "2024-11-12T15:21:58.629427012Z",
"startTime": "2024-11-12T16:14:50.144124674Z",
"endTime": "2024-11-12T17:55:22.919040857Z",
"updateTime": "2024-11-12T17:55:22.919040857Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "304c6707e228734989fbd97d7474e88f97677763",
"ref": "refs/heads/main"
}
}
}
[17:55:24.384] Invocation finished in 2h33m27.076354868s with status SUCCEEDED