[22:01:46.595] New invocation is queued and will start shortly
[22:01:47.699] Starting the invocation (attempt 1)
[22:01:47.726] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/fuchsia/+/e040a5356511e561b657cb2e22935da3bf32178a"
[22:01:47.727] Popped gitiles commit info from properties and tags
[22:01:47.727] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:01:47.727] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:01:47.728] Buildbucket request:
{
"requestId": "8913175461879146304",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "profile-fuchsia"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8913175461879146304",
"job": "fuchsia/profile-fuchsia-af244ac6",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "d4946c76d610f7ed056e0d59770247001e7ea2f9"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@d4946c76d610f7ed056e0d59770247001e7ea2f9",
"title": "d4946c76d610f7ed056e0d59770247001e7ea2f9",
"url": "https://fuchsia.googlesource.com/fuchsia/+/d4946c76d610f7ed056e0d59770247001e7ea2f9"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "5f5736c74fb3c6f795582c52384801a0f8d84646"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@5f5736c74fb3c6f795582c52384801a0f8d84646",
"title": "5f5736c74fb3c6f795582c52384801a0f8d84646",
"url": "https://fuchsia.googlesource.com/fuchsia/+/5f5736c74fb3c6f795582c52384801a0f8d84646"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "aa10e6918e857b791f39f9164c88c8b8244635c4"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@aa10e6918e857b791f39f9164c88c8b8244635c4",
"title": "aa10e6918e857b791f39f9164c88c8b8244635c4",
"url": "https://fuchsia.googlesource.com/fuchsia/+/aa10e6918e857b791f39f9164c88c8b8244635c4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "03bedbdce4602e6cc2c0981d8ec43055ec5046bd"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@03bedbdce4602e6cc2c0981d8ec43055ec5046bd",
"title": "03bedbdce4602e6cc2c0981d8ec43055ec5046bd",
"url": "https://fuchsia.googlesource.com/fuchsia/+/03bedbdce4602e6cc2c0981d8ec43055ec5046bd"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "a099fe7bea2858119343155bf52b617ffa43149f"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@a099fe7bea2858119343155bf52b617ffa43149f",
"title": "a099fe7bea2858119343155bf52b617ffa43149f",
"url": "https://fuchsia.googlesource.com/fuchsia/+/a099fe7bea2858119343155bf52b617ffa43149f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "fd9cf61e236e860c1df81e60903fb7c5f7b14a92"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@fd9cf61e236e860c1df81e60903fb7c5f7b14a92",
"title": "fd9cf61e236e860c1df81e60903fb7c5f7b14a92",
"url": "https://fuchsia.googlesource.com/fuchsia/+/fd9cf61e236e860c1df81e60903fb7c5f7b14a92"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "ebce80c22869ddfa15f5b9640c9ee953a8bd7167"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@ebce80c22869ddfa15f5b9640c9ee953a8bd7167",
"title": "ebce80c22869ddfa15f5b9640c9ee953a8bd7167",
"url": "https://fuchsia.googlesource.com/fuchsia/+/ebce80c22869ddfa15f5b9640c9ee953a8bd7167"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "a401a604d50fcacf2c025e3ebe3b2b5733e048c1"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@a401a604d50fcacf2c025e3ebe3b2b5733e048c1",
"title": "a401a604d50fcacf2c025e3ebe3b2b5733e048c1",
"url": "https://fuchsia.googlesource.com/fuchsia/+/a401a604d50fcacf2c025e3ebe3b2b5733e048c1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "eef4a56b1411820d414d4cf59d848925d24488f6"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@eef4a56b1411820d414d4cf59d848925d24488f6",
"title": "eef4a56b1411820d414d4cf59d848925d24488f6",
"url": "https://fuchsia.googlesource.com/fuchsia/+/eef4a56b1411820d414d4cf59d848925d24488f6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "edef3efc5f92f1bc1d80c004607e4919c3b1eab1"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@edef3efc5f92f1bc1d80c004607e4919c3b1eab1",
"title": "edef3efc5f92f1bc1d80c004607e4919c3b1eab1",
"url": "https://fuchsia.googlesource.com/fuchsia/+/edef3efc5f92f1bc1d80c004607e4919c3b1eab1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "0d9ea9129a6508118df2d2d95322d3a263a0af31"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@0d9ea9129a6508118df2d2d95322d3a263a0af31",
"title": "0d9ea9129a6508118df2d2d95322d3a263a0af31",
"url": "https://fuchsia.googlesource.com/fuchsia/+/0d9ea9129a6508118df2d2d95322d3a263a0af31"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "a12ac0e740efb5a307c8129c8be654c0cd2e0192"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@a12ac0e740efb5a307c8129c8be654c0cd2e0192",
"title": "a12ac0e740efb5a307c8129c8be654c0cd2e0192",
"url": "https://fuchsia.googlesource.com/fuchsia/+/a12ac0e740efb5a307c8129c8be654c0cd2e0192"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "8984c6c2d63af22eea6f0e4bce663536874d48ab"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@8984c6c2d63af22eea6f0e4bce663536874d48ab",
"title": "8984c6c2d63af22eea6f0e4bce663536874d48ab",
"url": "https://fuchsia.googlesource.com/fuchsia/+/8984c6c2d63af22eea6f0e4bce663536874d48ab"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "28b227d8adef964d06cd8b76f693805a6c5358d7"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@28b227d8adef964d06cd8b76f693805a6c5358d7",
"title": "28b227d8adef964d06cd8b76f693805a6c5358d7",
"url": "https://fuchsia.googlesource.com/fuchsia/+/28b227d8adef964d06cd8b76f693805a6c5358d7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "ffc889d58eaeae500d28128bdc2afef00ca9fe15"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@ffc889d58eaeae500d28128bdc2afef00ca9fe15",
"title": "ffc889d58eaeae500d28128bdc2afef00ca9fe15",
"url": "https://fuchsia.googlesource.com/fuchsia/+/ffc889d58eaeae500d28128bdc2afef00ca9fe15"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "32e4a865ed3208c969905c6154877c13935c5fa6"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@32e4a865ed3208c969905c6154877c13935c5fa6",
"title": "32e4a865ed3208c969905c6154877c13935c5fa6",
"url": "https://fuchsia.googlesource.com/fuchsia/+/32e4a865ed3208c969905c6154877c13935c5fa6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "4f48b1409633e14f9391c4ed2081d146237d051e"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@4f48b1409633e14f9391c4ed2081d146237d051e",
"title": "4f48b1409633e14f9391c4ed2081d146237d051e",
"url": "https://fuchsia.googlesource.com/fuchsia/+/4f48b1409633e14f9391c4ed2081d146237d051e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "e5e2a492a2410d41b3807b6c22a4e2d1d684bb72"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@e5e2a492a2410d41b3807b6c22a4e2d1d684bb72",
"title": "e5e2a492a2410d41b3807b6c22a4e2d1d684bb72",
"url": "https://fuchsia.googlesource.com/fuchsia/+/e5e2a492a2410d41b3807b6c22a4e2d1d684bb72"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "6c3cad2a2873b2ac7ba310337eb1b10a842aaa27"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@6c3cad2a2873b2ac7ba310337eb1b10a842aaa27",
"title": "6c3cad2a2873b2ac7ba310337eb1b10a842aaa27",
"url": "https://fuchsia.googlesource.com/fuchsia/+/6c3cad2a2873b2ac7ba310337eb1b10a842aaa27"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "0ac3491086eaf7ae964f8bd79efe3091fecaeb7d"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@0ac3491086eaf7ae964f8bd79efe3091fecaeb7d",
"title": "0ac3491086eaf7ae964f8bd79efe3091fecaeb7d",
"url": "https://fuchsia.googlesource.com/fuchsia/+/0ac3491086eaf7ae964f8bd79efe3091fecaeb7d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "1eafc18fbffacc78f682a3c62e921a840cc74db6"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@1eafc18fbffacc78f682a3c62e921a840cc74db6",
"title": "1eafc18fbffacc78f682a3c62e921a840cc74db6",
"url": "https://fuchsia.googlesource.com/fuchsia/+/1eafc18fbffacc78f682a3c62e921a840cc74db6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "bef5ab4dd7714a5ddda964d1eccee311ec1d965d"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@bef5ab4dd7714a5ddda964d1eccee311ec1d965d",
"title": "bef5ab4dd7714a5ddda964d1eccee311ec1d965d",
"url": "https://fuchsia.googlesource.com/fuchsia/+/bef5ab4dd7714a5ddda964d1eccee311ec1d965d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "b5f630f9af6ccc4fc9a0cf8347585ca5133e5559"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@b5f630f9af6ccc4fc9a0cf8347585ca5133e5559",
"title": "b5f630f9af6ccc4fc9a0cf8347585ca5133e5559",
"url": "https://fuchsia.googlesource.com/fuchsia/+/b5f630f9af6ccc4fc9a0cf8347585ca5133e5559"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "cfd7a2d99eadd656a4081e3ffa349f20b087bd5a"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@cfd7a2d99eadd656a4081e3ffa349f20b087bd5a",
"title": "cfd7a2d99eadd656a4081e3ffa349f20b087bd5a",
"url": "https://fuchsia.googlesource.com/fuchsia/+/cfd7a2d99eadd656a4081e3ffa349f20b087bd5a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "d9d36b996ccc29b289e15e3d93a6b41ebdd6dc0c"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@d9d36b996ccc29b289e15e3d93a6b41ebdd6dc0c",
"title": "d9d36b996ccc29b289e15e3d93a6b41ebdd6dc0c",
"url": "https://fuchsia.googlesource.com/fuchsia/+/d9d36b996ccc29b289e15e3d93a6b41ebdd6dc0c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "36c3d81d7c31fd20a73e57d1957858edd6cb1be7"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@36c3d81d7c31fd20a73e57d1957858edd6cb1be7",
"title": "36c3d81d7c31fd20a73e57d1957858edd6cb1be7",
"url": "https://fuchsia.googlesource.com/fuchsia/+/36c3d81d7c31fd20a73e57d1957858edd6cb1be7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "307205e717eb4965cc2bcb675d6d35898e9d7b95"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@307205e717eb4965cc2bcb675d6d35898e9d7b95",
"title": "307205e717eb4965cc2bcb675d6d35898e9d7b95",
"url": "https://fuchsia.googlesource.com/fuchsia/+/307205e717eb4965cc2bcb675d6d35898e9d7b95"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/fuchsia",
"revision": "e040a5356511e561b657cb2e22935da3bf32178a"
},
"id": "https://fuchsia.googlesource.com/fuchsia/+/refs/heads/main@e040a5356511e561b657cb2e22935da3bf32178a",
"title": "e040a5356511e561b657cb2e22935da3bf32178a",
"url": "https://fuchsia.googlesource.com/fuchsia/+/e040a5356511e561b657cb2e22935da3bf32178a"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "e040a5356511e561b657cb2e22935da3bf32178a",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8913175461879146304"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/profile-fuchsia-af244ac6"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[22:01:48.449] Scheduled build:
{
"id": "8747745399905771553",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "profile-fuchsia"
},
"createdBy": "project:fuchsia",
"createTime": "2024-05-16T22:01:47.856048958Z",
"updateTime": "2024-05-16T22:01:47.856048958Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "e040a5356511e561b657cb2e22935da3bf32178a",
"ref": "refs/heads/main"
}
}
}
[22:01:48.449] Task URL: https://cr-buildbucket.appspot.com/build/8747745399905771553
[22:01:48.449] Scheduling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:2:0) after 8m20s
[22:01:49.277] Received PubSub notification, asking Buildbucket for the build status
[22:01:49.328] Build status: SCHEDULED
[22:01:53.971] Received PubSub notification, asking Buildbucket for the build status
[22:01:53.995] Build status: STARTED
[22:10:08.516] Handling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:2:0)
[22:10:08.555] Build status: STARTED
[22:10:08.555] Scheduling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:5:0) after 8m51s
[22:18:59.671] Handling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:5:0)
[22:18:59.700] Build status: STARTED
[22:18:59.700] Scheduling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:6:0) after 8m14s
[22:27:13.712] Handling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:6:0)
[22:27:13.791] Build status: STARTED
[22:27:13.791] Scheduling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:7:0) after 3m48s
[22:31:01.851] Handling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:7:0)
[22:31:01.874] Build status: STARTED
[22:31:01.874] Scheduling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:8:0) after 7m8s
[22:38:09.901] Handling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:8:0)
[22:38:09.918] Build status: STARTED
[22:38:09.918] Scheduling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:9:0) after 4m5s
[22:42:14.987] Handling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:9:0)
[22:42:15.005] Build status: STARTED
[22:42:15.005] Scheduling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:10:0) after 5m58s
[22:48:13.031] Handling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:10:0)
[22:48:13.086] Build status: STARTED
[22:48:13.086] Scheduling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:11:0) after 1m37s
[22:49:50.145] Handling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:11:0)
[22:49:50.169] Build status: STARTED
[22:49:50.169] Scheduling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:12:0) after 7m51s
[22:57:41.207] Handling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:12:0)
[22:57:41.224] Build status: STARTED
[22:57:41.224] Scheduling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:13:0) after 2m38s
[23:00:19.271] Handling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:13:0)
[23:00:19.333] Build status: STARTED
[23:00:19.333] Scheduling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:14:0) after 2m17s
[23:02:36.359] Handling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:14:0)
[23:02:36.391] Build status: STARTED
[23:02:36.391] Scheduling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:15:0) after 7m4s
[23:09:40.523] Handling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:15:0)
[23:09:40.608] Build status: STARTED
[23:09:40.608] Scheduling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:16:0) after 1m45s
[23:11:25.629] Handling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:16:0)
[23:11:25.699] Build status: STARTED
[23:11:25.699] Scheduling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:17:0) after 6m40s
[23:18:05.770] Handling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:17:0)
[23:18:05.833] Build status: STARTED
[23:18:05.833] Scheduling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:18:0) after 1m55s
[23:20:00.894] Handling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:18:0)
[23:20:00.914] Build status: STARTED
[23:20:00.914] Scheduling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:19:0) after 5m52s
[23:25:52.938] Handling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:19:0)
[23:25:52.987] Build status: STARTED
[23:25:52.988] Scheduling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:20:0) after 6m21s
[23:32:14.009] Handling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:20:0)
[23:32:14.032] Build status: STARTED
[23:32:14.032] Scheduling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:21:0) after 6m21s
[23:38:35.064] Handling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:21:0)
[23:38:35.108] Build status: STARTED
[23:38:35.108] Scheduling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:22:0) after 7m59s
[23:46:34.149] Handling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:22:0)
[23:46:34.169] Build status: STARTED
[23:46:34.169] Scheduling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:23:0) after 1m23s
[23:47:57.241] Handling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:23:0)
[23:47:57.263] Build status: STARTED
[23:47:57.263] Scheduling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:24:0) after 2m45s
[23:50:42.293] Handling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:24:0)
[23:50:42.311] Build status: STARTED
[23:50:42.311] Scheduling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:25:0) after 1m20s
[23:52:02.342] Handling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:25:0)
[23:52:02.363] Build status: STARTED
[23:52:02.363] Scheduling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:26:0) after 1m40s
[23:53:42.395] Handling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:26:0)
[23:53:42.501] Build status: STARTED
[23:53:42.501] Scheduling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:27:0) after 5m35s
[23:59:17.537] Handling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:27:0)
[23:59:17.558] Build status: STARTED
[23:59:17.558] Scheduling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:28:0) after 1m35s
[00:00:52.595] Handling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:28:0)
[00:00:52.651] Build status: STARTED
[00:00:52.651] Scheduling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:29:0) after 5m14s
[00:06:06.668] Handling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:29:0)
[00:06:06.694] Build status: STARTED
[00:06:06.694] Scheduling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:30:0) after 4m54s
[00:11:00.715] Handling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:30:0)
[00:11:00.732] Build status: STARTED
[00:11:00.732] Scheduling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:31:0) after 1m54s
[00:12:54.766] Handling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:31:0)
[00:12:54.792] Build status: STARTED
[00:12:54.792] Scheduling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:32:0) after 1m23s
[00:14:17.904] Handling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:32:0)
[00:14:17.926] Build status: STARTED
[00:14:17.926] Scheduling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:33:0) after 7m24s
[00:21:41.978] Handling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:33:0)
[00:21:42.067] Build status: STARTED
[00:21:42.067] Scheduling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:34:0) after 4m3s
[00:25:45.230] Handling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:34:0)
[00:25:45.249] Build status: STARTED
[00:25:45.249] Scheduling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:35:0) after 6m18s
[00:32:03.443] Handling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:35:0)
[00:32:03.464] Build status: STARTED
[00:32:03.464] Scheduling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:36:0) after 1m6s
[00:33:09.496] Handling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:36:0)
[00:33:09.548] Build status: STARTED
[00:33:09.548] Scheduling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:37:0) after 5m29s
[00:38:38.550] Handling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:37:0)
[00:38:38.568] Build status: STARTED
[00:38:38.568] Scheduling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:38:0) after 3m2s
[00:41:40.603] Handling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:38:0)
[00:41:40.620] Build status: STARTED
[00:41:40.620] Scheduling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:39:0) after 1m39s
[00:43:19.624] Handling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:39:0)
[00:43:19.660] Build status: STARTED
[00:43:19.660] Scheduling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:40:0) after 1m18s
[00:44:37.679] Handling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:40:0)
[00:44:37.696] Build status: STARTED
[00:44:37.696] Scheduling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:41:0) after 2m38s
[00:47:15.720] Handling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:41:0)
[00:47:15.744] Build status: STARTED
[00:47:15.744] Scheduling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:42:0) after 2m38s
[00:49:53.794] Handling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:42:0)
[00:49:53.812] Build status: STARTED
[00:49:53.812] Scheduling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:43:0) after 1m25s
[00:51:18.846] Handling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:43:0)
[00:51:18.957] Build status: STARTED
[00:51:18.957] Scheduling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:44:0) after 8m22s
[00:59:41.020] Handling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:44:0)
[00:59:41.042] Build status: STARTED
[00:59:41.042] Scheduling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:45:0) after 9m44s
[01:09:25.079] Handling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:45:0)
[01:09:25.102] Build status: STARTED
[01:09:25.102] Scheduling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:46:0) after 1m19s
[01:10:44.117] Handling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:46:0)
[01:10:44.138] Build status: STARTED
[01:10:44.138] Scheduling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:47:0) after 4m33s
[01:15:17.129] Handling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:47:0)
[01:15:17.149] Build status: STARTED
[01:15:17.149] Scheduling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:48:0) after 5m24s
[01:20:41.159] Handling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:48:0)
[01:20:41.178] Build status: STARTED
[01:20:41.178] Scheduling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:49:0) after 1m50s
[01:22:31.247] Handling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:49:0)
[01:22:31.268] Build status: STARTED
[01:22:31.268] Scheduling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:50:0) after 8m22s
[01:30:53.291] Handling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:50:0)
[01:30:53.310] Build status: STARTED
[01:30:53.310] Scheduling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:51:0) after 4m49s
[01:35:42.331] Handling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:51:0)
[01:35:42.350] Build status: STARTED
[01:35:42.350] Scheduling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:52:0) after 5m8s
[01:40:50.355] Handling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:52:0)
[01:40:50.379] Build status: STARTED
[01:40:50.379] Scheduling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:53:0) after 6m48s
[01:47:38.476] Handling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:53:0)
[01:47:38.496] Build status: STARTED
[01:47:38.496] Scheduling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:54:0) after 3m56s
[01:51:34.536] Handling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:54:0)
[01:51:34.601] Build status: STARTED
[01:51:34.601] Scheduling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:55:0) after 2m23s
[01:53:57.704] Handling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:55:0)
[01:53:57.779] Build status: STARTED
[01:53:57.779] Scheduling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:56:0) after 8m33s
[02:02:30.856] Handling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:56:0)
[02:02:30.886] Build status: STARTED
[02:02:30.886] Scheduling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:57:0) after 8m52s
[02:11:22.953] Handling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:57:0)
[02:11:23.009] Build status: STARTED
[02:11:23.009] Scheduling timer "check-buildbucket-build-status" (fuchsia/profile-fuchsia-af244ac6:8913175461879146304:58:0) after 5m2s
[02:12:22.768] Received PubSub notification, asking Buildbucket for the build status
[02:12:22.812] Build:
{
"id": "8747745399905771553",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "profile-fuchsia"
},
"createdBy": "project:fuchsia",
"createTime": "2024-05-16T22:01:47.856048958Z",
"startTime": "2024-05-16T22:01:53.664886668Z",
"endTime": "2024-05-17T02:12:22.481195181Z",
"updateTime": "2024-05-17T02:12:22.481195181Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "fuchsia",
"id": "e040a5356511e561b657cb2e22935da3bf32178a",
"ref": "refs/heads/main"
}
}
}
[02:12:22.812] Invocation finished in 4h10m36.221760064s with status SUCCEEDED