[22:02:14.211] New invocation is queued and will start shortly
[22:02:16.137] Starting the invocation (attempt 1)
[22:02:16.178] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/b7d5d7f518a9712b1ff799eddebd6255f168e2cd"
[22:02:16.178] Popped gitiles commit info from properties and tags
[22:02:16.178] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:02:16.178] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:02:16.183] Buildbucket request:
{
"requestId": "8929754677776868160",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.arm64-asan"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8929754677776868160",
"job": "fuchsia/core.arm64-asan-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d12c73846998ea3ee5fe8c261f9cb6b44f75fe59"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d12c73846998ea3ee5fe8c261f9cb6b44f75fe59",
"title": "d12c73846998ea3ee5fe8c261f9cb6b44f75fe59",
"url": "https://fuchsia.googlesource.com/integration/+/d12c73846998ea3ee5fe8c261f9cb6b44f75fe59"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c78ef64b064bd7457dbbaa07ff69dc66706be535"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c78ef64b064bd7457dbbaa07ff69dc66706be535",
"title": "c78ef64b064bd7457dbbaa07ff69dc66706be535",
"url": "https://fuchsia.googlesource.com/integration/+/c78ef64b064bd7457dbbaa07ff69dc66706be535"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "31a17ccc3b2a33a89a13e1b6d965b5a787948c1f"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@31a17ccc3b2a33a89a13e1b6d965b5a787948c1f",
"title": "31a17ccc3b2a33a89a13e1b6d965b5a787948c1f",
"url": "https://fuchsia.googlesource.com/integration/+/31a17ccc3b2a33a89a13e1b6d965b5a787948c1f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "1dd7a429cd5c1b7442e4d9d83ced43c821317b43"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@1dd7a429cd5c1b7442e4d9d83ced43c821317b43",
"title": "1dd7a429cd5c1b7442e4d9d83ced43c821317b43",
"url": "https://fuchsia.googlesource.com/integration/+/1dd7a429cd5c1b7442e4d9d83ced43c821317b43"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "777389ff6fbea5a12d5953eb5df4ccdba33f8174"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@777389ff6fbea5a12d5953eb5df4ccdba33f8174",
"title": "777389ff6fbea5a12d5953eb5df4ccdba33f8174",
"url": "https://fuchsia.googlesource.com/integration/+/777389ff6fbea5a12d5953eb5df4ccdba33f8174"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "ee1f447dc6891ce839a6385a6a191a71c3c1e097"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@ee1f447dc6891ce839a6385a6a191a71c3c1e097",
"title": "ee1f447dc6891ce839a6385a6a191a71c3c1e097",
"url": "https://fuchsia.googlesource.com/integration/+/ee1f447dc6891ce839a6385a6a191a71c3c1e097"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "73b890cb81595a020291ce5b84e86eb00c053991"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@73b890cb81595a020291ce5b84e86eb00c053991",
"title": "73b890cb81595a020291ce5b84e86eb00c053991",
"url": "https://fuchsia.googlesource.com/integration/+/73b890cb81595a020291ce5b84e86eb00c053991"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "fb63e36f2cda377f488dd6e43194f0b34edd5d3f"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@fb63e36f2cda377f488dd6e43194f0b34edd5d3f",
"title": "fb63e36f2cda377f488dd6e43194f0b34edd5d3f",
"url": "https://fuchsia.googlesource.com/integration/+/fb63e36f2cda377f488dd6e43194f0b34edd5d3f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "eff7d5aa8ed3614a71ce562600a0b9df3902bc4c"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@eff7d5aa8ed3614a71ce562600a0b9df3902bc4c",
"title": "eff7d5aa8ed3614a71ce562600a0b9df3902bc4c",
"url": "https://fuchsia.googlesource.com/integration/+/eff7d5aa8ed3614a71ce562600a0b9df3902bc4c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "5c9d864b6d91b8e5c584c238067744f0803d917a"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@5c9d864b6d91b8e5c584c238067744f0803d917a",
"title": "5c9d864b6d91b8e5c584c238067744f0803d917a",
"url": "https://fuchsia.googlesource.com/integration/+/5c9d864b6d91b8e5c584c238067744f0803d917a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "db1cc3861fde70833de22805064a4ffc49163c47"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@db1cc3861fde70833de22805064a4ffc49163c47",
"title": "db1cc3861fde70833de22805064a4ffc49163c47",
"url": "https://fuchsia.googlesource.com/integration/+/db1cc3861fde70833de22805064a4ffc49163c47"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "1a430cb06f53fad74ee96a46cddf00a0f1e33d64"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@1a430cb06f53fad74ee96a46cddf00a0f1e33d64",
"title": "1a430cb06f53fad74ee96a46cddf00a0f1e33d64",
"url": "https://fuchsia.googlesource.com/integration/+/1a430cb06f53fad74ee96a46cddf00a0f1e33d64"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "902fc9f95bf26acff2df0682fc362897af532599"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@902fc9f95bf26acff2df0682fc362897af532599",
"title": "902fc9f95bf26acff2df0682fc362897af532599",
"url": "https://fuchsia.googlesource.com/integration/+/902fc9f95bf26acff2df0682fc362897af532599"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c152cdaf08dcfeeaf3e49255e2ed749da7769c7c"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c152cdaf08dcfeeaf3e49255e2ed749da7769c7c",
"title": "c152cdaf08dcfeeaf3e49255e2ed749da7769c7c",
"url": "https://fuchsia.googlesource.com/integration/+/c152cdaf08dcfeeaf3e49255e2ed749da7769c7c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "7c3adf2fecea1bf7df52b06732204130b1797bf3"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@7c3adf2fecea1bf7df52b06732204130b1797bf3",
"title": "7c3adf2fecea1bf7df52b06732204130b1797bf3",
"url": "https://fuchsia.googlesource.com/integration/+/7c3adf2fecea1bf7df52b06732204130b1797bf3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "ad3d93ede62879917c3f0e7605714094b2200af4"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@ad3d93ede62879917c3f0e7605714094b2200af4",
"title": "ad3d93ede62879917c3f0e7605714094b2200af4",
"url": "https://fuchsia.googlesource.com/integration/+/ad3d93ede62879917c3f0e7605714094b2200af4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "6fa6ce0d50e10750c18fabb125312bcfe45bf2f2"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@6fa6ce0d50e10750c18fabb125312bcfe45bf2f2",
"title": "6fa6ce0d50e10750c18fabb125312bcfe45bf2f2",
"url": "https://fuchsia.googlesource.com/integration/+/6fa6ce0d50e10750c18fabb125312bcfe45bf2f2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b7d5d7f518a9712b1ff799eddebd6255f168e2cd"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b7d5d7f518a9712b1ff799eddebd6255f168e2cd",
"title": "b7d5d7f518a9712b1ff799eddebd6255f168e2cd",
"url": "https://fuchsia.googlesource.com/integration/+/b7d5d7f518a9712b1ff799eddebd6255f168e2cd"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "b7d5d7f518a9712b1ff799eddebd6255f168e2cd",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8929754677776868160"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/core.arm64-asan-e5a0ec7f"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[22:02:16.816] Scheduled build:
{
"id": "8764324614983257313",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.arm64-asan"
},
"createdBy": "project:fuchsia",
"createTime": "2023-11-15T22:02:16.251376570Z",
"updateTime": "2023-11-15T22:02:16.251376570Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "b7d5d7f518a9712b1ff799eddebd6255f168e2cd",
"ref": "refs/heads/main"
}
}
}
[22:02:16.816] Task URL: https://cr-buildbucket.appspot.com/build/8764324614983257313
[22:02:16.816] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8929754677776868160:2:0) after 8m26s
[22:02:17.707] Received PubSub notification, asking Buildbucket for the build status
[22:02:17.737] Build status: SCHEDULED
[22:03:10.770] Received PubSub notification, asking Buildbucket for the build status
[22:03:10.863] Build status: STARTED
[22:10:43.992] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8929754677776868160:2:0)
[22:10:44.027] Build status: STARTED
[22:10:44.027] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8929754677776868160:5:0) after 3m1s
[22:13:45.052] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8929754677776868160:5:0)
[22:13:45.078] Build status: STARTED
[22:13:45.078] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8929754677776868160:6:0) after 7m11s
[22:20:56.453] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8929754677776868160:6:0)
[22:20:56.478] Build status: STARTED
[22:20:56.478] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8929754677776868160:7:0) after 5m25s
[22:26:21.508] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8929754677776868160:7:0)
[22:26:21.534] Build status: STARTED
[22:26:21.534] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8929754677776868160:8:0) after 9m28s
[22:35:49.615] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8929754677776868160:8:0)
[22:35:49.632] Build status: STARTED
[22:35:49.632] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8929754677776868160:9:0) after 5m31s
[22:41:21.274] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8929754677776868160:9:0)
[22:41:21.328] Build status: STARTED
[22:41:21.328] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8929754677776868160:10:0) after 6m15s
[22:47:36.448] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8929754677776868160:10:0)
[22:47:36.511] Build status: STARTED
[22:47:36.511] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8929754677776868160:11:0) after 2m29s
[22:50:05.600] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8929754677776868160:11:0)
[22:50:05.620] Build status: STARTED
[22:50:05.621] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8929754677776868160:12:0) after 1m13s
[22:51:18.596] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8929754677776868160:12:0)
[22:51:18.705] Build status: STARTED
[22:51:18.705] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8929754677776868160:13:0) after 7m33s
[22:58:51.825] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8929754677776868160:13:0)
[22:58:51.868] Build status: STARTED
[22:58:51.868] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8929754677776868160:14:0) after 5m58s
[23:04:49.982] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8929754677776868160:14:0)
[23:04:50.024] Build status: STARTED
[23:04:50.024] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8929754677776868160:15:0) after 9m40s
[23:07:41.366] Received PubSub notification, asking Buildbucket for the build status
[23:07:41.853] Build:
{
"id": "8764324614983257313",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.arm64-asan"
},
"createdBy": "project:fuchsia",
"createTime": "2023-11-15T22:02:16.251376570Z",
"startTime": "2023-11-15T22:03:09.535485Z",
"endTime": "2023-11-15T23:07:40.517616Z",
"updateTime": "2023-11-15T23:07:40.517616Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "b7d5d7f518a9712b1ff799eddebd6255f168e2cd",
"ref": "refs/heads/main"
}
}
}
[23:07:41.853] Invocation finished in 1h5m27.650173955s with status SUCCEEDED