[01:14:05.507] New invocation is queued and will start shortly
[01:14:06.774] Starting the invocation (attempt 1)
[01:14:06.808] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9440f41128375c95da78fba6735a8c87b22f19c9"
[01:14:06.808] Popped gitiles commit info from properties and tags
[01:14:06.808] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:14:06.808] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:14:06.808] Buildbucket request:
{
"requestId": "8916606047191123888",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "fuchsia-fyi-arm64-dbg"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "9440f41128375c95da78fba6735a8c87b22f19c9",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8916606047191123888"
},
{
"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"
}
}
[01:14:07.464] Scheduled build:
{
"id": "8751175985071874689",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "fuchsia-fyi-arm64-dbg"
},
"number": 7994,
"createdBy": "project:chromium",
"createTime": "2024-04-09T01:14:06.893802527Z",
"updateTime": "2024-04-09T01:14:06.893802527Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "9440f41128375c95da78fba6735a8c87b22f19c9",
"ref": "refs/heads/main"
}
}
}
[01:14:07.464] Task URL: https://cr-buildbucket.appspot.com/build/8751175985071874689
[01:14:07.464] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:2:0) after 9m2s
[01:14:08.469] Received PubSub notification, asking Buildbucket for the build status
[01:14:08.500] Build status: SCHEDULED
[01:14:14.150] Received PubSub notification, asking Buildbucket for the build status
[01:14:14.191] Build status: STARTED
[01:23:09.496] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:2:0)
[01:23:09.521] Build status: STARTED
[01:23:09.521] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:5:0) after 6m9s
[01:29:18.570] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:5:0)
[01:29:18.602] Build status: STARTED
[01:29:18.602] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:6:0) after 7m18s
[01:36:36.636] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:6:0)
[01:36:36.655] Build status: STARTED
[01:36:36.655] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:7:0) after 2m10s
[01:38:46.740] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:7:0)
[01:38:46.804] Build status: STARTED
[01:38:46.804] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:8:0) after 7m42s
[01:46:28.837] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:8:0)
[01:46:28.861] Build status: STARTED
[01:46:28.861] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:9:0) after 6m16s
[01:52:44.888] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:9:0)
[01:52:44.909] Build status: STARTED
[01:52:44.909] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:10:0) after 1m11s
[01:53:55.973] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:10:0)
[01:53:55.996] Build status: STARTED
[01:53:55.996] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:11:0) after 2m44s
[01:56:40.017] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:11:0)
[01:56:40.045] Build status: STARTED
[01:56:40.045] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:12:0) after 9m11s
[02:05:51.068] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:12:0)
[02:05:51.126] Build status: STARTED
[02:05:51.126] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:13:0) after 3m5s
[02:08:56.218] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:13:0)
[02:08:56.394] Build status: STARTED
[02:08:56.394] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:14:0) after 4m28s
[02:13:24.528] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:14:0)
[02:13:24.589] Build status: STARTED
[02:13:24.589] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:15:0) after 5m58s
[02:19:22.629] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:15:0)
[02:19:22.650] Build status: STARTED
[02:19:22.650] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:16:0) after 6m9s
[02:25:31.709] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:16:0)
[02:25:31.732] Build status: STARTED
[02:25:31.732] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:17:0) after 7m30s
[02:33:01.759] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:17:0)
[02:33:01.798] Build status: STARTED
[02:33:01.798] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:18:0) after 3m41s
[02:36:42.780] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:18:0)
[02:36:42.802] Build status: STARTED
[02:36:42.802] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:19:0) after 2m11s
[02:38:53.841] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:19:0)
[02:38:53.859] Build status: STARTED
[02:38:53.859] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:20:0) after 8m58s
[02:47:51.920] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:20:0)
[02:47:51.945] Build status: STARTED
[02:47:51.945] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:21:0) after 4m19s
[02:52:11.118] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:21:0)
[02:52:11.149] Build status: STARTED
[02:52:11.149] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:22:0) after 4m51s
[02:57:02.248] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:22:0)
[02:57:02.270] Build status: STARTED
[02:57:02.270] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:23:0) after 8m8s
[03:05:10.378] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:23:0)
[03:05:10.398] Build status: STARTED
[03:05:10.398] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:24:0) after 1m13s
[03:06:23.518] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:24:0)
[03:06:23.552] Build status: STARTED
[03:06:23.552] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:25:0) after 2m7s
[03:08:30.550] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:25:0)
[03:08:30.681] Build status: STARTED
[03:08:30.681] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:26:0) after 1m38s
[03:10:08.858] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:26:0)
[03:10:08.884] Build status: STARTED
[03:10:08.884] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:27:0) after 2m11s
[03:12:19.941] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:27:0)
[03:12:19.965] Build status: STARTED
[03:12:19.965] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:28:0) after 7m17s
[03:19:37.006] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:28:0)
[03:19:37.037] Build status: STARTED
[03:19:37.037] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:29:0) after 7m57s
[03:27:34.176] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:29:0)
[03:27:34.194] Build status: STARTED
[03:27:34.194] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:30:0) after 1m18s
[03:28:52.328] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:30:0)
[03:28:52.346] Build status: STARTED
[03:28:52.346] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:31:0) after 8m27s
[03:37:19.403] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:31:0)
[03:37:19.488] Build status: STARTED
[03:37:19.488] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:32:0) after 7m44s
[03:45:03.567] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:32:0)
[03:45:03.585] Build status: STARTED
[03:45:03.585] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:33:0) after 4m15s
[03:49:18.702] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:33:0)
[03:49:18.723] Build status: STARTED
[03:49:18.723] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:34:0) after 7m16s
[03:56:34.748] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:34:0)
[03:56:34.764] Build status: STARTED
[03:56:34.764] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:35:0) after 8m54s
[04:05:28.784] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:35:0)
[04:05:28.814] Build status: STARTED
[04:05:28.814] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:36:0) after 7m57s
[04:13:25.923] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:36:0)
[04:13:25.943] Build status: STARTED
[04:13:25.943] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:37:0) after 2m10s
[04:15:36.013] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:37:0)
[04:15:36.036] Build status: STARTED
[04:15:36.036] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:38:0) after 9m44s
[04:25:20.344] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:38:0)
[04:25:20.371] Build status: STARTED
[04:25:20.371] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:39:0) after 5m22s
[04:30:42.702] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:39:0)
[04:30:42.737] Build status: STARTED
[04:30:42.737] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:40:0) after 7m24s
[04:38:06.901] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:40:0)
[04:38:06.923] Build status: STARTED
[04:38:06.923] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:41:0) after 9m43s
[04:47:49.984] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:41:0)
[04:47:50.008] Build status: STARTED
[04:47:50.008] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:42:0) after 4m46s
[04:52:36.040] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:42:0)
[04:52:36.057] Build status: STARTED
[04:52:36.057] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:43:0) after 8m35s
[05:01:11.187] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:43:0)
[05:01:11.213] Build status: STARTED
[05:01:11.213] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:44:0) after 7m10s
[05:08:21.253] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:44:0)
[05:08:21.299] Build status: STARTED
[05:08:21.299] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:45:0) after 2m14s
[05:10:35.336] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:45:0)
[05:10:35.427] Build status: STARTED
[05:10:35.427] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:46:0) after 3m57s
[05:14:32.712] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:46:0)
[05:14:32.736] Build status: STARTED
[05:14:32.736] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:47:0) after 2m59s
[05:17:31.762] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:47:0)
[05:17:31.782] Build status: STARTED
[05:17:31.782] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:48:0) after 6m38s
[05:24:09.817] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:48:0)
[05:24:09.841] Build status: STARTED
[05:24:09.841] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-fyi-arm64-dbg:8916606047191123888:49:0) after 7m5s
[05:25:15.971] Received PubSub notification, asking Buildbucket for the build status
[05:25:15.989] Build:
{
"id": "8751175985071874689",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "fuchsia-fyi-arm64-dbg"
},
"number": 7994,
"createdBy": "project:chromium",
"createTime": "2024-04-09T01:14:06.893802527Z",
"startTime": "2024-04-09T01:14:13.945711563Z",
"endTime": "2024-04-09T05:25:15.395494133Z",
"updateTime": "2024-04-09T05:25:15.395494133Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "9440f41128375c95da78fba6735a8c87b22f19c9",
"ref": "refs/heads/main"
}
}
}
[05:25:15.989] Invocation finished in 4h11m10.500374665s with status SUCCEEDED