[17:36:02.715] New invocation is queued and will start shortly
[17:36:03.797] Starting the invocation (attempt 1)
[17:36:03.828] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c27758967bac7aa41db431f5166090153a6fc2ad"
[17:36:03.828] Popped gitiles commit info from properties and tags
[17:36:03.828] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:36:03.828] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:36:03.829] Buildbucket request:
{
"requestId": "8898153083830953280",
"builder": {
"project": "chromium-m131",
"bucket": "ci",
"builder": "win-arm64-dbg"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8898153083830953280",
"job": "chromium-m131/win-arm64-dbg",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "3ce82347ed761d70384ec79023f00a8105ca3864"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@3ce82347ed761d70384ec79023f00a8105ca3864",
"title": "3ce82347ed761d70384ec79023f00a8105ca3864",
"url": "https://chromium.googlesource.com/chromium/src/+/3ce82347ed761d70384ec79023f00a8105ca3864"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "5ec70fc7cb7050b05c7b225183b4cd40e610366e"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@5ec70fc7cb7050b05c7b225183b4cd40e610366e",
"title": "5ec70fc7cb7050b05c7b225183b4cd40e610366e",
"url": "https://chromium.googlesource.com/chromium/src/+/5ec70fc7cb7050b05c7b225183b4cd40e610366e"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "ea0616a5a52b98001def48c5eb55a3b5c7d1ce80"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@ea0616a5a52b98001def48c5eb55a3b5c7d1ce80",
"title": "ea0616a5a52b98001def48c5eb55a3b5c7d1ce80",
"url": "https://chromium.googlesource.com/chromium/src/+/ea0616a5a52b98001def48c5eb55a3b5c7d1ce80"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "8b7bc815503c497f140f266231455340468c43ae"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@8b7bc815503c497f140f266231455340468c43ae",
"title": "8b7bc815503c497f140f266231455340468c43ae",
"url": "https://chromium.googlesource.com/chromium/src/+/8b7bc815503c497f140f266231455340468c43ae"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "6c4a6e4157aba226af4aba941af18b8808beafd7"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@6c4a6e4157aba226af4aba941af18b8808beafd7",
"title": "6c4a6e4157aba226af4aba941af18b8808beafd7",
"url": "https://chromium.googlesource.com/chromium/src/+/6c4a6e4157aba226af4aba941af18b8808beafd7"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "bea4e33a3f268a171d5df9ec028e381c787f7bfc"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@bea4e33a3f268a171d5df9ec028e381c787f7bfc",
"title": "bea4e33a3f268a171d5df9ec028e381c787f7bfc",
"url": "https://chromium.googlesource.com/chromium/src/+/bea4e33a3f268a171d5df9ec028e381c787f7bfc"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "94bb8f475af31a1c827828c5b23aa28516a2e851"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@94bb8f475af31a1c827828c5b23aa28516a2e851",
"title": "94bb8f475af31a1c827828c5b23aa28516a2e851",
"url": "https://chromium.googlesource.com/chromium/src/+/94bb8f475af31a1c827828c5b23aa28516a2e851"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "0ad995b4f4f3d338d8ee9aabd386865035e68298"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@0ad995b4f4f3d338d8ee9aabd386865035e68298",
"title": "0ad995b4f4f3d338d8ee9aabd386865035e68298",
"url": "https://chromium.googlesource.com/chromium/src/+/0ad995b4f4f3d338d8ee9aabd386865035e68298"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "59d60987336cdb1668d8b990d371974ab9b1adc7"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@59d60987336cdb1668d8b990d371974ab9b1adc7",
"title": "59d60987336cdb1668d8b990d371974ab9b1adc7",
"url": "https://chromium.googlesource.com/chromium/src/+/59d60987336cdb1668d8b990d371974ab9b1adc7"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "afc3768e0a21c1725c9f2748147a238e6764ebea"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@afc3768e0a21c1725c9f2748147a238e6764ebea",
"title": "afc3768e0a21c1725c9f2748147a238e6764ebea",
"url": "https://chromium.googlesource.com/chromium/src/+/afc3768e0a21c1725c9f2748147a238e6764ebea"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "0a94fcf090f42c90225921228a8a2b5bfab0184f"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@0a94fcf090f42c90225921228a8a2b5bfab0184f",
"title": "0a94fcf090f42c90225921228a8a2b5bfab0184f",
"url": "https://chromium.googlesource.com/chromium/src/+/0a94fcf090f42c90225921228a8a2b5bfab0184f"
},
{
"gitiles": {
"ref": "refs/branch-heads/6778",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "c27758967bac7aa41db431f5166090153a6fc2ad"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6778@c27758967bac7aa41db431f5166090153a6fc2ad",
"title": "c27758967bac7aa41db431f5166090153a6fc2ad",
"url": "https://chromium.googlesource.com/chromium/src/+/c27758967bac7aa41db431f5166090153a6fc2ad"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "c27758967bac7aa41db431f5166090153a6fc2ad",
"ref": "refs/branch-heads/6778"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8898153083830953280"
},
{
"key": "scheduler_job_id",
"value": "chromium-m131/win-arm64-dbg"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[17:36:04.534] Scheduled build:
{
"id": "8732723021933049617",
"builder": {
"project": "chromium-m131",
"bucket": "ci",
"builder": "win-arm64-dbg"
},
"number": 126,
"createdBy": "project:chromium-m131",
"createTime": "2024-10-29T17:36:03.900077212Z",
"updateTime": "2024-10-29T17:36:03.900077212Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "c27758967bac7aa41db431f5166090153a6fc2ad",
"ref": "refs/branch-heads/6778"
}
},
"canary": true
}
[17:36:04.534] Task URL: https://cr-buildbucket.appspot.com/build/8732723021933049617
[17:36:04.534] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8898153083830953280:2:0) after 9m30s
[17:36:05.527] Received PubSub notification, asking Buildbucket for the build status
[17:36:05.583] Build status: SCHEDULED
[17:45:34.565] Handling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8898153083830953280:2:0)
[17:45:34.583] Build status: SCHEDULED
[17:45:34.583] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8898153083830953280:4:0) after 1m39s
[17:47:13.603] Handling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8898153083830953280:4:0)
[17:47:15.658] Build status: SCHEDULED
[17:47:15.658] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8898153083830953280:5:0) after 2m2s
[17:49:17.679] Handling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8898153083830953280:5:0)
[17:49:17.705] Build status: SCHEDULED
[17:49:17.705] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8898153083830953280:6:0) after 3m37s
[17:52:54.793] Handling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8898153083830953280:6:0)
[17:52:54.821] Build status: SCHEDULED
[17:52:54.821] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8898153083830953280:7:0) after 9m26s
[17:54:16.159] Received PubSub notification, asking Buildbucket for the build status
[17:54:16.190] Build status: STARTED
[18:02:20.893] Handling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8898153083830953280:7:0)
[18:02:20.908] Build status: STARTED
[18:02:20.908] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8898153083830953280:9:0) after 3m45s
[18:06:05.935] Handling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8898153083830953280:9:0)
[18:06:05.970] Build status: STARTED
[18:06:05.970] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8898153083830953280:10:0) after 7m1s
[18:13:06.987] Handling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8898153083830953280:10:0)
[18:13:07.026] Build status: STARTED
[18:13:07.026] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8898153083830953280:11:0) after 9m3s
[18:22:10.098] Handling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8898153083830953280:11:0)
[18:22:10.116] Build status: STARTED
[18:22:10.116] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8898153083830953280:12:0) after 3m34s
[18:25:44.150] Handling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8898153083830953280:12:0)
[18:25:44.241] Build status: STARTED
[18:25:44.241] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8898153083830953280:13:0) after 9m21s
[18:35:05.266] Handling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8898153083830953280:13:0)
[18:35:05.302] Build status: STARTED
[18:35:05.302] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8898153083830953280:14:0) after 4m11s
[18:39:16.327] Handling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8898153083830953280:14:0)
[18:39:16.370] Build status: STARTED
[18:39:16.370] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8898153083830953280:15:0) after 8m42s
[18:47:58.395] Handling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8898153083830953280:15:0)
[18:47:58.411] Build status: STARTED
[18:47:58.411] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8898153083830953280:16:0) after 1m5s
[18:49:03.482] Handling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8898153083830953280:16:0)
[18:49:03.512] Build status: STARTED
[18:49:03.512] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8898153083830953280:17:0) after 8m55s
[18:57:58.721] Handling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8898153083830953280:17:0)
[18:57:58.845] Build status: STARTED
[18:57:58.845] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8898153083830953280:18:0) after 3m9s
[19:01:07.826] Handling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8898153083830953280:18:0)
[19:01:07.846] Build status: STARTED
[19:01:07.846] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8898153083830953280:19:0) after 4m33s
[19:05:40.864] Handling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8898153083830953280:19:0)
[19:05:41.122] Build status: STARTED
[19:05:41.122] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8898153083830953280:20:0) after 1m12s
[19:06:53.140] Handling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8898153083830953280:20:0)
[19:06:53.163] Build status: STARTED
[19:06:53.163] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8898153083830953280:21:0) after 2m0s
[19:08:53.220] Handling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8898153083830953280:21:0)
[19:08:53.248] Build status: STARTED
[19:08:53.248] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8898153083830953280:22:0) after 7m14s
[19:16:07.367] Handling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8898153083830953280:22:0)
[19:16:07.389] Build status: STARTED
[19:16:07.389] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8898153083830953280:23:0) after 5m11s
[19:21:18.415] Handling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8898153083830953280:23:0)
[19:21:18.434] Build status: STARTED
[19:21:18.434] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8898153083830953280:24:0) after 3m28s
[19:24:46.626] Handling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8898153083830953280:24:0)
[19:24:46.646] Build status: STARTED
[19:24:46.646] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8898153083830953280:25:0) after 9m2s
[19:33:48.673] Handling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8898153083830953280:25:0)
[19:33:48.755] Build status: STARTED
[19:33:48.755] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8898153083830953280:26:0) after 5m58s
[19:39:46.766] Handling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8898153083830953280:26:0)
[19:39:46.852] Build status: STARTED
[19:39:46.852] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8898153083830953280:27:0) after 2m47s
[19:42:33.872] Handling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8898153083830953280:27:0)
[19:42:33.895] Build status: STARTED
[19:42:33.895] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8898153083830953280:28:0) after 5m47s
[19:48:20.945] Handling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8898153083830953280:28:0)
[19:48:20.976] Build status: STARTED
[19:48:20.976] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8898153083830953280:29:0) after 1m16s
[19:49:36.951] Handling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8898153083830953280:29:0)
[19:49:36.980] Build status: STARTED
[19:49:36.980] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8898153083830953280:30:0) after 9m8s
[19:58:45.006] Handling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8898153083830953280:30:0)
[19:58:45.028] Build status: STARTED
[19:58:45.028] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8898153083830953280:31:0) after 1m14s
[19:59:59.077] Handling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8898153083830953280:31:0)
[19:59:59.099] Build status: STARTED
[19:59:59.099] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8898153083830953280:32:0) after 2m19s
[20:02:18.136] Handling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8898153083830953280:32:0)
[20:02:18.165] Build status: STARTED
[20:02:18.165] Scheduling timer "check-buildbucket-build-status" (chromium-m131/win-arm64-dbg:8898153083830953280:33:0) after 5m15s
[20:05:59.915] Received PubSub notification, asking Buildbucket for the build status
[20:05:59.947] Build:
{
"id": "8732723021933049617",
"builder": {
"project": "chromium-m131",
"bucket": "ci",
"builder": "win-arm64-dbg"
},
"number": 126,
"createdBy": "project:chromium-m131",
"createTime": "2024-10-29T17:36:03.900077212Z",
"startTime": "2024-10-29T17:54:15.722638843Z",
"endTime": "2024-10-29T20:05:59.090379899Z",
"updateTime": "2024-10-29T20:05:59.090379899Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "c27758967bac7aa41db431f5166090153a6fc2ad",
"ref": "refs/branch-heads/6778"
}
},
"canary": true
}
[20:05:59.947] Invocation finished in 2h29m57.240373349s with status SUCCEEDED