[01:53:25.320] New invocation is queued and will start shortly
[01:53:26.560] Starting the invocation (attempt 1)
[01:53:26.585] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bf2d033973bfdc914c34165e405ce1edec01219f"
[01:53:26.585] Popped gitiles commit info from properties and tags
[01:53:26.585] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:53:26.585] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:53:26.585] Buildbucket request:
{
"requestId": "8892685973607435552",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "fuchsia-fyi-x64-asan"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "bf2d033973bfdc914c34165e405ce1edec01219f",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8892685973607435552"
},
{
"key": "scheduler_job_id",
"value": "chromium/fuchsia-fyi-x64-asan"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[01:53:27.053] Scheduled build:
{
"id": "8727255911567096913",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "fuchsia-fyi-x64-asan"
},
"number": 6239,
"createdBy": "project:chromium",
"createTime": "2024-12-29T01:53:26.641443280Z",
"updateTime": "2024-12-29T01:53:26.641443280Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "bf2d033973bfdc914c34165e405ce1edec01219f",
"ref": "refs/heads/main"
}
}
}
[01:53:27.053] Task URL: https://cr-buildbucket.appspot.com/build/8727255911567096913
[01:53:27.053] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:2:0) after 4m7s
[01:53:28.048] Received PubSub notification, asking Buildbucket for the build status
[01:53:28.069] Build status: SCHEDULED
[01:57:34.126] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:2:0)
[01:57:34.147] Build status: SCHEDULED
[01:57:34.147] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:4:0) after 8m20s
[02:05:54.169] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:4:0)
[02:05:54.189] Build status: SCHEDULED
[02:05:54.189] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:5:0) after 4m19s
[02:10:13.394] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:5:0)
[02:10:13.418] Build status: SCHEDULED
[02:10:13.418] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:6:0) after 4m45s
[02:12:03.365] Received PubSub notification, asking Buildbucket for the build status
[02:12:03.382] Build status: STARTED
[02:14:58.617] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:6:0)
[02:14:58.641] Build status: STARTED
[02:14:58.641] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:8:0) after 9m3s
[02:24:02.041] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:8:0)
[02:24:02.117] Build status: STARTED
[02:24:02.117] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:9:0) after 6m18s
[02:30:20.132] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:9:0)
[02:30:20.251] Build status: STARTED
[02:30:20.252] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:10:0) after 2m47s
[02:33:07.272] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:10:0)
[02:33:07.293] Build status: STARTED
[02:33:07.293] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:11:0) after 9m17s
[02:42:24.579] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:11:0)
[02:42:24.610] Build status: STARTED
[02:42:24.610] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:12:0) after 3m46s
[02:46:10.702] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:12:0)
[02:46:10.725] Build status: STARTED
[02:46:10.725] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:13:0) after 9m11s
[02:55:21.959] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:13:0)
[02:55:21.980] Build status: STARTED
[02:55:21.980] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:14:0) after 3m52s
[02:59:14.093] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:14:0)
[02:59:14.117] Build status: STARTED
[02:59:14.117] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:15:0) after 8m37s
[03:07:51.295] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:15:0)
[03:07:51.326] Build status: STARTED
[03:07:51.326] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:16:0) after 1m52s
[03:09:43.462] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:16:0)
[03:09:43.500] Build status: STARTED
[03:09:43.500] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:17:0) after 7m42s
[03:17:25.596] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:17:0)
[03:17:25.664] Build status: STARTED
[03:17:25.664] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:18:0) after 9m24s
[03:26:49.688] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:18:0)
[03:26:49.714] Build status: STARTED
[03:26:49.714] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:19:0) after 6m37s
[03:33:26.847] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:19:0)
[03:33:26.937] Build status: STARTED
[03:33:26.937] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:20:0) after 4m43s
[03:38:10.014] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:20:0)
[03:38:10.029] Build status: STARTED
[03:38:10.029] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:21:0) after 6m2s
[03:44:12.104] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:21:0)
[03:44:12.133] Build status: STARTED
[03:44:12.133] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:22:0) after 4m25s
[03:48:37.157] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:22:0)
[03:48:37.181] Build status: STARTED
[03:48:37.181] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:23:0) after 1m42s
[03:50:19.197] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:23:0)
[03:50:19.213] Build status: STARTED
[03:50:19.213] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:24:0) after 2m56s
[03:53:15.461] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:24:0)
[03:53:15.494] Build status: STARTED
[03:53:15.494] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:25:0) after 5m50s
[03:59:05.521] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:25:0)
[03:59:05.541] Build status: STARTED
[03:59:05.541] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:26:0) after 5m24s
[04:04:29.558] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:26:0)
[04:04:29.577] Build status: STARTED
[04:04:29.577] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:27:0) after 8m34s
[04:13:03.593] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:27:0)
[04:13:03.613] Build status: STARTED
[04:13:03.613] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:28:0) after 2m9s
[04:15:12.709] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:28:0)
[04:15:12.731] Build status: STARTED
[04:15:12.731] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:29:0) after 3m27s
[04:18:39.849] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:29:0)
[04:18:39.867] Build status: STARTED
[04:18:39.867] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:30:0) after 3m12s
[04:21:51.941] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:30:0)
[04:21:51.980] Build status: STARTED
[04:21:51.980] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:31:0) after 2m55s
[04:24:47.008] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:31:0)
[04:24:47.027] Build status: STARTED
[04:24:47.027] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:32:0) after 4m34s
[04:29:21.093] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:32:0)
[04:29:21.155] Build status: STARTED
[04:29:21.156] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:33:0) after 3m28s
[04:32:49.241] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:33:0)
[04:32:49.257] Build status: STARTED
[04:32:49.257] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:34:0) after 6m28s
[04:39:17.543] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:34:0)
[04:39:17.585] Build status: STARTED
[04:39:17.585] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:35:0) after 5m49s
[04:45:06.607] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:35:0)
[04:45:06.624] Build status: STARTED
[04:45:06.624] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:36:0) after 5m57s
[04:51:03.642] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:36:0)
[04:51:03.803] Build status: STARTED
[04:51:03.803] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:37:0) after 1m33s
[04:52:36.889] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:37:0)
[04:52:36.909] Build status: STARTED
[04:52:36.909] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:38:0) after 1m48s
[04:54:24.933] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:38:0)
[04:54:24.950] Build status: STARTED
[04:54:24.950] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:39:0) after 3m43s
[04:58:08.171] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:39:0)
[04:58:08.190] Build status: STARTED
[04:58:08.190] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:40:0) after 8m16s
[05:06:24.431] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:40:0)
[05:06:24.456] Build status: STARTED
[05:06:24.456] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:41:0) after 6m41s
[05:13:05.474] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:41:0)
[05:13:05.499] Build status: STARTED
[05:13:05.499] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:42:0) after 7m31s
[05:20:36.523] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:42:0)
[05:20:36.541] Build status: STARTED
[05:20:36.541] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:43:0) after 3m58s
[05:24:34.555] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:43:0)
[05:24:34.582] Build status: STARTED
[05:24:34.582] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:44:0) after 8m28s
[05:33:02.597] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:44:0)
[05:33:02.611] Build status: STARTED
[05:33:02.611] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:45:0) after 5m27s
[05:38:29.618] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:45:0)
[05:38:29.679] Build status: STARTED
[05:38:29.679] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:46:0) after 4m6s
[05:42:35.699] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:46:0)
[05:42:35.719] Build status: STARTED
[05:42:35.719] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:47:0) after 4m0s
[05:46:36.144] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:47:0)
[05:46:36.164] Build status: STARTED
[05:46:36.164] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:48:0) after 1m41s
[05:48:17.205] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:48:0)
[05:48:17.402] Build status: STARTED
[05:48:17.402] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:49:0) after 1m17s
[05:49:34.421] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:49:0)
[05:49:34.467] Build status: STARTED
[05:49:34.467] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:50:0) after 6m13s
[05:55:47.486] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:50:0)
[05:55:47.502] Build status: STARTED
[05:55:47.503] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:51:0) after 7m30s
[06:03:17.535] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:51:0)
[06:03:17.550] Build status: STARTED
[06:03:17.550] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:52:0) after 1m23s
[06:04:40.574] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:52:0)
[06:04:40.592] Build status: STARTED
[06:04:40.592] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:53:0) after 3m12s
[06:07:52.615] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:53:0)
[06:07:52.634] Build status: STARTED
[06:07:52.634] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-x64-asan:8892685973607435552:54:0) after 9m7s
[06:14:45.783] Received PubSub notification, asking Buildbucket for the build status
[06:14:45.862] Build:
{
"id": "8727255911567096913",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "fuchsia-fyi-x64-asan"
},
"number": 6239,
"createdBy": "project:chromium",
"createTime": "2024-12-29T01:53:26.641443280Z",
"startTime": "2024-12-29T02:12:02.756119392Z",
"endTime": "2024-12-29T06:14:45.390442016Z",
"updateTime": "2024-12-29T06:14:45.390442016Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "bf2d033973bfdc914c34165e405ce1edec01219f",
"ref": "refs/heads/main"
}
}
}
[06:14:45.862] Invocation finished in 4h21m20.55062778s with status SUCCEEDED