[01:49:51.577] New invocation is queued and will start shortly
[01:49:52.963] Starting the invocation (attempt 1)
[01:49:53.002] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5a04a3e700ebd483f9f126e066215bc050cabb60"
[01:49:53.002] Popped gitiles commit info from properties and tags
[01:49:53.002] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:49:53.002] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:49:53.002] Buildbucket request:
{
"requestId": "9005026436075240000",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "mac-official"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "5a04a3e700ebd483f9f126e066215bc050cabb60",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "9005026436075240000"
},
{
"key": "scheduler_job_id",
"value": "chromium/mac-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[01:49:53.517] Scheduled build:
{
"id": "8839596373850810737",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "mac-official"
},
"number": 2121,
"createdBy": "project:chromium",
"createTime": "2021-08-07T01:49:53.068084397Z",
"updateTime": "2021-08-07T01:49:53.343020021Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "5a04a3e700ebd483f9f126e066215bc050cabb60",
"ref": "refs/heads/main"
}
}
}
[01:49:53.517] Task URL: https://cr-buildbucket.appspot.com/build/8839596373850810737
[01:49:53.517] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:2:0) after 7m27s
[01:50:01.891] Received PubSub notification, asking Buildbucket for the build status
[01:50:01.936] Build status: STARTED
[01:57:20.537] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:2:0)
[01:57:20.624] Build status: STARTED
[01:57:20.624] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:4:0) after 5m46s
[02:03:06.693] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:4:0)
[02:03:06.736] Build status: STARTED
[02:03:06.736] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:5:0) after 7m24s
[02:10:30.858] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:5:0)
[02:10:30.887] Build status: STARTED
[02:10:30.887] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:6:0) after 6m34s
[02:17:04.909] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:6:0)
[02:17:04.937] Build status: STARTED
[02:17:04.937] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:7:0) after 8m9s
[02:25:13.964] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:7:0)
[02:25:13.989] Build status: STARTED
[02:25:13.989] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:8:0) after 8m44s
[02:33:58.016] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:8:0)
[02:33:58.050] Build status: STARTED
[02:33:58.050] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:9:0) after 1m44s
[02:35:42.134] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:9:0)
[02:35:42.163] Build status: STARTED
[02:35:42.163] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:10:0) after 6m9s
[02:41:51.199] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:10:0)
[02:41:51.233] Build status: STARTED
[02:41:51.233] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:11:0) after 3m7s
[02:44:58.258] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:11:0)
[02:44:58.300] Build status: STARTED
[02:44:58.300] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:12:0) after 1m37s
[02:46:35.322] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:12:0)
[02:46:35.357] Build status: STARTED
[02:46:35.357] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:13:0) after 5m35s
[02:52:10.379] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:13:0)
[02:52:10.411] Build status: STARTED
[02:52:10.411] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:14:0) after 1m15s
[02:53:25.430] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:14:0)
[02:53:25.456] Build status: STARTED
[02:53:25.456] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:15:0) after 3m1s
[02:56:26.778] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:15:0)
[02:56:26.811] Build status: STARTED
[02:56:26.811] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:16:0) after 1m6s
[02:57:32.927] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:16:0)
[02:57:32.963] Build status: STARTED
[02:57:32.963] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:17:0) after 7m25s
[03:04:58.265] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:17:0)
[03:04:58.374] Build status: STARTED
[03:04:58.374] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:18:0) after 6m12s
[03:11:10.467] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:18:0)
[03:11:10.517] Build status: STARTED
[03:11:10.517] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:19:0) after 5m52s
[03:17:02.621] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:19:0)
[03:17:02.647] Build status: STARTED
[03:17:02.647] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:20:0) after 2m44s
[03:19:46.671] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:20:0)
[03:19:46.788] Build status: STARTED
[03:19:46.788] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:21:0) after 3m1s
[03:22:47.794] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:21:0)
[03:22:47.822] Build status: STARTED
[03:22:47.822] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:22:0) after 2m47s
[03:25:34.946] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:22:0)
[03:25:34.978] Build status: STARTED
[03:25:34.978] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:23:0) after 9m39s
[03:35:14.000] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:23:0)
[03:35:14.113] Build status: STARTED
[03:35:14.113] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:24:0) after 8m42s
[03:43:56.241] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:24:0)
[03:43:56.275] Build status: STARTED
[03:43:56.275] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:25:0) after 9m29s
[03:53:25.401] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:25:0)
[03:53:25.470] Build status: STARTED
[03:53:25.470] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:26:0) after 1m23s
[03:54:48.595] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:26:0)
[03:54:48.629] Build status: STARTED
[03:54:48.629] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:27:0) after 5m19s
[04:00:07.746] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:27:0)
[04:00:07.802] Build status: STARTED
[04:00:07.802] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:28:0) after 2m47s
[04:02:54.873] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:28:0)
[04:02:54.903] Build status: STARTED
[04:02:54.903] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:29:0) after 1m58s
[04:04:53.013] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:29:0)
[04:04:53.040] Build status: STARTED
[04:04:53.041] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:30:0) after 4m37s
[04:09:30.116] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:30:0)
[04:09:30.151] Build status: STARTED
[04:09:30.151] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:31:0) after 5m26s
[04:14:56.172] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:31:0)
[04:14:56.198] Build status: STARTED
[04:14:56.198] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:32:0) after 6m17s
[04:21:13.320] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:32:0)
[04:21:13.358] Build status: STARTED
[04:21:13.358] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:33:0) after 1m29s
[04:22:42.561] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:33:0)
[04:22:42.593] Build status: STARTED
[04:22:42.593] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:34:0) after 7m27s
[04:30:09.618] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:34:0)
[04:30:09.650] Build status: STARTED
[04:30:09.650] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:35:0) after 7m14s
[04:37:23.682] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:35:0)
[04:37:23.776] Build status: STARTED
[04:37:23.776] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:36:0) after 5m35s
[04:42:58.898] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:36:0)
[04:42:58.945] Build status: STARTED
[04:42:58.945] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:37:0) after 1m7s
[04:44:05.981] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:37:0)
[04:44:06.014] Build status: STARTED
[04:44:06.014] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:38:0) after 7m10s
[04:51:16.007] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:38:0)
[04:51:16.037] Build status: STARTED
[04:51:16.037] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:39:0) after 1m1s
[04:52:17.060] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:39:0)
[04:52:17.096] Build status: STARTED
[04:52:17.096] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:40:0) after 4m43s
[04:57:00.584] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:40:0)
[04:57:00.623] Build status: STARTED
[04:57:00.623] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:41:0) after 2m8s
[04:59:08.756] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:41:0)
[04:59:08.788] Build status: STARTED
[04:59:08.788] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:42:0) after 3m48s
[05:02:56.920] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:42:0)
[05:02:56.958] Build status: STARTED
[05:02:56.958] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:43:0) after 6m20s
[05:09:16.984] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:43:0)
[05:09:17.016] Build status: STARTED
[05:09:17.016] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:44:0) after 4m55s
[05:14:12.137] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:44:0)
[05:14:12.202] Build status: STARTED
[05:14:12.202] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:45:0) after 5m50s
[05:20:02.224] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:45:0)
[05:20:02.254] Build status: STARTED
[05:20:02.254] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:46:0) after 8m35s
[05:28:37.280] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:46:0)
[05:28:37.314] Build status: STARTED
[05:28:37.314] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:47:0) after 5m24s
[05:34:01.337] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:47:0)
[05:34:01.369] Build status: STARTED
[05:34:01.369] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:48:0) after 3m56s
[05:37:57.400] Handling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:48:0)
[05:37:57.428] Build status: STARTED
[05:37:57.428] Scheduling timer "check-buildbucket-build-status" (chromium/mac-official:9005026436075240000:49:0) after 4m40s
[05:39:21.321] Received PubSub notification, asking Buildbucket for the build status
[05:39:21.345] Build:
{
"id": "8839596373850810737",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "mac-official"
},
"number": 2121,
"createdBy": "project:chromium",
"createTime": "2021-08-07T01:49:53.068084397Z",
"startTime": "2021-08-07T01:50:01.160672Z",
"endTime": "2021-08-07T05:39:16.506940272Z",
"updateTime": "2021-08-07T05:39:16.507276471Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "5a04a3e700ebd483f9f126e066215bc050cabb60",
"ref": "refs/heads/main"
}
}
}
[05:39:21.345] Invocation finished in 3h49m29.782668792s with status SUCCEEDED