[23:16:50.827] New invocation is queued and will start shortly
[23:16:51.951] Starting the invocation (attempt 1)
[23:16:51.981] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/09108a00ddf90f6068797855c196287cf23287ac"
[23:16:51.981] Popped gitiles commit info from properties and tags
[23:16:51.981] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:16:51.981] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:16:51.982] Buildbucket request:
{
"requestId": "8937450725852474320",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8937450725852474320",
"job": "fuchsia/core.x64-debug-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "1fa514c4cb9eb7fd963c997656e4309daba76539"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@1fa514c4cb9eb7fd963c997656e4309daba76539",
"title": "1fa514c4cb9eb7fd963c997656e4309daba76539",
"url": "https://fuchsia.googlesource.com/integration/+/1fa514c4cb9eb7fd963c997656e4309daba76539"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a5c8448e0aa7f13a8b32469125782b74d676c809"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a5c8448e0aa7f13a8b32469125782b74d676c809",
"title": "a5c8448e0aa7f13a8b32469125782b74d676c809",
"url": "https://fuchsia.googlesource.com/integration/+/a5c8448e0aa7f13a8b32469125782b74d676c809"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "06646afc96e1aab9606330fae1573b6d74947960"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@06646afc96e1aab9606330fae1573b6d74947960",
"title": "06646afc96e1aab9606330fae1573b6d74947960",
"url": "https://fuchsia.googlesource.com/integration/+/06646afc96e1aab9606330fae1573b6d74947960"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "0a140e3bf67f83d42604342a814431c4e2dd77fb"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@0a140e3bf67f83d42604342a814431c4e2dd77fb",
"title": "0a140e3bf67f83d42604342a814431c4e2dd77fb",
"url": "https://fuchsia.googlesource.com/integration/+/0a140e3bf67f83d42604342a814431c4e2dd77fb"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "1457c3da3bfbf4d69c45041a48c88b64ab3fd90e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@1457c3da3bfbf4d69c45041a48c88b64ab3fd90e",
"title": "1457c3da3bfbf4d69c45041a48c88b64ab3fd90e",
"url": "https://fuchsia.googlesource.com/integration/+/1457c3da3bfbf4d69c45041a48c88b64ab3fd90e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a890f756d3e0b0a49c4256a404f91c7d8160bf12"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a890f756d3e0b0a49c4256a404f91c7d8160bf12",
"title": "a890f756d3e0b0a49c4256a404f91c7d8160bf12",
"url": "https://fuchsia.googlesource.com/integration/+/a890f756d3e0b0a49c4256a404f91c7d8160bf12"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "243cffe178ae8899e07f1ef7ad8eefee559417cc"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@243cffe178ae8899e07f1ef7ad8eefee559417cc",
"title": "243cffe178ae8899e07f1ef7ad8eefee559417cc",
"url": "https://fuchsia.googlesource.com/integration/+/243cffe178ae8899e07f1ef7ad8eefee559417cc"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "952ef0a1f903939f5f24c3083629cb9dece9540b"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@952ef0a1f903939f5f24c3083629cb9dece9540b",
"title": "952ef0a1f903939f5f24c3083629cb9dece9540b",
"url": "https://fuchsia.googlesource.com/integration/+/952ef0a1f903939f5f24c3083629cb9dece9540b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "4c80cf5300408a9e50e278401df30113538533ca"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@4c80cf5300408a9e50e278401df30113538533ca",
"title": "4c80cf5300408a9e50e278401df30113538533ca",
"url": "https://fuchsia.googlesource.com/integration/+/4c80cf5300408a9e50e278401df30113538533ca"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "885e11992e5e54e24a92bad1d09e3633d02f17e7"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@885e11992e5e54e24a92bad1d09e3633d02f17e7",
"title": "885e11992e5e54e24a92bad1d09e3633d02f17e7",
"url": "https://fuchsia.googlesource.com/integration/+/885e11992e5e54e24a92bad1d09e3633d02f17e7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "98cc8846a9da10ddd8ebda2ac3979a556b38a449"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@98cc8846a9da10ddd8ebda2ac3979a556b38a449",
"title": "98cc8846a9da10ddd8ebda2ac3979a556b38a449",
"url": "https://fuchsia.googlesource.com/integration/+/98cc8846a9da10ddd8ebda2ac3979a556b38a449"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c71590fbf3761d94273fed78ce99c76856618a35"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c71590fbf3761d94273fed78ce99c76856618a35",
"title": "c71590fbf3761d94273fed78ce99c76856618a35",
"url": "https://fuchsia.googlesource.com/integration/+/c71590fbf3761d94273fed78ce99c76856618a35"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "235656fc5a4ebfa658ec0d91cab4650bc29b8f2e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@235656fc5a4ebfa658ec0d91cab4650bc29b8f2e",
"title": "235656fc5a4ebfa658ec0d91cab4650bc29b8f2e",
"url": "https://fuchsia.googlesource.com/integration/+/235656fc5a4ebfa658ec0d91cab4650bc29b8f2e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "48445361409da701260560619d43bafd1ef4d00a"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@48445361409da701260560619d43bafd1ef4d00a",
"title": "48445361409da701260560619d43bafd1ef4d00a",
"url": "https://fuchsia.googlesource.com/integration/+/48445361409da701260560619d43bafd1ef4d00a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b002ac57d62da304c606ec9a9bcb5cbe21da8dc6"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b002ac57d62da304c606ec9a9bcb5cbe21da8dc6",
"title": "b002ac57d62da304c606ec9a9bcb5cbe21da8dc6",
"url": "https://fuchsia.googlesource.com/integration/+/b002ac57d62da304c606ec9a9bcb5cbe21da8dc6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "74474397a4d5297c93802e21b059879ade82b21a"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@74474397a4d5297c93802e21b059879ade82b21a",
"title": "74474397a4d5297c93802e21b059879ade82b21a",
"url": "https://fuchsia.googlesource.com/integration/+/74474397a4d5297c93802e21b059879ade82b21a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "09108a00ddf90f6068797855c196287cf23287ac"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@09108a00ddf90f6068797855c196287cf23287ac",
"title": "09108a00ddf90f6068797855c196287cf23287ac",
"url": "https://fuchsia.googlesource.com/integration/+/09108a00ddf90f6068797855c196287cf23287ac"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "09108a00ddf90f6068797855c196287cf23287ac",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8937450725852474320"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/core.x64-debug-e5a0ec7f"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[23:16:52.494] Scheduled build:
{
"id": "8772020663896349633",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-08-22T23:16:52.065957400Z",
"updateTime": "2023-08-22T23:16:52.065957400Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "09108a00ddf90f6068797855c196287cf23287ac",
"ref": "refs/heads/main"
}
}
}
[23:16:52.494] Task URL: https://cr-buildbucket.appspot.com/build/8772020663896349633
[23:16:52.494] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937450725852474320:2:0) after 1m34s
[23:16:53.435] Received PubSub notification, asking Buildbucket for the build status
[23:16:53.461] Build status: SCHEDULED
[23:16:54.628] Received PubSub notification, asking Buildbucket for the build status
[23:16:54.655] Build status: STARTED
[23:18:26.545] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937450725852474320:2:0)
[23:18:26.569] Build status: STARTED
[23:18:26.569] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937450725852474320:5:0) after 8m52s
[23:27:18.815] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937450725852474320:5:0)
[23:27:18.836] Build status: STARTED
[23:27:18.836] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937450725852474320:6:0) after 5m6s
[23:32:24.918] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937450725852474320:6:0)
[23:32:24.949] Build status: STARTED
[23:32:24.949] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937450725852474320:7:0) after 2m39s
[23:35:04.153] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937450725852474320:7:0)
[23:35:04.182] Build status: STARTED
[23:35:04.182] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937450725852474320:8:0) after 7m39s
[23:42:43.207] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937450725852474320:8:0)
[23:42:43.224] Build status: STARTED
[23:42:43.225] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937450725852474320:9:0) after 5m6s
[23:47:49.225] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937450725852474320:9:0)
[23:47:49.265] Build status: STARTED
[23:47:49.265] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937450725852474320:10:0) after 8m0s
[23:55:49.293] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937450725852474320:10:0)
[23:55:49.312] Build status: STARTED
[23:55:49.312] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937450725852474320:11:0) after 1m23s
[23:57:12.504] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937450725852474320:11:0)
[23:57:12.540] Build status: STARTED
[23:57:12.540] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937450725852474320:12:0) after 3m49s
[00:01:01.672] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937450725852474320:12:0)
[00:01:01.727] Build status: STARTED
[00:01:01.727] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937450725852474320:13:0) after 8m28s
[00:09:29.878] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937450725852474320:13:0)
[00:09:29.896] Build status: STARTED
[00:09:29.896] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937450725852474320:14:0) after 5m0s
[00:14:29.933] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937450725852474320:14:0)
[00:14:29.954] Build status: STARTED
[00:14:29.954] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937450725852474320:15:0) after 9m35s
[00:24:04.983] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937450725852474320:15:0)
[00:24:05.004] Build status: STARTED
[00:24:05.004] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937450725852474320:16:0) after 1m39s
[00:25:44.017] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937450725852474320:16:0)
[00:25:44.036] Build status: STARTED
[00:25:44.036] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937450725852474320:17:0) after 6m21s
[00:32:05.174] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937450725852474320:17:0)
[00:32:05.265] Build status: STARTED
[00:32:05.265] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937450725852474320:18:0) after 5m7s
[00:32:14.001] Received PubSub notification, asking Buildbucket for the build status
[00:32:14.026] Build:
{
"id": "8772020663896349633",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-08-22T23:16:52.065957400Z",
"startTime": "2023-08-22T23:16:53.993291Z",
"endTime": "2023-08-23T00:32:08.907836747Z",
"updateTime": "2023-08-23T00:32:08.907836747Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "09108a00ddf90f6068797855c196287cf23287ac",
"ref": "refs/heads/main"
}
}
}
[00:32:14.026] Invocation finished in 1h15m23.210117217s with status SUCCEEDED