[20:46:17.180] New invocation is queued and will start shortly
[20:46:18.432] Starting the invocation (attempt 1)
[20:46:18.467] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/fedf39d58661035447ed811b9a3f4cebed0a8439"
[20:46:18.467] Popped gitiles commit info from properties and tags
[20:46:18.467] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:46:18.467] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:46:18.468] Buildbucket request:
{
"requestId": "8918525432345105408",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "kythe"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8918525432345105408",
"job": "fuchsia/kythe-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "9b6e850f8be36de64e2a1cce1e175d2cc8526258"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@9b6e850f8be36de64e2a1cce1e175d2cc8526258",
"title": "9b6e850f8be36de64e2a1cce1e175d2cc8526258",
"url": "https://fuchsia.googlesource.com/integration/+/9b6e850f8be36de64e2a1cce1e175d2cc8526258"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "1b9d0e248eed5bf27d742e9f61ded668b5aa44e3"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@1b9d0e248eed5bf27d742e9f61ded668b5aa44e3",
"title": "1b9d0e248eed5bf27d742e9f61ded668b5aa44e3",
"url": "https://fuchsia.googlesource.com/integration/+/1b9d0e248eed5bf27d742e9f61ded668b5aa44e3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "ddc9651642f030d0ebd10b192ad51c945b02eec4"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@ddc9651642f030d0ebd10b192ad51c945b02eec4",
"title": "ddc9651642f030d0ebd10b192ad51c945b02eec4",
"url": "https://fuchsia.googlesource.com/integration/+/ddc9651642f030d0ebd10b192ad51c945b02eec4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "bfadc70b7dc284f87af0bd195b717911cf7f0cc8"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@bfadc70b7dc284f87af0bd195b717911cf7f0cc8",
"title": "bfadc70b7dc284f87af0bd195b717911cf7f0cc8",
"url": "https://fuchsia.googlesource.com/integration/+/bfadc70b7dc284f87af0bd195b717911cf7f0cc8"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "55766105085db46bfcae42e3ab877eec6c62f842"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@55766105085db46bfcae42e3ab877eec6c62f842",
"title": "55766105085db46bfcae42e3ab877eec6c62f842",
"url": "https://fuchsia.googlesource.com/integration/+/55766105085db46bfcae42e3ab877eec6c62f842"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "1ad68a957d69b9ecca367907fe0ff1e9939ddd69"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@1ad68a957d69b9ecca367907fe0ff1e9939ddd69",
"title": "1ad68a957d69b9ecca367907fe0ff1e9939ddd69",
"url": "https://fuchsia.googlesource.com/integration/+/1ad68a957d69b9ecca367907fe0ff1e9939ddd69"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "aff7a0b32d2d658297403905639809b52a192053"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@aff7a0b32d2d658297403905639809b52a192053",
"title": "aff7a0b32d2d658297403905639809b52a192053",
"url": "https://fuchsia.googlesource.com/integration/+/aff7a0b32d2d658297403905639809b52a192053"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "9d2d8af3e8cff81755a7178c714762d2c98808fd"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@9d2d8af3e8cff81755a7178c714762d2c98808fd",
"title": "9d2d8af3e8cff81755a7178c714762d2c98808fd",
"url": "https://fuchsia.googlesource.com/integration/+/9d2d8af3e8cff81755a7178c714762d2c98808fd"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c3a73ae8c5c192fe801af0198377abf210540de8"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c3a73ae8c5c192fe801af0198377abf210540de8",
"title": "c3a73ae8c5c192fe801af0198377abf210540de8",
"url": "https://fuchsia.googlesource.com/integration/+/c3a73ae8c5c192fe801af0198377abf210540de8"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "fedf39d58661035447ed811b9a3f4cebed0a8439"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@fedf39d58661035447ed811b9a3f4cebed0a8439",
"title": "fedf39d58661035447ed811b9a3f4cebed0a8439",
"url": "https://fuchsia.googlesource.com/integration/+/fedf39d58661035447ed811b9a3f4cebed0a8439"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "fedf39d58661035447ed811b9a3f4cebed0a8439",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8918525432345105408"
},
{
"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"
}
}
[20:46:20.886] Scheduled build:
{
"id": "8753095370266075841",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "kythe"
},
"createdBy": "project:fuchsia",
"createTime": "2024-03-18T20:46:18.530634081Z",
"updateTime": "2024-03-18T20:46:18.530634081Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "fedf39d58661035447ed811b9a3f4cebed0a8439",
"ref": "refs/heads/main"
}
},
"canary": true
}
[20:46:20.886] Task URL: https://cr-buildbucket.appspot.com/build/8753095370266075841
[20:46:20.886] Scheduling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8918525432345105408:2:0) after 5m37s
[20:46:21.582] Received PubSub notification, asking Buildbucket for the build status
[20:46:21.601] Build status: SCHEDULED
[20:46:22.474] Received PubSub notification, asking Buildbucket for the build status
[20:46:22.508] Build status: STARTED
[20:51:57.905] Handling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8918525432345105408:2:0)
[20:51:57.963] Build status: STARTED
[20:51:57.963] Scheduling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8918525432345105408:5:0) after 6m18s
[20:58:16.154] Handling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8918525432345105408:5:0)
[20:58:16.207] Build status: STARTED
[20:58:16.207] Scheduling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8918525432345105408:6:0) after 3m44s
[21:02:00.228] Handling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8918525432345105408:6:0)
[21:02:00.258] Build status: STARTED
[21:02:00.258] Scheduling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8918525432345105408:7:0) after 3m47s
[21:05:47.284] Handling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8918525432345105408:7:0)
[21:05:47.308] Build status: STARTED
[21:05:47.308] Scheduling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8918525432345105408:8:0) after 3m15s
[21:09:02.338] Handling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8918525432345105408:8:0)
[21:09:02.355] Build status: STARTED
[21:09:02.355] Scheduling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8918525432345105408:9:0) after 6m35s
[21:15:37.459] Handling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8918525432345105408:9:0)
[21:15:37.492] Build status: STARTED
[21:15:37.492] Scheduling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8918525432345105408:10:0) after 3m14s
[21:18:51.589] Handling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8918525432345105408:10:0)
[21:18:51.607] Build status: STARTED
[21:18:51.607] Scheduling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8918525432345105408:11:0) after 9m30s
[21:28:21.661] Handling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8918525432345105408:11:0)
[21:28:21.725] Build status: STARTED
[21:28:21.725] Scheduling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8918525432345105408:12:0) after 4m16s
[21:32:37.844] Handling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8918525432345105408:12:0)
[21:32:37.871] Build status: STARTED
[21:32:37.871] Scheduling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8918525432345105408:13:0) after 5m46s
[21:38:23.889] Handling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8918525432345105408:13:0)
[21:38:23.906] Build status: STARTED
[21:38:23.906] Scheduling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8918525432345105408:14:0) after 6m31s
[21:44:54.925] Handling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8918525432345105408:14:0)
[21:44:54.950] Build status: STARTED
[21:44:54.950] Scheduling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8918525432345105408:15:0) after 2m9s
[21:47:03.971] Handling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8918525432345105408:15:0)
[21:47:03.988] Build status: STARTED
[21:47:03.988] Scheduling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8918525432345105408:16:0) after 3m34s
[21:50:38.007] Handling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8918525432345105408:16:0)
[21:50:38.074] Build status: STARTED
[21:50:38.075] Scheduling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8918525432345105408:17:0) after 1m18s
[21:51:56.166] Handling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8918525432345105408:17:0)
[21:51:56.193] Build status: STARTED
[21:51:56.193] Scheduling timer "check-buildbucket-build-status" (fuchsia/kythe-e5a0ec7f:8918525432345105408:18:0) after 8m50s
[21:55:54.102] Received PubSub notification, asking Buildbucket for the build status
[21:55:54.123] Build:
{
"id": "8753095370266075841",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "kythe"
},
"createdBy": "project:fuchsia",
"createTime": "2024-03-18T20:46:18.530634081Z",
"startTime": "2024-03-18T20:46:21.626393Z",
"endTime": "2024-03-18T21:55:53.289721Z",
"updateTime": "2024-03-18T21:55:53.289721Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "fedf39d58661035447ed811b9a3f4cebed0a8439",
"ref": "refs/heads/main"
}
},
"canary": true
}
[21:55:54.123] Invocation finished in 1h9m36.959745136s with status SUCCEEDED