[18:12:00.536] New invocation is queued and will start shortly
[18:12:01.651] Starting the invocation (attempt 1)
[18:12:01.684] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/3a4226ee19c2746d1cbb8c911f563a2d7be6b26d"
[18:12:01.684] Popped gitiles commit info from properties and tags
[18:12:01.684] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:12:01.684] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:12:01.685] Buildbucket request:
{
"requestId": "8887822767026283040",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "kythe"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8887822767026283040",
"job": "fuchsia/kythe-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "4a4fcff69dcf86c95622e57f60ea3dd5ef7c6c57"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@4a4fcff69dcf86c95622e57f60ea3dd5ef7c6c57",
"title": "4a4fcff69dcf86c95622e57f60ea3dd5ef7c6c57",
"url": "https://fuchsia.googlesource.com/integration/+/4a4fcff69dcf86c95622e57f60ea3dd5ef7c6c57"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "e92ace48fc5855f73181ed0fe68093c572b23f74"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@e92ace48fc5855f73181ed0fe68093c572b23f74",
"title": "e92ace48fc5855f73181ed0fe68093c572b23f74",
"url": "https://fuchsia.googlesource.com/integration/+/e92ace48fc5855f73181ed0fe68093c572b23f74"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "5e2b1b677dfebfc6f44f4bd375056438d8391180"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@5e2b1b677dfebfc6f44f4bd375056438d8391180",
"title": "5e2b1b677dfebfc6f44f4bd375056438d8391180",
"url": "https://fuchsia.googlesource.com/integration/+/5e2b1b677dfebfc6f44f4bd375056438d8391180"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "7ac9e14c61a763d23cecd5b18a91556d427a57b6"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@7ac9e14c61a763d23cecd5b18a91556d427a57b6",
"title": "7ac9e14c61a763d23cecd5b18a91556d427a57b6",
"url": "https://fuchsia.googlesource.com/integration/+/7ac9e14c61a763d23cecd5b18a91556d427a57b6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "233047d750e260629aaaa11c55a4b807ee92eda0"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@233047d750e260629aaaa11c55a4b807ee92eda0",
"title": "233047d750e260629aaaa11c55a4b807ee92eda0",
"url": "https://fuchsia.googlesource.com/integration/+/233047d750e260629aaaa11c55a4b807ee92eda0"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "1e38e5378465b02268eb86b08166d206a8446af6"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@1e38e5378465b02268eb86b08166d206a8446af6",
"title": "1e38e5378465b02268eb86b08166d206a8446af6",
"url": "https://fuchsia.googlesource.com/integration/+/1e38e5378465b02268eb86b08166d206a8446af6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "537f55bc7f2562eeee0135e8eef1bab8bd77a7ef"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@537f55bc7f2562eeee0135e8eef1bab8bd77a7ef",
"title": "537f55bc7f2562eeee0135e8eef1bab8bd77a7ef",
"url": "https://fuchsia.googlesource.com/integration/+/537f55bc7f2562eeee0135e8eef1bab8bd77a7ef"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "64a01de85decec246996b3b27cf8ee5a5fdd3232"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@64a01de85decec246996b3b27cf8ee5a5fdd3232",
"title": "64a01de85decec246996b3b27cf8ee5a5fdd3232",
"url": "https://fuchsia.googlesource.com/integration/+/64a01de85decec246996b3b27cf8ee5a5fdd3232"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "3a4226ee19c2746d1cbb8c911f563a2d7be6b26d"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@3a4226ee19c2746d1cbb8c911f563a2d7be6b26d",
"title": "3a4226ee19c2746d1cbb8c911f563a2d7be6b26d",
"url": "https://fuchsia.googlesource.com/integration/+/3a4226ee19c2746d1cbb8c911f563a2d7be6b26d"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "3a4226ee19c2746d1cbb8c911f563a2d7be6b26d",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8887822767026283040"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/kythe-e5a0ec7f"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[18:12:02.878] Scheduled build:
{
"id": "8722392704972580913",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "kythe"
},
"createdBy": "project:fuchsia",
"createTime": "2025-02-20T18:12:01.866849860Z",
"updateTime": "2025-02-20T18:12:01.866849860Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "3a4226ee19c2746d1cbb8c911f563a2d7be6b26d",
"ref": "refs/heads/main"
}
}
}
[18:12:02.878] Task URL: https://cr-buildbucket.appspot.com/build/8722392704972580913
[18:12:02.878] Scheduling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8887822767026283040:2:0) after 3m48s
[18:12:03.500] Received PubSub notification, asking Buildbucket for the build status
[18:12:03.526] Build status: SCHEDULED
[18:12:07.787] Received PubSub notification, asking Buildbucket for the build status
[18:12:07.822] Build status: STARTED
[18:15:50.953] Handling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8887822767026283040:2:0)
[18:15:50.984] Build status: STARTED
[18:15:50.984] Scheduling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8887822767026283040:5:0) after 8m58s
[18:24:49.002] Handling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8887822767026283040:5:0)
[18:24:49.055] Build status: STARTED
[18:24:49.055] Scheduling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8887822767026283040:6:0) after 6m57s
[18:31:46.131] Handling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8887822767026283040:6:0)
[18:31:46.162] Build status: STARTED
[18:31:46.162] Scheduling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8887822767026283040:7:0) after 8m18s
[18:40:04.186] Handling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8887822767026283040:7:0)
[18:40:04.206] Build status: STARTED
[18:40:04.206] Scheduling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8887822767026283040:8:0) after 1m32s
[18:41:36.279] Handling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8887822767026283040:8:0)
[18:41:36.308] Build status: STARTED
[18:41:36.308] Scheduling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8887822767026283040:9:0) after 1m44s
[18:43:20.518] Handling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8887822767026283040:9:0)
[18:43:20.577] Build status: STARTED
[18:43:20.577] Scheduling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8887822767026283040:10:0) after 6m8s
[18:49:28.724] Handling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8887822767026283040:10:0)
[18:49:28.748] Build status: STARTED
[18:49:28.748] Scheduling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8887822767026283040:11:0) after 4m46s
[18:54:14.850] Handling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8887822767026283040:11:0)
[18:54:14.908] Build status: STARTED
[18:54:14.908] Scheduling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8887822767026283040:12:0) after 2m26s
[18:56:40.925] Handling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8887822767026283040:12:0)
[18:56:40.954] Build status: STARTED
[18:56:40.954] Scheduling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8887822767026283040:13:0) after 1m46s
[18:58:27.106] Handling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8887822767026283040:13:0)
[18:58:27.129] Build status: STARTED
[18:58:27.129] Scheduling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8887822767026283040:14:0) after 3m4s
[19:01:31.228] Handling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8887822767026283040:14:0)
[19:01:31.250] Build status: STARTED
[19:01:31.250] Scheduling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8887822767026283040:15:0) after 3m34s
[19:05:05.347] Handling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8887822767026283040:15:0)
[19:05:05.374] Build status: STARTED
[19:05:05.374] Scheduling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8887822767026283040:16:0) after 5m58s
[19:11:03.455] Handling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8887822767026283040:16:0)
[19:11:03.552] Build status: STARTED
[19:11:03.552] Scheduling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8887822767026283040:17:0) after 7m28s
[19:18:31.628] Handling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8887822767026283040:17:0)
[19:18:31.651] Build status: STARTED
[19:18:31.651] Scheduling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8887822767026283040:18:0) after 2m55s
[19:21:26.689] Handling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8887822767026283040:18:0)
[19:21:26.705] Build status: STARTED
[19:21:26.705] Scheduling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8887822767026283040:19:0) after 2m22s
[19:23:48.731] Handling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8887822767026283040:19:0)
[19:23:48.789] Build status: STARTED
[19:23:48.789] Scheduling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8887822767026283040:20:0) after 6m57s
[19:30:45.814] Handling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8887822767026283040:20:0)
[19:30:45.829] Build status: STARTED
[19:30:45.829] Scheduling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8887822767026283040:21:0) after 1m59s
[19:32:44.898] Handling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8887822767026283040:21:0)
[19:32:44.920] Build status: STARTED
[19:32:44.921] Scheduling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8887822767026283040:22:0) after 2m24s
[19:35:08.913] Handling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8887822767026283040:22:0)
[19:35:08.935] Build status: STARTED
[19:35:08.935] Scheduling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8887822767026283040:23:0) after 4m5s
[19:39:13.957] Handling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8887822767026283040:23:0)
[19:39:13.975] Build status: STARTED
[19:39:13.975] Scheduling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8887822767026283040:24:0) after 4m7s
[19:43:20.990] Handling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8887822767026283040:24:0)
[19:43:21.008] Build status: STARTED
[19:43:21.008] Scheduling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8887822767026283040:25:0) after 3m5s
[19:46:26.025] Handling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8887822767026283040:25:0)
[19:46:26.049] Build status: STARTED
[19:46:26.049] Scheduling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8887822767026283040:26:0) after 1m39s
[19:48:05.070] Handling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8887822767026283040:26:0)
[19:48:05.089] Build status: STARTED
[19:48:05.089] Scheduling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8887822767026283040:27:0) after 8m59s
[19:57:04.202] Handling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8887822767026283040:27:0)
[19:57:04.274] Build status: STARTED
[19:57:04.274] Scheduling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8887822767026283040:28:0) after 6m42s
[19:59:59.835] Received PubSub notification, asking Buildbucket for the build status
[19:59:59.854] Build:
{
"id": "8722392704972580913",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "kythe"
},
"createdBy": "project:fuchsia",
"createTime": "2025-02-20T18:12:01.866849860Z",
"startTime": "2025-02-20T18:12:07.171877849Z",
"endTime": "2025-02-20T19:59:59.591311355Z",
"updateTime": "2025-02-20T19:59:59.591311355Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "3a4226ee19c2746d1cbb8c911f563a2d7be6b26d",
"ref": "refs/heads/main"
}
}
}
[19:59:59.854] Invocation finished in 1h47m59.329683696s with status SUCCEEDED