[04:44:34.418] New invocation is queued and will start shortly
[04:44:35.567] Starting the invocation (attempt 1)
[04:44:35.594] Popped buildset tag "buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/6a7687c455125f6597a9719227a0efcb7f71e572"
[04:44:35.594] Popped gitiles commit info from properties and tags
[04:44:35.594] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:44:35.594] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:44:35.594] Buildbucket request:
{
"requestId": "8893037593549410144",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "clang-prod-unbounded-trigger"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8893037593549410144",
"job": "fuchsia/clang-prod-unbounded-trigger-40be1bb5",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "970f65a98a681831e308860e7004e066f5152791"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@970f65a98a681831e308860e7004e066f5152791",
"title": "970f65a98a681831e308860e7004e066f5152791",
"url": "https://llvm.googlesource.com/llvm-project/+/970f65a98a681831e308860e7004e066f5152791"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "88d04be815bd289c691ab81061ac8a573ad15677"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@88d04be815bd289c691ab81061ac8a573ad15677",
"title": "88d04be815bd289c691ab81061ac8a573ad15677",
"url": "https://llvm.googlesource.com/llvm-project/+/88d04be815bd289c691ab81061ac8a573ad15677"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "b5f0ec80d59d054617994f0de409c38fabc8c207"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@b5f0ec80d59d054617994f0de409c38fabc8c207",
"title": "b5f0ec80d59d054617994f0de409c38fabc8c207",
"url": "https://llvm.googlesource.com/llvm-project/+/b5f0ec80d59d054617994f0de409c38fabc8c207"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "ac1d560709d2ecfe83a98285d4a13afae6db4316"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@ac1d560709d2ecfe83a98285d4a13afae6db4316",
"title": "ac1d560709d2ecfe83a98285d4a13afae6db4316",
"url": "https://llvm.googlesource.com/llvm-project/+/ac1d560709d2ecfe83a98285d4a13afae6db4316"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "56600c11add12aababdd313c43650facf2a0338f"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@56600c11add12aababdd313c43650facf2a0338f",
"title": "56600c11add12aababdd313c43650facf2a0338f",
"url": "https://llvm.googlesource.com/llvm-project/+/56600c11add12aababdd313c43650facf2a0338f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "34f70007348d2d1a0e59fc0996e90a0b6fba1933"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@34f70007348d2d1a0e59fc0996e90a0b6fba1933",
"title": "34f70007348d2d1a0e59fc0996e90a0b6fba1933",
"url": "https://llvm.googlesource.com/llvm-project/+/34f70007348d2d1a0e59fc0996e90a0b6fba1933"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "d3846eca2061e6e9a8d654551153f7362c27b59a"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@d3846eca2061e6e9a8d654551153f7362c27b59a",
"title": "d3846eca2061e6e9a8d654551153f7362c27b59a",
"url": "https://llvm.googlesource.com/llvm-project/+/d3846eca2061e6e9a8d654551153f7362c27b59a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "1de228fa9bf07c118294b53e3da37ab2b5ff1fd4"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@1de228fa9bf07c118294b53e3da37ab2b5ff1fd4",
"title": "1de228fa9bf07c118294b53e3da37ab2b5ff1fd4",
"url": "https://llvm.googlesource.com/llvm-project/+/1de228fa9bf07c118294b53e3da37ab2b5ff1fd4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "a1328c077c9bae5c3b3cb952fe817c4881569cbc"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@a1328c077c9bae5c3b3cb952fe817c4881569cbc",
"title": "a1328c077c9bae5c3b3cb952fe817c4881569cbc",
"url": "https://llvm.googlesource.com/llvm-project/+/a1328c077c9bae5c3b3cb952fe817c4881569cbc"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "6a7687c455125f6597a9719227a0efcb7f71e572"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@6a7687c455125f6597a9719227a0efcb7f71e572",
"title": "6a7687c455125f6597a9719227a0efcb7f71e572",
"url": "https://llvm.googlesource.com/llvm-project/+/6a7687c455125f6597a9719227a0efcb7f71e572"
}
]
}
},
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "6a7687c455125f6597a9719227a0efcb7f71e572",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8893037593549410144"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/clang-prod-unbounded-trigger-40be1bb5"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[04:44:35.941] Scheduled build:
{
"id": "8727607531602069249",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "clang-prod-unbounded-trigger"
},
"createdBy": "project:fuchsia",
"createTime": "2024-12-25T04:44:35.645818214Z",
"updateTime": "2024-12-25T04:44:35.645818214Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "6a7687c455125f6597a9719227a0efcb7f71e572",
"ref": "refs/heads/main"
}
}
}
[04:44:35.941] Task URL: https://cr-buildbucket.appspot.com/build/8727607531602069249
[04:44:35.941] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:2:0) after 4m9s
[04:46:34.531] Received PubSub notification, asking Buildbucket for the build status
[04:46:34.558] Build status: STARTED
[04:46:42.426] Received PubSub notification, asking Buildbucket for the build status
[04:46:42.449] Build status: STARTED
[04:48:45.153] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:2:0)
[04:48:45.182] Build status: STARTED
[04:48:45.182] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:5:0) after 2m21s
[04:51:06.245] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:5:0)
[04:51:06.272] Build status: STARTED
[04:51:06.272] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:6:0) after 4m37s
[04:55:43.287] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:6:0)
[04:55:43.352] Build status: STARTED
[04:55:43.352] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:7:0) after 3m29s
[04:59:12.380] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:7:0)
[04:59:12.409] Build status: STARTED
[04:59:12.409] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:8:0) after 7m50s
[05:07:02.438] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:8:0)
[05:07:02.459] Build status: STARTED
[05:07:02.459] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:9:0) after 2m13s
[05:09:15.496] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:9:0)
[05:09:15.516] Build status: STARTED
[05:09:15.517] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:10:0) after 3m23s
[05:12:38.533] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:10:0)
[05:12:38.582] Build status: STARTED
[05:12:38.582] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:11:0) after 9m10s
[05:21:48.606] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:11:0)
[05:21:48.632] Build status: STARTED
[05:21:48.632] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:12:0) after 3m46s
[05:25:34.802] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:12:0)
[05:25:34.828] Build status: STARTED
[05:25:34.828] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:13:0) after 7m51s
[05:33:25.980] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:13:0)
[05:33:26.020] Build status: STARTED
[05:33:26.020] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:14:0) after 8m31s
[05:41:57.131] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:14:0)
[05:41:57.157] Build status: STARTED
[05:41:57.157] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:15:0) after 6m27s
[05:48:24.174] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:15:0)
[05:48:24.194] Build status: STARTED
[05:48:24.194] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:16:0) after 8m58s
[05:57:22.214] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:16:0)
[05:57:22.255] Build status: STARTED
[05:57:22.255] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:17:0) after 2m28s
[05:59:50.277] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:17:0)
[05:59:50.305] Build status: STARTED
[05:59:50.305] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:18:0) after 2m15s
[06:02:05.415] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:18:0)
[06:02:05.436] Build status: STARTED
[06:02:05.436] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:19:0) after 8m14s
[06:10:19.489] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:19:0)
[06:10:19.559] Build status: STARTED
[06:10:19.559] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:20:0) after 3m20s
[06:13:39.653] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:20:0)
[06:13:39.681] Build status: STARTED
[06:13:39.681] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:21:0) after 7m11s
[06:20:50.732] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:21:0)
[06:20:50.818] Build status: STARTED
[06:20:50.818] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:22:0) after 9m11s
[06:30:01.866] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:22:0)
[06:30:01.888] Build status: STARTED
[06:30:01.888] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:23:0) after 4m24s
[06:34:26.007] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:23:0)
[06:34:26.045] Build status: STARTED
[06:34:26.045] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:24:0) after 6m58s
[06:41:24.200] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:24:0)
[06:41:24.229] Build status: STARTED
[06:41:24.229] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:25:0) after 2m11s
[06:43:35.408] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:25:0)
[06:43:35.436] Build status: STARTED
[06:43:35.436] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:26:0) after 6m18s
[06:49:53.458] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:26:0)
[06:49:53.545] Build status: STARTED
[06:49:53.545] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:27:0) after 9m22s
[06:59:15.569] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:27:0)
[06:59:15.588] Build status: STARTED
[06:59:15.588] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:28:0) after 2m54s
[07:02:09.665] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:28:0)
[07:02:09.695] Build status: STARTED
[07:02:09.695] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:29:0) after 2m27s
[07:04:36.686] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:29:0)
[07:04:36.702] Build status: STARTED
[07:04:36.702] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:30:0) after 9m59s
[07:14:35.680] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:30:0)
[07:14:35.709] Build status: STARTED
[07:14:35.709] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:31:0) after 6m4s
[07:20:39.734] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:31:0)
[07:20:39.810] Build status: STARTED
[07:20:39.810] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:32:0) after 8m28s
[07:29:07.838] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:32:0)
[07:29:07.855] Build status: STARTED
[07:29:07.855] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:33:0) after 5m42s
[07:34:49.977] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:33:0)
[07:34:49.999] Build status: STARTED
[07:34:49.999] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:34:0) after 4m21s
[07:39:11.095] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:34:0)
[07:39:11.116] Build status: STARTED
[07:39:11.116] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:35:0) after 5m41s
[07:44:52.321] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:35:0)
[07:44:52.338] Build status: STARTED
[07:44:52.338] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:36:0) after 2m52s
[07:47:44.360] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:36:0)
[07:47:44.385] Build status: STARTED
[07:47:44.385] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:37:0) after 7m21s
[07:55:05.408] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:37:0)
[07:55:05.424] Build status: STARTED
[07:55:05.424] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:38:0) after 2m45s
[07:57:50.464] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:38:0)
[07:57:50.490] Build status: STARTED
[07:57:50.490] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:39:0) after 9m45s
[08:07:35.514] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:39:0)
[08:07:35.538] Build status: STARTED
[08:07:35.538] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:40:0) after 9m39s
[08:17:14.663] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:40:0)
[08:17:14.684] Build status: STARTED
[08:17:14.684] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:41:0) after 4m29s
[08:21:43.709] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:41:0)
[08:21:43.727] Build status: STARTED
[08:21:43.727] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:42:0) after 5m34s
[08:27:17.745] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:42:0)
[08:27:17.765] Build status: STARTED
[08:27:17.765] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8893037593549410144:43:0) after 3m42s
[08:27:38.341] Received PubSub notification, asking Buildbucket for the build status
[08:27:38.364] Build:
{
"id": "8727607531602069249",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "clang-prod-unbounded-trigger"
},
"createdBy": "project:fuchsia",
"createTime": "2024-12-25T04:44:35.645818214Z",
"startTime": "2024-12-25T04:44:40.792962249Z",
"endTime": "2024-12-25T08:27:37.965644783Z",
"updateTime": "2024-12-25T08:27:37.965644783Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "6a7687c455125f6597a9719227a0efcb7f71e572",
"ref": "refs/heads/main"
}
}
}
[08:27:38.364] Invocation finished in 3h43m3.960227832s with status SUCCEEDED