[19:29:09.885] New invocation is queued and will start shortly
[19:29:10.959] Starting the invocation (attempt 1)
[19:29:10.987] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a2724f28b8d6f3aa17b9eeb198cd9b0af3c56888"
[19:29:10.987] Popped gitiles commit info from properties and tags
[19:29:10.987] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:29:10.989] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:29:10.989] Buildbucket request:
{
"requestId": "8916265360244105664",
"builder": {
"project": "openscreen",
"bucket": "ci",
"builder": "chromium_linux_x64"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8916265360244105664",
"job": "openscreen/chromium_linux_x64",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "3ae1fbc6036d30637527c740a303faaeef6248ae"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/heads/main@3ae1fbc6036d30637527c740a303faaeef6248ae",
"title": "3ae1fbc6036d30637527c740a303faaeef6248ae",
"url": "https://chromium.googlesource.com/chromium/src/+/3ae1fbc6036d30637527c740a303faaeef6248ae"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "8187164d6e13f261650595a4faa3a256f9c3f26c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/heads/main@8187164d6e13f261650595a4faa3a256f9c3f26c",
"title": "8187164d6e13f261650595a4faa3a256f9c3f26c",
"url": "https://chromium.googlesource.com/chromium/src/+/8187164d6e13f261650595a4faa3a256f9c3f26c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "1903f25963be9df9cb3c9cfbb9d82766b1691dd4"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/heads/main@1903f25963be9df9cb3c9cfbb9d82766b1691dd4",
"title": "1903f25963be9df9cb3c9cfbb9d82766b1691dd4",
"url": "https://chromium.googlesource.com/chromium/src/+/1903f25963be9df9cb3c9cfbb9d82766b1691dd4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "94df12931a7e7d28f27c8bf06a7b29d972ae42dc"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/heads/main@94df12931a7e7d28f27c8bf06a7b29d972ae42dc",
"title": "94df12931a7e7d28f27c8bf06a7b29d972ae42dc",
"url": "https://chromium.googlesource.com/chromium/src/+/94df12931a7e7d28f27c8bf06a7b29d972ae42dc"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "f5774bf2e88190cf514f58838e9a3f20736991cb"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/heads/main@f5774bf2e88190cf514f58838e9a3f20736991cb",
"title": "f5774bf2e88190cf514f58838e9a3f20736991cb",
"url": "https://chromium.googlesource.com/chromium/src/+/f5774bf2e88190cf514f58838e9a3f20736991cb"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "a2724f28b8d6f3aa17b9eeb198cd9b0af3c56888"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/heads/main@a2724f28b8d6f3aa17b9eeb198cd9b0af3c56888",
"title": "a2724f28b8d6f3aa17b9eeb198cd9b0af3c56888",
"url": "https://chromium.googlesource.com/chromium/src/+/a2724f28b8d6f3aa17b9eeb198cd9b0af3c56888"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "a2724f28b8d6f3aa17b9eeb198cd9b0af3c56888",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8916265360244105664"
},
{
"key": "scheduler_job_id",
"value": "openscreen/chromium_linux_x64"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[19:29:11.449] Scheduled build:
{
"id": "8750835298362405601",
"builder": {
"project": "openscreen",
"bucket": "ci",
"builder": "chromium_linux_x64"
},
"createdBy": "project:openscreen",
"createTime": "2024-04-12T19:29:11.058886360Z",
"updateTime": "2024-04-12T19:29:11.058886360Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "a2724f28b8d6f3aa17b9eeb198cd9b0af3c56888",
"ref": "refs/heads/main"
}
}
}
[19:29:11.449] Task URL: https://cr-buildbucket.appspot.com/build/8750835298362405601
[19:29:11.449] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8916265360244105664:2:0) after 1m18s
[19:29:12.444] Received PubSub notification, asking Buildbucket for the build status
[19:29:12.466] Build status: SCHEDULED
[19:29:17.176] Received PubSub notification, asking Buildbucket for the build status
[19:29:17.238] Build status: STARTED
[19:30:29.458] Handling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8916265360244105664:2:0)
[19:30:29.494] Build status: STARTED
[19:30:29.495] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8916265360244105664:5:0) after 2m15s
[19:32:44.622] Handling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8916265360244105664:5:0)
[19:32:44.657] Build status: STARTED
[19:32:44.657] Scheduling timer "check-buildbucket-build-status" (openscreen/chromium_linux_x64:8916265360244105664:6:0) after 9m14s
[19:32:46.725] Received PubSub notification, asking Buildbucket for the build status
[19:32:46.804] Build:
{
"id": "8750835298362405601",
"builder": {
"project": "openscreen",
"bucket": "ci",
"builder": "chromium_linux_x64"
},
"createdBy": "project:openscreen",
"createTime": "2024-04-12T19:29:11.058886360Z",
"startTime": "2024-04-12T19:29:16.980684411Z",
"endTime": "2024-04-12T19:32:46.354838670Z",
"updateTime": "2024-04-12T19:32:46.354838670Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "a2724f28b8d6f3aa17b9eeb198cd9b0af3c56888",
"ref": "refs/heads/main"
}
}
}
[19:32:46.804] Invocation finished in 3m36.92435073s with status SUCCEEDED