[01:52:26.081] New invocation is queued and will start shortly
[01:52:27.147] Starting the invocation (attempt 1)
[01:52:27.183] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e30bed218928069ddb3398334d0368e104d58f30"
[01:52:27.183] Popped gitiles commit info from properties and tags
[01:52:27.183] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:52:27.183] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:52:27.183] Buildbucket request:
{
"requestId": "8885438278409559280",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Fuchsia (dbg)"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "e30bed218928069ddb3398334d0368e104d58f30",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8885438278409559280"
},
{
"key": "scheduler_job_id",
"value": "chromium/Deterministic Fuchsia (dbg)"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[01:52:27.904] Scheduled build:
{
"id": "8720008216477368353",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Fuchsia (dbg)"
},
"number": 23234,
"createdBy": "project:chromium",
"createTime": "2025-03-19T01:52:27.302938091Z",
"updateTime": "2025-03-19T01:52:27.302938091Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "e30bed218928069ddb3398334d0368e104d58f30",
"ref": "refs/heads/main"
}
},
"canary": true
}
[01:52:27.904] Task URL: https://cr-buildbucket.appspot.com/build/8720008216477368353
[01:52:27.904] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8885438278409559280:2:0) after 3m41s
[01:52:28.987] Received PubSub notification, asking Buildbucket for the build status
[01:52:29.009] Build status: SCHEDULED
[01:56:08.956] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8885438278409559280:2:0)
[01:56:09.002] Build status: SCHEDULED
[01:56:09.002] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8885438278409559280:4:0) after 3m0s
[01:59:09.074] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8885438278409559280:4:0)
[01:59:09.103] Build status: SCHEDULED
[01:59:09.103] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8885438278409559280:5:0) after 2m53s
[02:02:02.178] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8885438278409559280:5:0)
[02:02:02.224] Build status: SCHEDULED
[02:02:02.224] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8885438278409559280:6:0) after 9m41s
[02:11:43.417] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8885438278409559280:6:0)
[02:11:43.450] Build status: SCHEDULED
[02:11:43.450] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8885438278409559280:7:0) after 8m40s
[02:20:23.618] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8885438278409559280:7:0)
[02:20:23.684] Build status: SCHEDULED
[02:20:23.684] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8885438278409559280:8:0) after 5m59s
[02:26:22.748] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8885438278409559280:8:0)
[02:26:22.773] Build status: SCHEDULED
[02:26:22.773] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8885438278409559280:9:0) after 5m44s
[02:32:07.023] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8885438278409559280:9:0)
[02:32:07.046] Build status: SCHEDULED
[02:32:07.046] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8885438278409559280:10:0) after 9m51s
[02:41:58.073] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8885438278409559280:10:0)
[02:41:58.105] Build status: SCHEDULED
[02:41:58.105] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8885438278409559280:11:0) after 9m3s
[02:51:01.136] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8885438278409559280:11:0)
[02:51:01.154] Build status: SCHEDULED
[02:51:01.154] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8885438278409559280:12:0) after 4m11s
[02:55:12.176] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8885438278409559280:12:0)
[02:55:12.191] Build status: SCHEDULED
[02:55:12.191] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8885438278409559280:13:0) after 1m39s
[02:56:51.237] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8885438278409559280:13:0)
[02:56:51.253] Build status: SCHEDULED
[02:56:51.253] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8885438278409559280:14:0) after 7m25s
[03:04:16.335] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8885438278409559280:14:0)
[03:04:16.357] Build status: SCHEDULED
[03:04:16.357] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8885438278409559280:15:0) after 8m20s
[03:08:15.730] Received PubSub notification, asking Buildbucket for the build status
[03:08:15.748] Build status: STARTED
[03:12:36.390] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8885438278409559280:15:0)
[03:12:36.464] Build status: STARTED
[03:12:36.464] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8885438278409559280:17:0) after 5m46s
[03:18:22.543] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8885438278409559280:17:0)
[03:18:22.609] Build status: STARTED
[03:18:22.609] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8885438278409559280:18:0) after 4m23s
[03:22:45.662] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8885438278409559280:18:0)
[03:22:45.696] Build status: STARTED
[03:22:45.696] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8885438278409559280:19:0) after 8m37s
[03:31:22.736] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8885438278409559280:19:0)
[03:31:22.763] Build status: STARTED
[03:31:22.763] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8885438278409559280:20:0) after 1m58s
[03:33:20.794] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8885438278409559280:20:0)
[03:33:20.849] Build status: STARTED
[03:33:20.849] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8885438278409559280:21:0) after 2m6s
[03:35:26.879] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8885438278409559280:21:0)
[03:35:26.899] Build status: STARTED
[03:35:26.899] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8885438278409559280:22:0) after 1m22s
[03:36:48.905] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8885438278409559280:22:0)
[03:36:48.940] Build status: STARTED
[03:36:48.940] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8885438278409559280:23:0) after 8m16s
[03:45:05.453] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8885438278409559280:23:0)
[03:45:05.472] Build status: STARTED
[03:45:05.472] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8885438278409559280:24:0) after 7m38s
[03:52:43.493] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8885438278409559280:24:0)
[03:52:43.507] Build status: STARTED
[03:52:43.507] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8885438278409559280:25:0) after 8m13s
[04:00:56.735] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8885438278409559280:25:0)
[04:00:56.748] Build status: STARTED
[04:00:56.748] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8885438278409559280:26:0) after 7m52s
[04:08:48.779] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8885438278409559280:26:0)
[04:08:48.796] Build status: STARTED
[04:08:48.796] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8885438278409559280:27:0) after 1m34s
[04:10:22.849] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8885438278409559280:27:0)
[04:10:22.884] Build status: STARTED
[04:10:22.884] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8885438278409559280:28:0) after 4m5s
[04:14:27.897] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8885438278409559280:28:0)
[04:14:27.956] Build status: STARTED
[04:14:27.956] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8885438278409559280:29:0) after 4m15s
[04:18:42.977] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8885438278409559280:29:0)
[04:18:42.996] Build status: STARTED
[04:18:42.996] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8885438278409559280:30:0) after 4m57s
[04:23:40.014] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8885438278409559280:30:0)
[04:23:40.030] Build status: STARTED
[04:23:40.030] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8885438278409559280:31:0) after 2m28s
[04:26:08.060] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8885438278409559280:31:0)
[04:26:08.175] Build status: STARTED
[04:26:08.175] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8885438278409559280:32:0) after 6m48s
[04:32:56.179] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8885438278409559280:32:0)
[04:32:56.197] Build status: STARTED
[04:32:56.197] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8885438278409559280:33:0) after 9m6s
[04:42:02.292] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8885438278409559280:33:0)
[04:42:02.326] Build status: STARTED
[04:42:02.326] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8885438278409559280:34:0) after 2m48s
[04:44:50.346] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8885438278409559280:34:0)
[04:44:50.362] Build status: STARTED
[04:44:50.362] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8885438278409559280:35:0) after 3m28s
[04:48:18.393] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8885438278409559280:35:0)
[04:48:18.416] Build status: STARTED
[04:48:18.416] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8885438278409559280:36:0) after 8m25s
[04:52:50.367] Received PubSub notification, asking Buildbucket for the build status
[04:52:50.385] Build:
{
"id": "8720008216477368353",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Fuchsia (dbg)"
},
"number": 23234,
"createdBy": "project:chromium",
"createTime": "2025-03-19T01:52:27.302938091Z",
"startTime": "2025-03-19T03:08:15.500993767Z",
"endTime": "2025-03-19T04:52:50.078454786Z",
"updateTime": "2025-03-19T04:52:50.078454786Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "e30bed218928069ddb3398334d0368e104d58f30",
"ref": "refs/heads/main"
}
},
"canary": true
}
[04:52:50.385] Invocation finished in 3h0m24.310389336s with status SUCCEEDED