[00:06:56.984] New invocation is queued and will start shortly
[00:06:58.178] Starting the invocation (attempt 1)
[00:06:58.205] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/50cd2596b6617c790d99b71fc87c1a5fe2efea16"
[00:06:58.205] Popped gitiles commit info from properties and tags
[00:06:58.205] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:06:58.205] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:06:58.206] Buildbucket request:
{
"requestId": "8897494311481257968",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-gce"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8897494311481257968",
"job": "fuchsia/core.x64-gce-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "70a3636f95eefcd2681e8fd4f5df896bf335c871"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@70a3636f95eefcd2681e8fd4f5df896bf335c871",
"title": "70a3636f95eefcd2681e8fd4f5df896bf335c871",
"url": "https://fuchsia.googlesource.com/integration/+/70a3636f95eefcd2681e8fd4f5df896bf335c871"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "0d476fac00a49aeec9a8eb94505ca7d263a583a3"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@0d476fac00a49aeec9a8eb94505ca7d263a583a3",
"title": "0d476fac00a49aeec9a8eb94505ca7d263a583a3",
"url": "https://fuchsia.googlesource.com/integration/+/0d476fac00a49aeec9a8eb94505ca7d263a583a3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d6572a60d9c05e5e46b19d872889ca8e595f697a"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d6572a60d9c05e5e46b19d872889ca8e595f697a",
"title": "d6572a60d9c05e5e46b19d872889ca8e595f697a",
"url": "https://fuchsia.googlesource.com/integration/+/d6572a60d9c05e5e46b19d872889ca8e595f697a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c8a063720eeff96da27714711d49d5dd9f9918bb"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c8a063720eeff96da27714711d49d5dd9f9918bb",
"title": "c8a063720eeff96da27714711d49d5dd9f9918bb",
"url": "https://fuchsia.googlesource.com/integration/+/c8a063720eeff96da27714711d49d5dd9f9918bb"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d81bf10364a468a2e69e15cfd31fc46639009bbf"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d81bf10364a468a2e69e15cfd31fc46639009bbf",
"title": "d81bf10364a468a2e69e15cfd31fc46639009bbf",
"url": "https://fuchsia.googlesource.com/integration/+/d81bf10364a468a2e69e15cfd31fc46639009bbf"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "37a7fb1ec9d5ac31c05cc4a9a0f823c8bb6fac35"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@37a7fb1ec9d5ac31c05cc4a9a0f823c8bb6fac35",
"title": "37a7fb1ec9d5ac31c05cc4a9a0f823c8bb6fac35",
"url": "https://fuchsia.googlesource.com/integration/+/37a7fb1ec9d5ac31c05cc4a9a0f823c8bb6fac35"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "ba7efde3a98e47a4e121d696462458d844e17ff4"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@ba7efde3a98e47a4e121d696462458d844e17ff4",
"title": "ba7efde3a98e47a4e121d696462458d844e17ff4",
"url": "https://fuchsia.googlesource.com/integration/+/ba7efde3a98e47a4e121d696462458d844e17ff4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "920460f2fe94255771e96b6120d4084bcca8cc55"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@920460f2fe94255771e96b6120d4084bcca8cc55",
"title": "920460f2fe94255771e96b6120d4084bcca8cc55",
"url": "https://fuchsia.googlesource.com/integration/+/920460f2fe94255771e96b6120d4084bcca8cc55"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "2399c7ec7f148d60bc931ea93d4f5c0003bd3e86"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@2399c7ec7f148d60bc931ea93d4f5c0003bd3e86",
"title": "2399c7ec7f148d60bc931ea93d4f5c0003bd3e86",
"url": "https://fuchsia.googlesource.com/integration/+/2399c7ec7f148d60bc931ea93d4f5c0003bd3e86"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c1a537f99b318559af7850093608af3b89d204cc"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c1a537f99b318559af7850093608af3b89d204cc",
"title": "c1a537f99b318559af7850093608af3b89d204cc",
"url": "https://fuchsia.googlesource.com/integration/+/c1a537f99b318559af7850093608af3b89d204cc"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a96d663331f178e7503b2ba6db907f6a6964d30a"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a96d663331f178e7503b2ba6db907f6a6964d30a",
"title": "a96d663331f178e7503b2ba6db907f6a6964d30a",
"url": "https://fuchsia.googlesource.com/integration/+/a96d663331f178e7503b2ba6db907f6a6964d30a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "cedf449c444f8d5e4ca884a4cbcf677b4ad9b53a"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@cedf449c444f8d5e4ca884a4cbcf677b4ad9b53a",
"title": "cedf449c444f8d5e4ca884a4cbcf677b4ad9b53a",
"url": "https://fuchsia.googlesource.com/integration/+/cedf449c444f8d5e4ca884a4cbcf677b4ad9b53a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "4f69d6befff84362ddb2e0b8351154c4a734d7aa"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@4f69d6befff84362ddb2e0b8351154c4a734d7aa",
"title": "4f69d6befff84362ddb2e0b8351154c4a734d7aa",
"url": "https://fuchsia.googlesource.com/integration/+/4f69d6befff84362ddb2e0b8351154c4a734d7aa"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "415abc2e7607c8f1c880d0c047f587395d4ecb57"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@415abc2e7607c8f1c880d0c047f587395d4ecb57",
"title": "415abc2e7607c8f1c880d0c047f587395d4ecb57",
"url": "https://fuchsia.googlesource.com/integration/+/415abc2e7607c8f1c880d0c047f587395d4ecb57"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "fb5e21cb1359ff35810ed6fe856f5a1d0664f14a"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@fb5e21cb1359ff35810ed6fe856f5a1d0664f14a",
"title": "fb5e21cb1359ff35810ed6fe856f5a1d0664f14a",
"url": "https://fuchsia.googlesource.com/integration/+/fb5e21cb1359ff35810ed6fe856f5a1d0664f14a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "e7bfc1e88ee6310d9935d2b12a1fb1f7c460a5f6"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@e7bfc1e88ee6310d9935d2b12a1fb1f7c460a5f6",
"title": "e7bfc1e88ee6310d9935d2b12a1fb1f7c460a5f6",
"url": "https://fuchsia.googlesource.com/integration/+/e7bfc1e88ee6310d9935d2b12a1fb1f7c460a5f6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "50cd2596b6617c790d99b71fc87c1a5fe2efea16"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@50cd2596b6617c790d99b71fc87c1a5fe2efea16",
"title": "50cd2596b6617c790d99b71fc87c1a5fe2efea16",
"url": "https://fuchsia.googlesource.com/integration/+/50cd2596b6617c790d99b71fc87c1a5fe2efea16"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "50cd2596b6617c790d99b71fc87c1a5fe2efea16",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8897494311481257968"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/core.x64-gce-e5a0ec7f"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[00:06:58.567] Scheduled build:
{
"id": "8732064249495211649",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-gce"
},
"createdBy": "project:fuchsia",
"createTime": "2024-11-06T00:06:58.255449537Z",
"updateTime": "2024-11-06T00:06:58.255449537Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "50cd2596b6617c790d99b71fc87c1a5fe2efea16",
"ref": "refs/heads/main"
}
}
}
[00:06:58.567] Task URL: https://cr-buildbucket.appspot.com/build/8732064249495211649
[00:06:58.567] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-gce-e5a0ec7f:8897494311481257968:2:0) after 7m32s
[00:06:59.594] Received PubSub notification, asking Buildbucket for the build status
[00:06:59.630] Build status: SCHEDULED
[00:07:03.935] Received PubSub notification, asking Buildbucket for the build status
[00:07:03.961] Build status: STARTED
[00:14:30.602] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-gce-e5a0ec7f:8897494311481257968:2:0)
[00:14:30.700] Build status: STARTED
[00:14:30.700] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-gce-e5a0ec7f:8897494311481257968:5:0) after 8m18s
[00:22:48.772] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-gce-e5a0ec7f:8897494311481257968:5:0)
[00:22:48.809] Build status: STARTED
[00:22:48.809] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-gce-e5a0ec7f:8897494311481257968:6:0) after 4m37s
[00:27:25.876] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-gce-e5a0ec7f:8897494311481257968:6:0)
[00:27:25.906] Build status: STARTED
[00:27:25.906] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-gce-e5a0ec7f:8897494311481257968:7:0) after 3m53s
[00:31:18.924] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-gce-e5a0ec7f:8897494311481257968:7:0)
[00:31:18.944] Build status: STARTED
[00:31:18.944] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-gce-e5a0ec7f:8897494311481257968:8:0) after 3m16s
[00:34:34.942] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-gce-e5a0ec7f:8897494311481257968:8:0)
[00:34:34.961] Build status: STARTED
[00:34:34.961] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-gce-e5a0ec7f:8897494311481257968:9:0) after 9m41s
[00:44:15.986] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-gce-e5a0ec7f:8897494311481257968:9:0)
[00:44:16.013] Build status: STARTED
[00:44:16.013] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-gce-e5a0ec7f:8897494311481257968:10:0) after 3m46s
[00:48:02.030] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-gce-e5a0ec7f:8897494311481257968:10:0)
[00:48:02.044] Build status: STARTED
[00:48:02.044] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-gce-e5a0ec7f:8897494311481257968:11:0) after 3m57s
[00:51:59.211] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-gce-e5a0ec7f:8897494311481257968:11:0)
[00:51:59.230] Build status: STARTED
[00:51:59.230] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-gce-e5a0ec7f:8897494311481257968:12:0) after 3m40s
[00:55:39.227] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-gce-e5a0ec7f:8897494311481257968:12:0)
[00:55:39.341] Build status: STARTED
[00:55:39.341] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-gce-e5a0ec7f:8897494311481257968:13:0) after 2m7s
[00:57:46.373] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-gce-e5a0ec7f:8897494311481257968:13:0)
[00:57:46.420] Build status: STARTED
[00:57:46.420] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-gce-e5a0ec7f:8897494311481257968:14:0) after 8m5s
[01:05:51.499] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-gce-e5a0ec7f:8897494311481257968:14:0)
[01:05:51.519] Build status: STARTED
[01:05:51.519] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-gce-e5a0ec7f:8897494311481257968:15:0) after 1m45s
[01:07:36.554] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-gce-e5a0ec7f:8897494311481257968:15:0)
[01:07:36.602] Build status: STARTED
[01:07:36.603] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-gce-e5a0ec7f:8897494311481257968:16:0) after 7m13s
[01:14:49.622] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-gce-e5a0ec7f:8897494311481257968:16:0)
[01:14:49.641] Build status: STARTED
[01:14:49.642] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-gce-e5a0ec7f:8897494311481257968:17:0) after 1m28s
[01:16:17.669] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-gce-e5a0ec7f:8897494311481257968:17:0)
[01:16:17.691] Build status: STARTED
[01:16:17.691] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-gce-e5a0ec7f:8897494311481257968:18:0) after 4m20s
[01:20:37.802] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-gce-e5a0ec7f:8897494311481257968:18:0)
[01:20:37.823] Build status: STARTED
[01:20:37.824] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-gce-e5a0ec7f:8897494311481257968:19:0) after 9m27s
[01:30:04.909] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-gce-e5a0ec7f:8897494311481257968:19:0)
[01:30:04.936] Build status: STARTED
[01:30:04.936] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-gce-e5a0ec7f:8897494311481257968:20:0) after 4m40s
[01:34:44.966] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-gce-e5a0ec7f:8897494311481257968:20:0)
[01:34:44.981] Build status: STARTED
[01:34:44.981] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-gce-e5a0ec7f:8897494311481257968:21:0) after 3m3s
[01:34:54.526] Received PubSub notification, asking Buildbucket for the build status
[01:34:54.551] Build:
{
"id": "8732064249495211649",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-gce"
},
"createdBy": "project:fuchsia",
"createTime": "2024-11-06T00:06:58.255449537Z",
"startTime": "2024-11-06T00:07:03.574154608Z",
"endTime": "2024-11-06T01:34:54.060445019Z",
"updateTime": "2024-11-06T01:34:54.060445019Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "50cd2596b6617c790d99b71fc87c1a5fe2efea16",
"ref": "refs/heads/main"
}
}
}
[01:34:54.551] Invocation finished in 1h27m57.574724525s with status SUCCEEDED