[00:54:58.686] New invocation is queued and will start shortly
[00:55:00.788] Starting the invocation (attempt 1)
[00:55:00.817] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/510faba094f594bce7f03e1b80c03457e3918453"
[00:55:00.817] Popped gitiles commit info from properties and tags
[00:55:00.817] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:55:00.817] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:55:00.818] Buildbucket request:
{
"requestId": "8905463822839590544",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.arm64-kasan"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8905463822839590544",
"job": "fuchsia/core.arm64-kasan-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "f86b49ddf55c79ea27932cac30cbe7386848660c"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@f86b49ddf55c79ea27932cac30cbe7386848660c",
"title": "f86b49ddf55c79ea27932cac30cbe7386848660c",
"url": "https://fuchsia.googlesource.com/integration/+/f86b49ddf55c79ea27932cac30cbe7386848660c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "9f941250fef604bc334c8c4290f64ddcddab5b5e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@9f941250fef604bc334c8c4290f64ddcddab5b5e",
"title": "9f941250fef604bc334c8c4290f64ddcddab5b5e",
"url": "https://fuchsia.googlesource.com/integration/+/9f941250fef604bc334c8c4290f64ddcddab5b5e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "eb78f411edd9db4600832482145110d7adb08c1b"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@eb78f411edd9db4600832482145110d7adb08c1b",
"title": "eb78f411edd9db4600832482145110d7adb08c1b",
"url": "https://fuchsia.googlesource.com/integration/+/eb78f411edd9db4600832482145110d7adb08c1b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "aa36dfb98a1e8a35447117999f22b927306e9b82"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@aa36dfb98a1e8a35447117999f22b927306e9b82",
"title": "aa36dfb98a1e8a35447117999f22b927306e9b82",
"url": "https://fuchsia.googlesource.com/integration/+/aa36dfb98a1e8a35447117999f22b927306e9b82"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "2cfba006358d48e2ee77e052cd66abf151eb150e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@2cfba006358d48e2ee77e052cd66abf151eb150e",
"title": "2cfba006358d48e2ee77e052cd66abf151eb150e",
"url": "https://fuchsia.googlesource.com/integration/+/2cfba006358d48e2ee77e052cd66abf151eb150e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "6bee68d84ef1598adc40183ce9289f56dbc1cbe9"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@6bee68d84ef1598adc40183ce9289f56dbc1cbe9",
"title": "6bee68d84ef1598adc40183ce9289f56dbc1cbe9",
"url": "https://fuchsia.googlesource.com/integration/+/6bee68d84ef1598adc40183ce9289f56dbc1cbe9"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "fb689424036de5914bee91e1fb2979117b9ea3d3"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@fb689424036de5914bee91e1fb2979117b9ea3d3",
"title": "fb689424036de5914bee91e1fb2979117b9ea3d3",
"url": "https://fuchsia.googlesource.com/integration/+/fb689424036de5914bee91e1fb2979117b9ea3d3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a0dbd4a1afef6af41b732742d318af40811e55e6"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a0dbd4a1afef6af41b732742d318af40811e55e6",
"title": "a0dbd4a1afef6af41b732742d318af40811e55e6",
"url": "https://fuchsia.googlesource.com/integration/+/a0dbd4a1afef6af41b732742d318af40811e55e6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "8fc2e6b3b4cad5beccd0178923479d230df870f9"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@8fc2e6b3b4cad5beccd0178923479d230df870f9",
"title": "8fc2e6b3b4cad5beccd0178923479d230df870f9",
"url": "https://fuchsia.googlesource.com/integration/+/8fc2e6b3b4cad5beccd0178923479d230df870f9"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b74410b86b9eba59be4244069dd3b2b6a61f74a7"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b74410b86b9eba59be4244069dd3b2b6a61f74a7",
"title": "b74410b86b9eba59be4244069dd3b2b6a61f74a7",
"url": "https://fuchsia.googlesource.com/integration/+/b74410b86b9eba59be4244069dd3b2b6a61f74a7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "85ccbd381ef95df9a64582806e7df0df4a690117"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@85ccbd381ef95df9a64582806e7df0df4a690117",
"title": "85ccbd381ef95df9a64582806e7df0df4a690117",
"url": "https://fuchsia.googlesource.com/integration/+/85ccbd381ef95df9a64582806e7df0df4a690117"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "dde61ed3b0b1d0285abf674b607abb502bef28ed"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@dde61ed3b0b1d0285abf674b607abb502bef28ed",
"title": "dde61ed3b0b1d0285abf674b607abb502bef28ed",
"url": "https://fuchsia.googlesource.com/integration/+/dde61ed3b0b1d0285abf674b607abb502bef28ed"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "ac928234b35410e69f1057742d379db9e2f43ae5"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@ac928234b35410e69f1057742d379db9e2f43ae5",
"title": "ac928234b35410e69f1057742d379db9e2f43ae5",
"url": "https://fuchsia.googlesource.com/integration/+/ac928234b35410e69f1057742d379db9e2f43ae5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "64047a62d4aeb24fdd43d637abdb7279ab823e59"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@64047a62d4aeb24fdd43d637abdb7279ab823e59",
"title": "64047a62d4aeb24fdd43d637abdb7279ab823e59",
"url": "https://fuchsia.googlesource.com/integration/+/64047a62d4aeb24fdd43d637abdb7279ab823e59"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "0e7e00696999990e7c3f0c31fb05e5b57b5e8e61"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@0e7e00696999990e7c3f0c31fb05e5b57b5e8e61",
"title": "0e7e00696999990e7c3f0c31fb05e5b57b5e8e61",
"url": "https://fuchsia.googlesource.com/integration/+/0e7e00696999990e7c3f0c31fb05e5b57b5e8e61"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "2b182d9744774c392cc8c6962ca4c38c2c1eb34b"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@2b182d9744774c392cc8c6962ca4c38c2c1eb34b",
"title": "2b182d9744774c392cc8c6962ca4c38c2c1eb34b",
"url": "https://fuchsia.googlesource.com/integration/+/2b182d9744774c392cc8c6962ca4c38c2c1eb34b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "1d489ff7c10b5cfdaaa15dcd99ef8342ddaa3051"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@1d489ff7c10b5cfdaaa15dcd99ef8342ddaa3051",
"title": "1d489ff7c10b5cfdaaa15dcd99ef8342ddaa3051",
"url": "https://fuchsia.googlesource.com/integration/+/1d489ff7c10b5cfdaaa15dcd99ef8342ddaa3051"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "4298136da57b6bfc495366473e0c237d9997110a"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@4298136da57b6bfc495366473e0c237d9997110a",
"title": "4298136da57b6bfc495366473e0c237d9997110a",
"url": "https://fuchsia.googlesource.com/integration/+/4298136da57b6bfc495366473e0c237d9997110a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "9e4e508c77752129124b209208d1758c2e0b6289"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@9e4e508c77752129124b209208d1758c2e0b6289",
"title": "9e4e508c77752129124b209208d1758c2e0b6289",
"url": "https://fuchsia.googlesource.com/integration/+/9e4e508c77752129124b209208d1758c2e0b6289"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "80b61d63ebd8378f4cc82f0f343819bb917bca74"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@80b61d63ebd8378f4cc82f0f343819bb917bca74",
"title": "80b61d63ebd8378f4cc82f0f343819bb917bca74",
"url": "https://fuchsia.googlesource.com/integration/+/80b61d63ebd8378f4cc82f0f343819bb917bca74"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "fedd784ac071b358f001c11b31dba4012809d820"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@fedd784ac071b358f001c11b31dba4012809d820",
"title": "fedd784ac071b358f001c11b31dba4012809d820",
"url": "https://fuchsia.googlesource.com/integration/+/fedd784ac071b358f001c11b31dba4012809d820"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "510faba094f594bce7f03e1b80c03457e3918453"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@510faba094f594bce7f03e1b80c03457e3918453",
"title": "510faba094f594bce7f03e1b80c03457e3918453",
"url": "https://fuchsia.googlesource.com/integration/+/510faba094f594bce7f03e1b80c03457e3918453"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "510faba094f594bce7f03e1b80c03457e3918453",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8905463822839590544"
},
{
"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"
}
}
[00:55:01.245] Scheduled build:
{
"id": "8740033759880040945",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.arm64-kasan"
},
"createdBy": "project:fuchsia",
"createTime": "2024-08-10T00:55:00.886211992Z",
"updateTime": "2024-08-10T00:55:00.886211992Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "510faba094f594bce7f03e1b80c03457e3918453",
"ref": "refs/heads/main"
}
}
}
[00:55:01.245] Task URL: https://cr-buildbucket.appspot.com/build/8740033759880040945
[00:55:01.245] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8905463822839590544:2:0) after 5m21s
[00:55:02.166] Received PubSub notification, asking Buildbucket for the build status
[00:55:02.216] Build status: SCHEDULED
[00:56:54.487] Received PubSub notification, asking Buildbucket for the build status
[00:56:54.514] Build status: STARTED
[01:00:22.329] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8905463822839590544:2:0)
[01:00:22.370] Build status: STARTED
[01:00:22.370] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8905463822839590544:5:0) after 9m43s
[01:10:05.394] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8905463822839590544:5:0)
[01:10:05.415] Build status: STARTED
[01:10:05.415] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8905463822839590544:6:0) after 9m18s
[01:19:23.609] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8905463822839590544:6:0)
[01:19:23.642] Build status: STARTED
[01:19:23.642] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8905463822839590544:7:0) after 2m44s
[01:22:07.669] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8905463822839590544:7:0)
[01:22:07.704] Build status: STARTED
[01:22:07.704] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8905463822839590544:8:0) after 9m5s
[01:31:12.771] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8905463822839590544:8:0)
[01:31:12.792] Build status: STARTED
[01:31:12.792] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8905463822839590544:9:0) after 9m17s
[01:40:29.816] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8905463822839590544:9:0)
[01:40:29.844] Build status: STARTED
[01:40:29.844] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8905463822839590544:10:0) after 9m22s
[01:49:51.862] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8905463822839590544:10:0)
[01:49:51.881] Build status: STARTED
[01:49:51.881] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8905463822839590544:11:0) after 5m19s
[01:55:10.911] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8905463822839590544:11:0)
[01:55:10.944] Build status: STARTED
[01:55:10.944] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8905463822839590544:12:0) after 3m26s
[01:58:36.962] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8905463822839590544:12:0)
[01:58:36.981] Build status: STARTED
[01:58:36.981] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8905463822839590544:13:0) after 9m39s
[02:08:16.424] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8905463822839590544:13:0)
[02:08:16.441] Build status: STARTED
[02:08:16.441] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8905463822839590544:14:0) after 9m25s
[02:17:41.670] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8905463822839590544:14:0)
[02:17:41.689] Build status: STARTED
[02:17:41.689] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8905463822839590544:15:0) after 2m23s
[02:20:04.708] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8905463822839590544:15:0)
[02:20:04.773] Build status: STARTED
[02:20:04.773] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8905463822839590544:16:0) after 8m34s
[02:28:38.937] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8905463822839590544:16:0)
[02:28:38.956] Build status: STARTED
[02:28:38.957] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8905463822839590544:17:0) after 5m4s
[02:33:42.975] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8905463822839590544:17:0)
[02:33:42.992] Build status: STARTED
[02:33:42.992] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8905463822839590544:18:0) after 4m2s
[02:37:45.053] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8905463822839590544:18:0)
[02:37:45.073] Build status: STARTED
[02:37:45.073] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8905463822839590544:19:0) after 4m2s
[02:41:47.108] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8905463822839590544:19:0)
[02:41:47.127] Build status: STARTED
[02:41:47.127] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8905463822839590544:20:0) after 5m50s
[02:47:37.146] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8905463822839590544:20:0)
[02:47:37.170] Build status: STARTED
[02:47:37.170] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8905463822839590544:21:0) after 4m6s
[02:51:43.390] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8905463822839590544:21:0)
[02:51:43.406] Build status: STARTED
[02:51:43.406] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8905463822839590544:22:0) after 7m45s
[02:59:28.483] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8905463822839590544:22:0)
[02:59:28.501] Build status: STARTED
[02:59:28.501] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8905463822839590544:23:0) after 5m35s
[03:05:03.717] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8905463822839590544:23:0)
[03:05:03.735] Build status: STARTED
[03:05:03.735] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-kasan-e5a0ec7f:8905463822839590544:24:0) after 6m4s
[03:05:23.047] Received PubSub notification, asking Buildbucket for the build status
[03:05:23.062] Build:
{
"id": "8740033759880040945",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.arm64-kasan"
},
"createdBy": "project:fuchsia",
"createTime": "2024-08-10T00:55:00.886211992Z",
"startTime": "2024-08-10T00:56:53.892289609Z",
"endTime": "2024-08-10T03:05:22.682211229Z",
"updateTime": "2024-08-10T03:05:22.682211229Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "510faba094f594bce7f03e1b80c03457e3918453",
"ref": "refs/heads/main"
}
}
}
[03:05:23.062] Invocation finished in 2h10m24.382098834s with status SUCCEEDED