[01:32:12.759] New invocation is queued and will start shortly
[01:32:13.874] Starting the invocation (attempt 1)
[01:32:13.903] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/72fb6b30ee637fc71f15ce8678eee1ad02359909"
[01:32:13.903] Popped gitiles commit info from properties and tags
[01:32:13.904] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:32:13.905] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:32:13.906] Buildbucket request:
{
"requestId": "8918960428282170528",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-cxx20"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8918960428282170528",
"job": "fuchsia/core.x64-cxx20-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c1bf7fe8ff1e912d5107d4e483e21a7bbb1c7692"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c1bf7fe8ff1e912d5107d4e483e21a7bbb1c7692",
"title": "c1bf7fe8ff1e912d5107d4e483e21a7bbb1c7692",
"url": "https://fuchsia.googlesource.com/integration/+/c1bf7fe8ff1e912d5107d4e483e21a7bbb1c7692"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "3c335713106a1c565b1d8be76d67988238bd95c9"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@3c335713106a1c565b1d8be76d67988238bd95c9",
"title": "3c335713106a1c565b1d8be76d67988238bd95c9",
"url": "https://fuchsia.googlesource.com/integration/+/3c335713106a1c565b1d8be76d67988238bd95c9"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "de23d61626844fc15b79fa3128b3e7fc44c5acd4"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@de23d61626844fc15b79fa3128b3e7fc44c5acd4",
"title": "de23d61626844fc15b79fa3128b3e7fc44c5acd4",
"url": "https://fuchsia.googlesource.com/integration/+/de23d61626844fc15b79fa3128b3e7fc44c5acd4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "13f506f58e6dcfaa915a590b8c1dc572bbca0a76"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@13f506f58e6dcfaa915a590b8c1dc572bbca0a76",
"title": "13f506f58e6dcfaa915a590b8c1dc572bbca0a76",
"url": "https://fuchsia.googlesource.com/integration/+/13f506f58e6dcfaa915a590b8c1dc572bbca0a76"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c6298e0f9667ad68eda4472da83a4541c17f17fa"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c6298e0f9667ad68eda4472da83a4541c17f17fa",
"title": "c6298e0f9667ad68eda4472da83a4541c17f17fa",
"url": "https://fuchsia.googlesource.com/integration/+/c6298e0f9667ad68eda4472da83a4541c17f17fa"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "98ba8f412b3d9d90f73e75870719374d94849693"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@98ba8f412b3d9d90f73e75870719374d94849693",
"title": "98ba8f412b3d9d90f73e75870719374d94849693",
"url": "https://fuchsia.googlesource.com/integration/+/98ba8f412b3d9d90f73e75870719374d94849693"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "17d8b69f7ffc06b4b089953218ddc99c1406cc51"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@17d8b69f7ffc06b4b089953218ddc99c1406cc51",
"title": "17d8b69f7ffc06b4b089953218ddc99c1406cc51",
"url": "https://fuchsia.googlesource.com/integration/+/17d8b69f7ffc06b4b089953218ddc99c1406cc51"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "da52d0a4adb0873e079d50e80b9af3d90056795d"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@da52d0a4adb0873e079d50e80b9af3d90056795d",
"title": "da52d0a4adb0873e079d50e80b9af3d90056795d",
"url": "https://fuchsia.googlesource.com/integration/+/da52d0a4adb0873e079d50e80b9af3d90056795d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d0a5c25c0cc8f0e63a8ecc7a40dcef03c950b0c2"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d0a5c25c0cc8f0e63a8ecc7a40dcef03c950b0c2",
"title": "d0a5c25c0cc8f0e63a8ecc7a40dcef03c950b0c2",
"url": "https://fuchsia.googlesource.com/integration/+/d0a5c25c0cc8f0e63a8ecc7a40dcef03c950b0c2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "49fbf87d4fcf84588db1f93254c58631399632ea"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@49fbf87d4fcf84588db1f93254c58631399632ea",
"title": "49fbf87d4fcf84588db1f93254c58631399632ea",
"url": "https://fuchsia.googlesource.com/integration/+/49fbf87d4fcf84588db1f93254c58631399632ea"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "e515690e59502ce2898b6743a818ade95f74b6d3"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@e515690e59502ce2898b6743a818ade95f74b6d3",
"title": "e515690e59502ce2898b6743a818ade95f74b6d3",
"url": "https://fuchsia.googlesource.com/integration/+/e515690e59502ce2898b6743a818ade95f74b6d3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "f2121c92886f2724219d47992e6de60fa0ce6b78"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@f2121c92886f2724219d47992e6de60fa0ce6b78",
"title": "f2121c92886f2724219d47992e6de60fa0ce6b78",
"url": "https://fuchsia.googlesource.com/integration/+/f2121c92886f2724219d47992e6de60fa0ce6b78"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "675594df6a437adc555a4927fe8880506b89e072"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@675594df6a437adc555a4927fe8880506b89e072",
"title": "675594df6a437adc555a4927fe8880506b89e072",
"url": "https://fuchsia.googlesource.com/integration/+/675594df6a437adc555a4927fe8880506b89e072"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "8a4805d73d22c7dab2f5e02b15c03aa8d7dbffb0"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@8a4805d73d22c7dab2f5e02b15c03aa8d7dbffb0",
"title": "8a4805d73d22c7dab2f5e02b15c03aa8d7dbffb0",
"url": "https://fuchsia.googlesource.com/integration/+/8a4805d73d22c7dab2f5e02b15c03aa8d7dbffb0"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "229a0a83c19b2d2da3dcf429f4089c69d98cd725"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@229a0a83c19b2d2da3dcf429f4089c69d98cd725",
"title": "229a0a83c19b2d2da3dcf429f4089c69d98cd725",
"url": "https://fuchsia.googlesource.com/integration/+/229a0a83c19b2d2da3dcf429f4089c69d98cd725"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "444a6283547e3f9c1ca399e695203ace573838dd"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@444a6283547e3f9c1ca399e695203ace573838dd",
"title": "444a6283547e3f9c1ca399e695203ace573838dd",
"url": "https://fuchsia.googlesource.com/integration/+/444a6283547e3f9c1ca399e695203ace573838dd"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "fb2d2cf9ef40c3ef1cbfc789312035653bea49fc"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@fb2d2cf9ef40c3ef1cbfc789312035653bea49fc",
"title": "fb2d2cf9ef40c3ef1cbfc789312035653bea49fc",
"url": "https://fuchsia.googlesource.com/integration/+/fb2d2cf9ef40c3ef1cbfc789312035653bea49fc"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "7c5abba713888120d869d238a44a0fae05a1a883"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@7c5abba713888120d869d238a44a0fae05a1a883",
"title": "7c5abba713888120d869d238a44a0fae05a1a883",
"url": "https://fuchsia.googlesource.com/integration/+/7c5abba713888120d869d238a44a0fae05a1a883"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d1a15d132498cf3c02f478e4c1e2458079ec6604"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d1a15d132498cf3c02f478e4c1e2458079ec6604",
"title": "d1a15d132498cf3c02f478e4c1e2458079ec6604",
"url": "https://fuchsia.googlesource.com/integration/+/d1a15d132498cf3c02f478e4c1e2458079ec6604"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "eb8c4ece3fc8cc4ff72319746f5870191878c78b"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@eb8c4ece3fc8cc4ff72319746f5870191878c78b",
"title": "eb8c4ece3fc8cc4ff72319746f5870191878c78b",
"url": "https://fuchsia.googlesource.com/integration/+/eb8c4ece3fc8cc4ff72319746f5870191878c78b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "dbeec796722342e0edbc20d4d7e803d2aec1fd9c"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@dbeec796722342e0edbc20d4d7e803d2aec1fd9c",
"title": "dbeec796722342e0edbc20d4d7e803d2aec1fd9c",
"url": "https://fuchsia.googlesource.com/integration/+/dbeec796722342e0edbc20d4d7e803d2aec1fd9c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "72fb6b30ee637fc71f15ce8678eee1ad02359909"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@72fb6b30ee637fc71f15ce8678eee1ad02359909",
"title": "72fb6b30ee637fc71f15ce8678eee1ad02359909",
"url": "https://fuchsia.googlesource.com/integration/+/72fb6b30ee637fc71f15ce8678eee1ad02359909"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "72fb6b30ee637fc71f15ce8678eee1ad02359909",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8918960428282170528"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/core.x64-cxx20-e5a0ec7f"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[01:32:14.364] Scheduled build:
{
"id": "8753530366320482321",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-cxx20"
},
"createdBy": "project:fuchsia",
"createTime": "2024-03-14T01:32:13.965419236Z",
"updateTime": "2024-03-14T01:32:13.965419236Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "72fb6b30ee637fc71f15ce8678eee1ad02359909",
"ref": "refs/heads/main"
}
},
"canary": true
}
[01:32:14.364] Task URL: https://cr-buildbucket.appspot.com/build/8753530366320482321
[01:32:14.364] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8918960428282170528:2:0) after 4m23s
[01:32:15.412] Received PubSub notification, asking Buildbucket for the build status
[01:32:15.437] Build status: SCHEDULED
[01:32:16.893] Received PubSub notification, asking Buildbucket for the build status
[01:32:16.918] Build status: STARTED
[01:36:37.438] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8918960428282170528:2:0)
[01:36:37.456] Build status: STARTED
[01:36:37.456] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8918960428282170528:5:0) after 7m40s
[01:44:17.635] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8918960428282170528:5:0)
[01:44:17.677] Build status: STARTED
[01:44:17.677] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8918960428282170528:6:0) after 3m28s
[01:47:45.728] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8918960428282170528:6:0)
[01:47:45.754] Build status: STARTED
[01:47:45.754] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8918960428282170528:7:0) after 6m45s
[01:54:30.796] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8918960428282170528:7:0)
[01:54:30.833] Build status: STARTED
[01:54:30.833] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8918960428282170528:8:0) after 2m23s
[01:56:53.867] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8918960428282170528:8:0)
[01:56:53.916] Build status: STARTED
[01:56:53.916] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8918960428282170528:9:0) after 4m42s
[02:01:35.911] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8918960428282170528:9:0)
[02:01:35.943] Build status: STARTED
[02:01:35.943] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8918960428282170528:10:0) after 4m23s
[02:05:59.004] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8918960428282170528:10:0)
[02:05:59.113] Build status: STARTED
[02:05:59.113] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8918960428282170528:11:0) after 9m21s
[02:15:20.238] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8918960428282170528:11:0)
[02:15:20.270] Build status: STARTED
[02:15:20.270] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8918960428282170528:12:0) after 6m33s
[02:21:53.424] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8918960428282170528:12:0)
[02:21:53.501] Build status: STARTED
[02:21:53.501] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8918960428282170528:13:0) after 1m8s
[02:23:01.839] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8918960428282170528:13:0)
[02:23:01.856] Build status: STARTED
[02:23:01.856] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8918960428282170528:14:0) after 2m48s
[02:25:49.878] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8918960428282170528:14:0)
[02:25:49.902] Build status: STARTED
[02:25:49.902] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8918960428282170528:15:0) after 3m7s
[02:28:56.953] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8918960428282170528:15:0)
[02:28:57.001] Build status: STARTED
[02:28:57.001] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8918960428282170528:16:0) after 2m1s
[02:30:58.344] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8918960428282170528:16:0)
[02:30:58.370] Build status: STARTED
[02:30:58.370] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8918960428282170528:17:0) after 2m21s
[02:33:19.392] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8918960428282170528:17:0)
[02:33:19.411] Build status: STARTED
[02:33:19.411] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8918960428282170528:18:0) after 4m57s
[02:38:16.462] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8918960428282170528:18:0)
[02:38:16.487] Build status: STARTED
[02:38:16.487] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8918960428282170528:19:0) after 3m37s
[02:41:53.478] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8918960428282170528:19:0)
[02:41:53.546] Build status: STARTED
[02:41:53.546] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8918960428282170528:20:0) after 1m47s
[02:43:40.658] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8918960428282170528:20:0)
[02:43:40.692] Build status: STARTED
[02:43:40.692] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8918960428282170528:21:0) after 3m41s
[02:47:21.742] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8918960428282170528:21:0)
[02:47:21.766] Build status: STARTED
[02:47:21.766] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8918960428282170528:22:0) after 3m53s
[02:51:14.754] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8918960428282170528:22:0)
[02:51:14.819] Build status: STARTED
[02:51:14.819] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8918960428282170528:23:0) after 5m22s
[02:56:36.984] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8918960428282170528:23:0)
[02:56:37.021] Build status: STARTED
[02:56:37.021] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8918960428282170528:24:0) after 5m28s
[03:02:05.095] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8918960428282170528:24:0)
[03:02:05.134] Build status: STARTED
[03:02:05.134] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-cxx20-e5a0ec7f:8918960428282170528:25:0) after 9m0s
[03:10:15.353] Received PubSub notification, asking Buildbucket for the build status
[03:10:15.374] Build:
{
"id": "8753530366320482321",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-cxx20"
},
"createdBy": "project:fuchsia",
"createTime": "2024-03-14T01:32:13.965419236Z",
"startTime": "2024-03-14T01:32:15.828027Z",
"endTime": "2024-03-14T03:10:14.062163Z",
"updateTime": "2024-03-14T03:10:14.062163Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "72fb6b30ee637fc71f15ce8678eee1ad02359909",
"ref": "refs/heads/main"
}
},
"canary": true
}
[03:10:15.374] Invocation finished in 1h38m2.663291696s with status SUCCEEDED