[13:37:51.262] New invocation is queued and will start shortly
[13:37:52.477] Starting the invocation (attempt 1)
[13:37:52.509] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/542d7a1066602b57eb3f2d461948f14570171fe0"
[13:37:52.509] Popped gitiles commit info from properties and tags
[13:37:52.509] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:37:52.510] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:37:52.511] Buildbucket request:
{
"requestId": "8957962067338226960",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "fuchsia-fyi-arm64-dbg"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "542d7a1066602b57eb3f2d461948f14570171fe0",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8957962067338226960"
},
{
"key": "scheduler_job_id",
"value": "chromium/fuchsia-fyi-arm64-dbg"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[13:37:53.025] Scheduled build:
{
"id": "8792532005281146497",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "fuchsia-fyi-arm64-dbg"
},
"number": 5576,
"createdBy": "project:chromium",
"createTime": "2023-01-08T13:37:52.591016807Z",
"updateTime": "2023-01-08T13:37:52.591016807Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "542d7a1066602b57eb3f2d461948f14570171fe0",
"ref": "refs/heads/main"
}
}
}
[13:37:53.025] Task URL: https://cr-buildbucket.appspot.com/build/8792532005281146497
[13:37:53.025] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:2:0) after 3m29s
[13:37:54.085] Received PubSub notification, asking Buildbucket for the build status
[13:37:54.125] Build status: STARTED
[13:41:22.041] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:2:0)
[13:41:22.083] Build status: STARTED
[13:41:22.083] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:4:0) after 7m28s
[13:48:50.106] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:4:0)
[13:48:50.139] Build status: STARTED
[13:48:50.139] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:5:0) after 5m33s
[13:54:23.159] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:5:0)
[13:54:23.569] Build status: STARTED
[13:54:23.569] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:6:0) after 8m49s
[14:03:12.585] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:6:0)
[14:03:12.611] Build status: STARTED
[14:03:12.611] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:7:0) after 1m44s
[14:04:56.712] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:7:0)
[14:04:56.732] Build status: STARTED
[14:04:56.732] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:8:0) after 1m43s
[14:06:39.832] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:8:0)
[14:06:39.856] Build status: STARTED
[14:06:39.856] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:9:0) after 9m30s
[14:16:09.913] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:9:0)
[14:16:09.934] Build status: STARTED
[14:16:09.934] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:10:0) after 3m35s
[14:19:44.957] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:10:0)
[14:19:44.976] Build status: STARTED
[14:19:44.976] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:11:0) after 6m47s
[14:26:31.956] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:11:0)
[14:26:31.978] Build status: STARTED
[14:26:31.978] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:12:0) after 2m37s
[14:29:08.996] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:12:0)
[14:29:09.017] Build status: STARTED
[14:29:09.017] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:13:0) after 3m9s
[14:32:18.046] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:13:0)
[14:32:18.067] Build status: STARTED
[14:32:18.067] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:14:0) after 2m46s
[14:35:04.086] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:14:0)
[14:35:04.110] Build status: STARTED
[14:35:04.110] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:15:0) after 5m8s
[14:40:12.145] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:15:0)
[14:40:12.167] Build status: STARTED
[14:40:12.167] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:16:0) after 9m12s
[14:49:24.240] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:16:0)
[14:49:24.261] Build status: STARTED
[14:49:24.261] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:17:0) after 1m7s
[14:50:31.326] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:17:0)
[14:50:31.345] Build status: STARTED
[14:50:31.345] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:18:0) after 6m46s
[14:57:17.379] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:18:0)
[14:57:17.403] Build status: STARTED
[14:57:17.403] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:19:0) after 6m19s
[15:03:36.451] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:19:0)
[15:03:36.473] Build status: STARTED
[15:03:36.473] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:20:0) after 9m34s
[15:13:10.782] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:20:0)
[15:13:10.808] Build status: STARTED
[15:13:10.808] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:21:0) after 9m23s
[15:22:33.897] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:21:0)
[15:22:33.917] Build status: STARTED
[15:22:33.917] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:22:0) after 9m42s
[15:32:15.936] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:22:0)
[15:32:15.962] Build status: STARTED
[15:32:15.962] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:23:0) after 5m26s
[15:37:41.983] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:23:0)
[15:37:42.003] Build status: STARTED
[15:37:42.003] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:24:0) after 5m2s
[15:42:44.021] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:24:0)
[15:42:44.049] Build status: STARTED
[15:42:44.049] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:25:0) after 8m38s
[15:51:22.079] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:25:0)
[15:51:22.099] Build status: STARTED
[15:51:22.099] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:26:0) after 5m7s
[15:56:29.153] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:26:0)
[15:56:29.175] Build status: STARTED
[15:56:29.175] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:27:0) after 2m31s
[15:59:00.201] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:27:0)
[15:59:00.222] Build status: STARTED
[15:59:00.222] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:28:0) after 3m25s
[16:02:25.337] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:28:0)
[16:02:25.365] Build status: STARTED
[16:02:25.365] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:29:0) after 1m39s
[16:04:04.481] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:29:0)
[16:04:04.503] Build status: STARTED
[16:04:04.503] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:30:0) after 6m47s
[16:10:51.608] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:30:0)
[16:10:51.631] Build status: STARTED
[16:10:51.631] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:31:0) after 3m7s
[16:13:58.649] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:31:0)
[16:13:58.670] Build status: STARTED
[16:13:58.670] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:32:0) after 7m58s
[16:21:56.692] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:32:0)
[16:21:56.721] Build status: STARTED
[16:21:56.721] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:33:0) after 7m28s
[16:29:24.741] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:33:0)
[16:29:24.761] Build status: STARTED
[16:29:24.761] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:34:0) after 5m0s
[16:34:24.805] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:34:0)
[16:34:24.827] Build status: STARTED
[16:34:24.827] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:35:0) after 4m28s
[16:38:52.931] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:35:0)
[16:38:52.954] Build status: STARTED
[16:38:52.954] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:36:0) after 8m4s
[16:46:57.061] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:36:0)
[16:46:57.083] Build status: STARTED
[16:46:57.083] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:37:0) after 5m27s
[16:52:24.185] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:37:0)
[16:52:24.205] Build status: STARTED
[16:52:24.205] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:38:0) after 7m49s
[17:00:13.234] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:38:0)
[17:00:13.266] Build status: STARTED
[17:00:13.266] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:39:0) after 3m43s
[17:03:56.384] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:39:0)
[17:03:56.407] Build status: STARTED
[17:03:56.407] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:40:0) after 8m37s
[17:12:33.459] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:40:0)
[17:12:33.483] Build status: STARTED
[17:12:33.483] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:41:0) after 5m43s
[17:18:16.562] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:41:0)
[17:18:16.594] Build status: STARTED
[17:18:16.594] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:42:0) after 4m17s
[17:22:34.204] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:42:0)
[17:22:34.225] Build status: STARTED
[17:22:34.225] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:43:0) after 6m38s
[17:29:12.208] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:43:0)
[17:29:12.228] Build status: STARTED
[17:29:12.228] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:44:0) after 5m41s
[17:34:53.315] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:44:0)
[17:34:53.343] Build status: STARTED
[17:34:53.343] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:45:0) after 9m43s
[17:44:36.401] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:45:0)
[17:44:36.423] Build status: STARTED
[17:44:36.423] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:46:0) after 7m53s
[17:52:29.440] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:46:0)
[17:52:29.462] Build status: STARTED
[17:52:29.462] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:47:0) after 6m38s
[17:59:07.566] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:47:0)
[17:59:07.589] Build status: STARTED
[17:59:07.589] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:48:0) after 4m6s
[18:03:13.635] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:48:0)
[18:03:13.659] Build status: STARTED
[18:03:13.659] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8957962067338226960:49:0) after 5m34s
[18:05:02.402] Received PubSub notification, asking Buildbucket for the build status
[18:05:02.451] Build:
{
"id": "8792532005281146497",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "fuchsia-fyi-arm64-dbg"
},
"number": 5576,
"createdBy": "project:chromium",
"createTime": "2023-01-08T13:37:52.591016807Z",
"startTime": "2023-01-08T13:37:53.550282Z",
"endTime": "2023-01-08T18:05:01.740621398Z",
"updateTime": "2023-01-08T18:05:01.740621398Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "542d7a1066602b57eb3f2d461948f14570171fe0",
"ref": "refs/heads/main"
}
}
}
[18:05:02.451] Invocation finished in 4h27m11.205143069s with status SUCCEEDED