[16:02:28.427] New invocation is queued and will start shortly
[16:02:29.637] Starting the invocation (attempt 1)
[16:02:29.661] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/1577bf4261875bc2f51904fd415c1798b6e65228"
[16:02:29.661] Popped gitiles commit info from properties and tags
[16:02:29.662] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:02:29.662] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:02:29.662] Buildbucket request:
{
"requestId": "8899155537475638464",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.arm64-kasan"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8899155537475638464",
"job": "fuchsia/core.arm64-kasan-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "50325b84d1243e870a6c071e040c297071f13d07"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@50325b84d1243e870a6c071e040c297071f13d07",
"title": "50325b84d1243e870a6c071e040c297071f13d07",
"url": "https://fuchsia.googlesource.com/integration/+/50325b84d1243e870a6c071e040c297071f13d07"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "8632c35657f796c8bcd3553fb1264004661003db"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@8632c35657f796c8bcd3553fb1264004661003db",
"title": "8632c35657f796c8bcd3553fb1264004661003db",
"url": "https://fuchsia.googlesource.com/integration/+/8632c35657f796c8bcd3553fb1264004661003db"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c8f663fad1922bb3c5c1c014c79bcf9eadedc3ff"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c8f663fad1922bb3c5c1c014c79bcf9eadedc3ff",
"title": "c8f663fad1922bb3c5c1c014c79bcf9eadedc3ff",
"url": "https://fuchsia.googlesource.com/integration/+/c8f663fad1922bb3c5c1c014c79bcf9eadedc3ff"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "ef53ff8528c117ede7c0bf7b45c9effb7eebbcf3"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@ef53ff8528c117ede7c0bf7b45c9effb7eebbcf3",
"title": "ef53ff8528c117ede7c0bf7b45c9effb7eebbcf3",
"url": "https://fuchsia.googlesource.com/integration/+/ef53ff8528c117ede7c0bf7b45c9effb7eebbcf3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "61e471b4dd99f620157d144106586466e0b67989"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@61e471b4dd99f620157d144106586466e0b67989",
"title": "61e471b4dd99f620157d144106586466e0b67989",
"url": "https://fuchsia.googlesource.com/integration/+/61e471b4dd99f620157d144106586466e0b67989"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c011c41fe975b7c80e21182a91e9fc6dcced008e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c011c41fe975b7c80e21182a91e9fc6dcced008e",
"title": "c011c41fe975b7c80e21182a91e9fc6dcced008e",
"url": "https://fuchsia.googlesource.com/integration/+/c011c41fe975b7c80e21182a91e9fc6dcced008e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "1577bf4261875bc2f51904fd415c1798b6e65228"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@1577bf4261875bc2f51904fd415c1798b6e65228",
"title": "1577bf4261875bc2f51904fd415c1798b6e65228",
"url": "https://fuchsia.googlesource.com/integration/+/1577bf4261875bc2f51904fd415c1798b6e65228"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "1577bf4261875bc2f51904fd415c1798b6e65228",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8899155537475638464"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/core.arm64-kasan-e5a0ec7f"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[16:02:31.642] Scheduled build:
{
"id": "8733725475465152721",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.arm64-kasan"
},
"createdBy": "project:fuchsia",
"createTime": "2024-10-18T16:02:29.713193532Z",
"updateTime": "2024-10-18T16:02:29.713193532Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "1577bf4261875bc2f51904fd415c1798b6e65228",
"ref": "refs/heads/main"
}
}
}
[16:02:31.642] Task URL: https://cr-buildbucket.appspot.com/build/8733725475465152721
[16:02:31.642] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8899155537475638464:2:0) after 9m29s
[16:02:32.571] Received PubSub notification, asking Buildbucket for the build status
[16:02:32.638] Build status: SCHEDULED
[16:02:37.339] Received PubSub notification, asking Buildbucket for the build status
[16:02:37.376] Build status: STARTED
[16:12:00.694] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8899155537475638464:2:0)
[16:12:00.766] Build status: STARTED
[16:12:00.766] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8899155537475638464:5:0) after 2m57s
[16:14:57.797] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8899155537475638464:5:0)
[16:14:57.826] Build status: STARTED
[16:14:57.826] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8899155537475638464:6:0) after 5m48s
[16:20:45.914] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8899155537475638464:6:0)
[16:20:45.931] Build status: STARTED
[16:20:45.931] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8899155537475638464:7:0) after 9m40s
[16:30:25.957] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8899155537475638464:7:0)
[16:30:25.977] Build status: STARTED
[16:30:25.977] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8899155537475638464:8:0) after 5m2s
[16:35:27.976] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8899155537475638464:8:0)
[16:35:27.996] Build status: STARTED
[16:35:27.996] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8899155537475638464:9:0) after 5m57s
[16:41:25.010] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8899155537475638464:9:0)
[16:41:25.028] Build status: STARTED
[16:41:25.028] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8899155537475638464:10:0) after 2m3s
[16:43:28.044] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8899155537475638464:10:0)
[16:43:28.063] Build status: STARTED
[16:43:28.063] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8899155537475638464:11:0) after 5m39s
[16:49:07.147] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8899155537475638464:11:0)
[16:49:07.167] Build status: STARTED
[16:49:07.167] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8899155537475638464:12:0) after 1m8s
[16:50:15.210] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8899155537475638464:12:0)
[16:50:15.238] Build status: STARTED
[16:50:15.239] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8899155537475638464:13:0) after 3m50s
[16:54:05.255] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8899155537475638464:13:0)
[16:54:05.272] Build status: STARTED
[16:54:05.272] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8899155537475638464:14:0) after 3m48s
[16:57:53.282] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8899155537475638464:14:0)
[16:57:53.300] Build status: STARTED
[16:57:53.300] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8899155537475638464:15:0) after 4m44s
[17:02:37.414] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8899155537475638464:15:0)
[17:02:37.441] Build status: STARTED
[17:02:37.441] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8899155537475638464:16:0) after 9m53s
[17:12:30.462] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8899155537475638464:16:0)
[17:12:30.481] Build status: STARTED
[17:12:30.481] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8899155537475638464:17:0) after 2m8s
[17:14:38.504] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8899155537475638464:17:0)
[17:14:38.520] Build status: STARTED
[17:14:38.520] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8899155537475638464:18:0) after 2m30s
[17:17:08.559] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8899155537475638464:18:0)
[17:17:08.577] Build status: STARTED
[17:17:08.577] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8899155537475638464:19:0) after 3m39s
[17:20:47.593] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8899155537475638464:19:0)
[17:20:47.628] Build status: STARTED
[17:20:47.628] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8899155537475638464:20:0) after 4m23s
[17:25:10.669] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8899155537475638464:20:0)
[17:25:10.687] Build status: STARTED
[17:25:10.687] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8899155537475638464:21:0) after 4m9s
[17:29:19.811] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8899155537475638464:21:0)
[17:29:19.828] Build status: STARTED
[17:29:19.828] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8899155537475638464:22:0) after 7m6s
[17:36:25.882] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8899155537475638464:22:0)
[17:36:25.907] Build status: STARTED
[17:36:25.907] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8899155537475638464:23:0) after 5m41s
[17:42:06.925] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8899155537475638464:23:0)
[17:42:06.952] Build status: STARTED
[17:42:06.952] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8899155537475638464:24:0) after 8m18s
[17:50:24.966] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8899155537475638464:24:0)
[17:50:24.988] Build status: STARTED
[17:50:24.988] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8899155537475638464:25:0) after 1m26s
[17:51:51.025] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8899155537475638464:25:0)
[17:51:51.040] Build status: STARTED
[17:51:51.040] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8899155537475638464:26:0) after 1m0s
[17:52:51.075] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8899155537475638464:26:0)
[17:52:51.093] Build status: STARTED
[17:52:51.093] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8899155537475638464:27:0) after 5m15s
[17:58:06.121] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8899155537475638464:27:0)
[17:58:06.137] Build status: STARTED
[17:58:06.137] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8899155537475638464:28:0) after 8m47s
[18:06:53.243] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8899155537475638464:28:0)
[18:06:53.340] Build status: STARTED
[18:06:53.340] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8899155537475638464:29:0) after 1m9s
[18:07:06.698] Received PubSub notification, asking Buildbucket for the build status
[18:07:06.714] Build:
{
"id": "8733725475465152721",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.arm64-kasan"
},
"createdBy": "project:fuchsia",
"createTime": "2024-10-18T16:02:29.713193532Z",
"startTime": "2024-10-18T16:02:37.105903813Z",
"endTime": "2024-10-18T18:07:00.959070673Z",
"updateTime": "2024-10-18T18:07:00.959070673Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "1577bf4261875bc2f51904fd415c1798b6e65228",
"ref": "refs/heads/main"
}
}
}
[18:07:06.714] Invocation finished in 2h4m38.301520072s with status SUCCEEDED