[02:24:54.830] New invocation is queued and will start shortly
[02:24:56.002] Starting the invocation (attempt 1)
[02:24:56.030] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/37b8302574be32e75526c87e5fce60f727d2c5dc"
[02:24:56.030] Popped gitiles commit info from properties and tags
[02:24:56.030] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:24:56.030] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:24:56.031] Buildbucket request:
{
"requestId": "8910712788640492128",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.arm64-kasan"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8910712788640492128",
"job": "fuchsia/core.arm64-kasan-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "8ec865af1b51f310df4073d8aa49243dd2cd347b"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@8ec865af1b51f310df4073d8aa49243dd2cd347b",
"title": "8ec865af1b51f310df4073d8aa49243dd2cd347b",
"url": "https://fuchsia.googlesource.com/integration/+/8ec865af1b51f310df4073d8aa49243dd2cd347b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d1ae033d5247465aafdaf85a7fde6571a5d8bbc6"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d1ae033d5247465aafdaf85a7fde6571a5d8bbc6",
"title": "d1ae033d5247465aafdaf85a7fde6571a5d8bbc6",
"url": "https://fuchsia.googlesource.com/integration/+/d1ae033d5247465aafdaf85a7fde6571a5d8bbc6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "86bbecfdc24c0a2f2b280ececf752955ae6e198f"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@86bbecfdc24c0a2f2b280ececf752955ae6e198f",
"title": "86bbecfdc24c0a2f2b280ececf752955ae6e198f",
"url": "https://fuchsia.googlesource.com/integration/+/86bbecfdc24c0a2f2b280ececf752955ae6e198f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c84e883bb5a0ae42d6a56b9af85672f60a585323"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c84e883bb5a0ae42d6a56b9af85672f60a585323",
"title": "c84e883bb5a0ae42d6a56b9af85672f60a585323",
"url": "https://fuchsia.googlesource.com/integration/+/c84e883bb5a0ae42d6a56b9af85672f60a585323"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "7c5cb85ac69f101f5199c5e2ffe15bf6f82e92e3"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@7c5cb85ac69f101f5199c5e2ffe15bf6f82e92e3",
"title": "7c5cb85ac69f101f5199c5e2ffe15bf6f82e92e3",
"url": "https://fuchsia.googlesource.com/integration/+/7c5cb85ac69f101f5199c5e2ffe15bf6f82e92e3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "14e7ed32ee2f699a377c142dbb771cff77fbca23"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@14e7ed32ee2f699a377c142dbb771cff77fbca23",
"title": "14e7ed32ee2f699a377c142dbb771cff77fbca23",
"url": "https://fuchsia.googlesource.com/integration/+/14e7ed32ee2f699a377c142dbb771cff77fbca23"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "4e24cc2d2d6f5004ae06a2d312ad74b1874af7ce"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@4e24cc2d2d6f5004ae06a2d312ad74b1874af7ce",
"title": "4e24cc2d2d6f5004ae06a2d312ad74b1874af7ce",
"url": "https://fuchsia.googlesource.com/integration/+/4e24cc2d2d6f5004ae06a2d312ad74b1874af7ce"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "bb895b00dd008a644147f59d86fe133065fb5d53"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@bb895b00dd008a644147f59d86fe133065fb5d53",
"title": "bb895b00dd008a644147f59d86fe133065fb5d53",
"url": "https://fuchsia.googlesource.com/integration/+/bb895b00dd008a644147f59d86fe133065fb5d53"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "46fbcdd1d841c3da3febb5a92fd508163c96c888"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@46fbcdd1d841c3da3febb5a92fd508163c96c888",
"title": "46fbcdd1d841c3da3febb5a92fd508163c96c888",
"url": "https://fuchsia.googlesource.com/integration/+/46fbcdd1d841c3da3febb5a92fd508163c96c888"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "8508cb8b8964d2295344dba3e96fcba6c1cf1fd5"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@8508cb8b8964d2295344dba3e96fcba6c1cf1fd5",
"title": "8508cb8b8964d2295344dba3e96fcba6c1cf1fd5",
"url": "https://fuchsia.googlesource.com/integration/+/8508cb8b8964d2295344dba3e96fcba6c1cf1fd5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "042ac60383f806644833cd944a425b8c30c045cc"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@042ac60383f806644833cd944a425b8c30c045cc",
"title": "042ac60383f806644833cd944a425b8c30c045cc",
"url": "https://fuchsia.googlesource.com/integration/+/042ac60383f806644833cd944a425b8c30c045cc"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d41c4253ee7362f40de1aefc76c41261e442e12e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d41c4253ee7362f40de1aefc76c41261e442e12e",
"title": "d41c4253ee7362f40de1aefc76c41261e442e12e",
"url": "https://fuchsia.googlesource.com/integration/+/d41c4253ee7362f40de1aefc76c41261e442e12e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "43b2d40d77d6f99645eeb018fe663efaf95be3ae"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@43b2d40d77d6f99645eeb018fe663efaf95be3ae",
"title": "43b2d40d77d6f99645eeb018fe663efaf95be3ae",
"url": "https://fuchsia.googlesource.com/integration/+/43b2d40d77d6f99645eeb018fe663efaf95be3ae"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b8bb300713889547fad5b5aefc1e3788bd1cacad"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b8bb300713889547fad5b5aefc1e3788bd1cacad",
"title": "b8bb300713889547fad5b5aefc1e3788bd1cacad",
"url": "https://fuchsia.googlesource.com/integration/+/b8bb300713889547fad5b5aefc1e3788bd1cacad"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "6173d506a3705640b492e93274030bd327c2d8ac"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@6173d506a3705640b492e93274030bd327c2d8ac",
"title": "6173d506a3705640b492e93274030bd327c2d8ac",
"url": "https://fuchsia.googlesource.com/integration/+/6173d506a3705640b492e93274030bd327c2d8ac"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "37b8302574be32e75526c87e5fce60f727d2c5dc"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@37b8302574be32e75526c87e5fce60f727d2c5dc",
"title": "37b8302574be32e75526c87e5fce60f727d2c5dc",
"url": "https://fuchsia.googlesource.com/integration/+/37b8302574be32e75526c87e5fce60f727d2c5dc"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "37b8302574be32e75526c87e5fce60f727d2c5dc",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8910712788640492128"
},
{
"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"
}
}
[02:24:56.522] Scheduled build:
{
"id": "8745282726621434945",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.arm64-kasan"
},
"createdBy": "project:fuchsia",
"createTime": "2024-06-13T02:24:56.117996231Z",
"updateTime": "2024-06-13T02:24:56.117996231Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "37b8302574be32e75526c87e5fce60f727d2c5dc",
"ref": "refs/heads/main"
}
},
"canary": true
}
[02:24:56.522] Task URL: https://cr-buildbucket.appspot.com/build/8745282726621434945
[02:24:56.522] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8910712788640492128:2:0) after 9m53s
[02:24:57.465] Received PubSub notification, asking Buildbucket for the build status
[02:24:57.489] Build status: SCHEDULED
[02:26:30.088] Received PubSub notification, asking Buildbucket for the build status
[02:26:30.120] Build status: STARTED
[02:34:49.539] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8910712788640492128:2:0)
[02:34:49.657] Build status: STARTED
[02:34:49.657] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8910712788640492128:5:0) after 1m36s
[02:36:25.674] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8910712788640492128:5:0)
[02:36:25.828] Build status: STARTED
[02:36:25.828] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8910712788640492128:6:0) after 9m5s
[02:45:30.844] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8910712788640492128:6:0)
[02:45:30.865] Build status: STARTED
[02:45:30.865] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8910712788640492128:7:0) after 8m41s
[02:54:11.848] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8910712788640492128:7:0)
[02:54:11.875] Build status: STARTED
[02:54:11.875] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8910712788640492128:8:0) after 5m37s
[02:59:48.950] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8910712788640492128:8:0)
[02:59:48.970] Build status: STARTED
[02:59:48.970] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8910712788640492128:9:0) after 5m33s
[03:05:22.017] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8910712788640492128:9:0)
[03:05:22.037] Build status: STARTED
[03:05:22.037] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8910712788640492128:10:0) after 1m42s
[03:07:04.130] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8910712788640492128:10:0)
[03:07:04.149] Build status: STARTED
[03:07:04.149] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8910712788640492128:11:0) after 1m13s
[03:08:17.166] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8910712788640492128:11:0)
[03:08:17.184] Build status: STARTED
[03:08:17.184] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8910712788640492128:12:0) after 6m5s
[03:14:22.246] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8910712788640492128:12:0)
[03:14:22.268] Build status: STARTED
[03:14:22.268] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8910712788640492128:13:0) after 8m9s
[03:22:31.262] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8910712788640492128:13:0)
[03:22:31.279] Build status: STARTED
[03:22:31.279] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8910712788640492128:14:0) after 9m35s
[03:32:06.302] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8910712788640492128:14:0)
[03:32:06.384] Build status: STARTED
[03:32:06.384] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8910712788640492128:15:0) after 2m29s
[03:34:35.403] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8910712788640492128:15:0)
[03:34:35.555] Build status: STARTED
[03:34:35.555] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8910712788640492128:16:0) after 4m16s
[03:38:51.579] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8910712788640492128:16:0)
[03:38:51.599] Build status: STARTED
[03:38:51.599] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8910712788640492128:17:0) after 1m15s
[03:40:06.615] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8910712788640492128:17:0)
[03:40:06.639] Build status: STARTED
[03:40:06.639] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8910712788640492128:18:0) after 7m57s
[03:48:03.638] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8910712788640492128:18:0)
[03:48:03.696] Build status: STARTED
[03:48:03.696] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8910712788640492128:19:0) after 5m25s
[03:53:28.717] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8910712788640492128:19:0)
[03:53:28.739] Build status: STARTED
[03:53:28.739] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8910712788640492128:20:0) after 6m26s
[03:59:54.803] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8910712788640492128:20:0)
[03:59:54.821] Build status: STARTED
[03:59:54.821] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8910712788640492128:21:0) after 8m7s
[04:08:01.847] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8910712788640492128:21:0)
[04:08:01.879] Build status: STARTED
[04:08:01.879] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8910712788640492128:22:0) after 3m33s
[04:11:34.899] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8910712788640492128:22:0)
[04:11:34.923] Build status: STARTED
[04:11:34.923] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8910712788640492128:23:0) after 7m41s
[04:19:15.949] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8910712788640492128:23:0)
[04:19:15.973] Build status: STARTED
[04:19:15.973] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8910712788640492128:24:0) after 4m12s
[04:23:27.977] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8910712788640492128:24:0)
[04:23:28.065] Build status: STARTED
[04:23:28.065] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8910712788640492128:25:0) after 4m5s
[04:27:33.207] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8910712788640492128:25:0)
[04:27:33.224] Build status: STARTED
[04:27:33.224] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8910712788640492128:26:0) after 2m40s
[04:30:13.255] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8910712788640492128:26:0)
[04:30:13.275] Build status: STARTED
[04:30:13.276] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8910712788640492128:27:0) after 9m45s
[04:39:58.300] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8910712788640492128:27:0)
[04:39:58.318] Build status: STARTED
[04:39:58.318] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8910712788640492128:28:0) after 8m22s
[04:48:20.348] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8910712788640492128:28:0)
[04:48:20.366] Build status: STARTED
[04:48:20.366] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8910712788640492128:29:0) after 1m48s
[04:50:08.388] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8910712788640492128:29:0)
[04:50:08.467] Build status: STARTED
[04:50:08.467] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8910712788640492128:30:0) after 5m17s
[04:51:25.644] Received PubSub notification, asking Buildbucket for the build status
[04:51:25.695] Build:
{
"id": "8745282726621434945",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.arm64-kasan"
},
"createdBy": "project:fuchsia",
"createTime": "2024-06-13T02:24:56.117996231Z",
"startTime": "2024-06-13T02:26:29.511762035Z",
"endTime": "2024-06-13T04:51:25.341472064Z",
"updateTime": "2024-06-13T04:51:25.341472064Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "37b8302574be32e75526c87e5fce60f727d2c5dc",
"ref": "refs/heads/main"
}
},
"canary": true
}
[04:51:25.695] Invocation finished in 2h26m30.886678251s with status SUCCEEDED