[00:51:10.858] New invocation is queued and will start shortly
[00:51:11.930] Starting the invocation (attempt 1)
[00:51:11.956] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/70c81beddc7888fbce87f5fa47cc2736b18efb67"
[00:51:11.956] Popped gitiles commit info from properties and tags
[00:51:11.956] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:51:11.957] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:51:11.957] Buildbucket request:
{
"requestId": "8910899879720209472",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "kythe"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8910899879720209472",
"job": "fuchsia/kythe-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "626f29b7531b0b7af851a45ab526a3a75531b8df"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@626f29b7531b0b7af851a45ab526a3a75531b8df",
"title": "626f29b7531b0b7af851a45ab526a3a75531b8df",
"url": "https://fuchsia.googlesource.com/integration/+/626f29b7531b0b7af851a45ab526a3a75531b8df"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b5bbdf7b36d520802ae93fff662d98064a478f64"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b5bbdf7b36d520802ae93fff662d98064a478f64",
"title": "b5bbdf7b36d520802ae93fff662d98064a478f64",
"url": "https://fuchsia.googlesource.com/integration/+/b5bbdf7b36d520802ae93fff662d98064a478f64"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a50ebbb6cbc91474338507cc3cf574d840afd3c8"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a50ebbb6cbc91474338507cc3cf574d840afd3c8",
"title": "a50ebbb6cbc91474338507cc3cf574d840afd3c8",
"url": "https://fuchsia.googlesource.com/integration/+/a50ebbb6cbc91474338507cc3cf574d840afd3c8"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "e4f9089c0b0685f1b5932d53700203c4eee6919d"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@e4f9089c0b0685f1b5932d53700203c4eee6919d",
"title": "e4f9089c0b0685f1b5932d53700203c4eee6919d",
"url": "https://fuchsia.googlesource.com/integration/+/e4f9089c0b0685f1b5932d53700203c4eee6919d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "f5c00f065e061948812f3693faa986b40e002e8e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@f5c00f065e061948812f3693faa986b40e002e8e",
"title": "f5c00f065e061948812f3693faa986b40e002e8e",
"url": "https://fuchsia.googlesource.com/integration/+/f5c00f065e061948812f3693faa986b40e002e8e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "de8315926efe51de4e06ad93c22f18056db1e0b4"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@de8315926efe51de4e06ad93c22f18056db1e0b4",
"title": "de8315926efe51de4e06ad93c22f18056db1e0b4",
"url": "https://fuchsia.googlesource.com/integration/+/de8315926efe51de4e06ad93c22f18056db1e0b4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "e54b26b68f5addc713bc0fe630a72636a4c8f320"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@e54b26b68f5addc713bc0fe630a72636a4c8f320",
"title": "e54b26b68f5addc713bc0fe630a72636a4c8f320",
"url": "https://fuchsia.googlesource.com/integration/+/e54b26b68f5addc713bc0fe630a72636a4c8f320"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "40338018989dc89b865f3a0b4e189c6d5c4f695b"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@40338018989dc89b865f3a0b4e189c6d5c4f695b",
"title": "40338018989dc89b865f3a0b4e189c6d5c4f695b",
"url": "https://fuchsia.googlesource.com/integration/+/40338018989dc89b865f3a0b4e189c6d5c4f695b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "15c5b5b41cf0858df43a528d6d52c7e80de3558b"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@15c5b5b41cf0858df43a528d6d52c7e80de3558b",
"title": "15c5b5b41cf0858df43a528d6d52c7e80de3558b",
"url": "https://fuchsia.googlesource.com/integration/+/15c5b5b41cf0858df43a528d6d52c7e80de3558b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "552c8d7535dfe505a8a84c99e96af40bd2ff2bac"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@552c8d7535dfe505a8a84c99e96af40bd2ff2bac",
"title": "552c8d7535dfe505a8a84c99e96af40bd2ff2bac",
"url": "https://fuchsia.googlesource.com/integration/+/552c8d7535dfe505a8a84c99e96af40bd2ff2bac"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a199e91dc32add94cf046ac2db7d08585ef60450"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a199e91dc32add94cf046ac2db7d08585ef60450",
"title": "a199e91dc32add94cf046ac2db7d08585ef60450",
"url": "https://fuchsia.googlesource.com/integration/+/a199e91dc32add94cf046ac2db7d08585ef60450"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b53e749b21b56b5c936edfc0a0049c543fa21346"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b53e749b21b56b5c936edfc0a0049c543fa21346",
"title": "b53e749b21b56b5c936edfc0a0049c543fa21346",
"url": "https://fuchsia.googlesource.com/integration/+/b53e749b21b56b5c936edfc0a0049c543fa21346"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "70c81beddc7888fbce87f5fa47cc2736b18efb67"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@70c81beddc7888fbce87f5fa47cc2736b18efb67",
"title": "70c81beddc7888fbce87f5fa47cc2736b18efb67",
"url": "https://fuchsia.googlesource.com/integration/+/70c81beddc7888fbce87f5fa47cc2736b18efb67"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "70c81beddc7888fbce87f5fa47cc2736b18efb67",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8910899879720209472"
},
{
"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"
}
}
[00:51:12.619] Scheduled build:
{
"id": "8745469817847466561",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "kythe"
},
"createdBy": "project:fuchsia",
"createTime": "2024-06-11T00:51:12.020325407Z",
"updateTime": "2024-06-11T00:51:12.020325407Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "70c81beddc7888fbce87f5fa47cc2736b18efb67",
"ref": "refs/heads/main"
}
}
}
[00:51:12.619] Task URL: https://cr-buildbucket.appspot.com/build/8745469817847466561
[00:51:12.619] Scheduling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8910899879720209472:2:0) after 1m37s
[00:51:14.294] Received PubSub notification, asking Buildbucket for the build status
[00:51:14.317] Build status: SCHEDULED
[00:51:18.702] Received PubSub notification, asking Buildbucket for the build status
[00:51:18.723] Build status: STARTED
[00:52:49.728] Handling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8910899879720209472:2:0)
[00:52:49.745] Build status: STARTED
[00:52:49.745] Scheduling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8910899879720209472:5:0) after 1m17s
[00:54:06.812] Handling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8910899879720209472:5:0)
[00:54:06.835] Build status: STARTED
[00:54:06.835] Scheduling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8910899879720209472:6:0) after 3m27s
[00:57:33.850] Handling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8910899879720209472:6:0)
[00:57:33.876] Build status: STARTED
[00:57:33.876] Scheduling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8910899879720209472:7:0) after 5m15s
[01:02:49.099] Handling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8910899879720209472:7:0)
[01:02:49.122] Build status: STARTED
[01:02:49.122] Scheduling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8910899879720209472:8:0) after 3m59s
[01:06:48.142] Handling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8910899879720209472:8:0)
[01:06:48.176] Build status: STARTED
[01:06:48.176] Scheduling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8910899879720209472:9:0) after 6m36s
[01:13:24.196] Handling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8910899879720209472:9:0)
[01:13:24.221] Build status: STARTED
[01:13:24.221] Scheduling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8910899879720209472:10:0) after 5m55s
[01:19:19.272] Handling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8910899879720209472:10:0)
[01:19:19.314] Build status: STARTED
[01:19:19.314] Scheduling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8910899879720209472:11:0) after 8m7s
[01:27:26.368] Handling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8910899879720209472:11:0)
[01:27:26.386] Build status: STARTED
[01:27:26.386] Scheduling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8910899879720209472:12:0) after 2m31s
[01:29:57.884] Handling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8910899879720209472:12:0)
[01:29:57.902] Build status: STARTED
[01:29:57.902] Scheduling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8910899879720209472:13:0) after 8m33s
[01:38:31.475] Handling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8910899879720209472:13:0)
[01:38:31.496] Build status: STARTED
[01:38:31.496] Scheduling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8910899879720209472:14:0) after 2m8s
[01:40:39.519] Handling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8910899879720209472:14:0)
[01:40:39.547] Build status: STARTED
[01:40:39.547] Scheduling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8910899879720209472:15:0) after 6m6s
[01:46:45.616] Handling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8910899879720209472:15:0)
[01:46:45.668] Build status: STARTED
[01:46:45.668] Scheduling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8910899879720209472:16:0) after 1m38s
[01:48:23.735] Handling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8910899879720209472:16:0)
[01:48:23.754] Build status: STARTED
[01:48:23.754] Scheduling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8910899879720209472:17:0) after 5m9s
[01:53:32.869] Handling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8910899879720209472:17:0)
[01:53:32.887] Build status: STARTED
[01:53:32.887] Scheduling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8910899879720209472:18:0) after 6m51s
[02:00:23.884] Handling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8910899879720209472:18:0)
[02:00:23.928] Build status: STARTED
[02:00:23.928] Scheduling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8910899879720209472:19:0) after 1m22s
[02:01:45.955] Handling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8910899879720209472:19:0)
[02:01:45.973] Build status: STARTED
[02:01:45.973] Scheduling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8910899879720209472:20:0) after 4m57s
[02:06:42.998] Handling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8910899879720209472:20:0)
[02:06:43.027] Build status: STARTED
[02:06:43.028] Scheduling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8910899879720209472:21:0) after 1m13s
[02:07:56.055] Handling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8910899879720209472:21:0)
[02:07:56.075] Build status: STARTED
[02:07:56.075] Scheduling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8910899879720209472:22:0) after 1m32s
[02:09:28.116] Handling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8910899879720209472:22:0)
[02:09:28.137] Build status: STARTED
[02:09:28.138] Scheduling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8910899879720209472:23:0) after 9m28s
[02:16:06.127] Received PubSub notification, asking Buildbucket for the build status
[02:16:06.155] Build:
{
"id": "8745469817847466561",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "kythe"
},
"createdBy": "project:fuchsia",
"createTime": "2024-06-11T00:51:12.020325407Z",
"startTime": "2024-06-11T00:51:17.002238187Z",
"endTime": "2024-06-11T02:16:05.673090617Z",
"updateTime": "2024-06-11T02:16:05.673090617Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "70c81beddc7888fbce87f5fa47cc2736b18efb67",
"ref": "refs/heads/main"
}
}
}
[02:16:06.155] Invocation finished in 1h24m55.303883276s with status SUCCEEDED