{"level":"warn","ts":"2026-03-17T21:55:43.916947Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-17T21:55:43.917036Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.67:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.67:2380","--initial-cluster=instance=https://199.19.213.67:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.67:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.67:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-03-17T21:55:43.91711Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-17T21:55:43.917129Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.67:2380"]} {"level":"info","ts":"2026-03-17T21:55:43.917148Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-17T21:55:43.917773Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.67:2379"]} {"level":"info","ts":"2026-03-17T21:55:43.917854Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.67:2380"],"listen-peer-urls":["https://199.19.213.67:2380"],"advertise-client-urls":["https://199.19.213.67:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.67:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.67:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-03-17T21:55:43.92139Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.275278ms"} {"level":"info","ts":"2026-03-17T21:55:43.927198Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fba6d6d8cd5f215c","cluster-id":"f3c660457a1c2c72"} {"level":"info","ts":"2026-03-17T21:55:43.927308Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fba6d6d8cd5f215c switched to configuration voters=()"} {"level":"info","ts":"2026-03-17T21:55:43.927333Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fba6d6d8cd5f215c became follower at term 0"} {"level":"info","ts":"2026-03-17T21:55:43.927358Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fba6d6d8cd5f215c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-17T21:55:43.927371Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fba6d6d8cd5f215c became follower at term 1"} {"level":"info","ts":"2026-03-17T21:55:43.92742Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fba6d6d8cd5f215c switched to configuration voters=(18133417176300724572)"} {"level":"warn","ts":"2026-03-17T21:55:43.935737Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-17T21:55:43.938561Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-17T21:55:43.940004Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-03-17T21:55:43.945372Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fba6d6d8cd5f215c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-17T21:55:43.945614Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-17T21:55:43.945733Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-17T21:55:43.945747Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-17T21:55:43.946091Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fba6d6d8cd5f215c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-17T21:55:43.948314Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fba6d6d8cd5f215c switched to configuration voters=(18133417176300724572)"} {"level":"info","ts":"2026-03-17T21:55:43.948548Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f3c660457a1c2c72","local-member-id":"fba6d6d8cd5f215c","added-peer-id":"fba6d6d8cd5f215c","added-peer-peer-urls":["https://199.19.213.67:2380"]} {"level":"info","ts":"2026-03-17T21:55:43.951867Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-17T21:55:43.951986Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.67:2380"} {"level":"info","ts":"2026-03-17T21:55:43.952062Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.67:2380"} {"level":"info","ts":"2026-03-17T21:55:43.952171Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fba6d6d8cd5f215c","initial-advertise-peer-urls":["https://199.19.213.67:2380"],"listen-peer-urls":["https://199.19.213.67:2380"],"advertise-client-urls":["https://199.19.213.67:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.67:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-17T21:55:43.952231Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-17T21:55:44.127903Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fba6d6d8cd5f215c is starting a new election at term 1"} {"level":"info","ts":"2026-03-17T21:55:44.127989Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fba6d6d8cd5f215c became pre-candidate at term 1"} {"level":"info","ts":"2026-03-17T21:55:44.128017Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fba6d6d8cd5f215c received MsgPreVoteResp from fba6d6d8cd5f215c at term 1"} {"level":"info","ts":"2026-03-17T21:55:44.128045Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fba6d6d8cd5f215c became candidate at term 2"} {"level":"info","ts":"2026-03-17T21:55:44.128063Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fba6d6d8cd5f215c received MsgVoteResp from fba6d6d8cd5f215c at term 2"} {"level":"info","ts":"2026-03-17T21:55:44.128087Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fba6d6d8cd5f215c became leader at term 2"} {"level":"info","ts":"2026-03-17T21:55:44.128141Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fba6d6d8cd5f215c elected leader fba6d6d8cd5f215c at term 2"} {"level":"info","ts":"2026-03-17T21:55:44.129323Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-17T21:55:44.130206Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-17T21:55:44.130206Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fba6d6d8cd5f215c","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.67:2379]}","request-path":"/0/members/fba6d6d8cd5f215c/attributes","cluster-id":"f3c660457a1c2c72","publish-timeout":"7s"} {"level":"info","ts":"2026-03-17T21:55:44.130281Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-17T21:55:44.130595Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-17T21:55:44.130677Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-17T21:55:44.130715Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f3c660457a1c2c72","local-member-id":"fba6d6d8cd5f215c","cluster-version":"3.5"} {"level":"info","ts":"2026-03-17T21:55:44.13082Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-17T21:55:44.130905Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-17T21:55:44.134807Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-17T21:55:44.144432Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.67:2379"} {"level":"info","ts":"2026-03-17T21:56:13.380381Z","caller":"traceutil/trace.go:171","msg":"trace[1087187393] transaction","detail":"{read_only:false; response_revision:434; number_of_response:1; }","duration":"176.28051ms","start":"2026-03-17T21:56:13.20406Z","end":"2026-03-17T21:56:13.380341Z","steps":["trace[1087187393] 'process raft request' (duration: 138.837234ms)","trace[1087187393] 'compare' (duration: 37.180071ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:56:22.246854Z","caller":"traceutil/trace.go:171","msg":"trace[275621148] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"117.404504ms","start":"2026-03-17T21:56:22.129425Z","end":"2026-03-17T21:56:22.24683Z","steps":["trace[275621148] 'process raft request' (duration: 117.287832ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:56:23.206363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.59635ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-03-17T21:56:42.915305Z","caller":"traceutil/trace.go:171","msg":"trace[558255588] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"175.781789ms","start":"2026-03-17T21:56:42.739499Z","end":"2026-03-17T21:56:42.915281Z","steps":["trace[558255588] 'process raft request' (duration: 175.690287ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:56:42.915333Z","caller":"traceutil/trace.go:171","msg":"trace[630966075] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"178.629089ms","start":"2026-03-17T21:56:42.736667Z","end":"2026-03-17T21:56:42.915296Z","steps":["trace[630966075] 'process raft request' (duration: 149.866923ms)","trace[630966075] 'compare' (duration: 28.512971ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T21:56:43.215567Z","caller":"traceutil/trace.go:171","msg":"trace[1652969673] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"291.692585ms","start":"2026-03-17T21:56:42.923827Z","end":"2026-03-17T21:56:43.215519Z","steps":["trace[1652969673] 'process raft request' (duration: 291.197815ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:57:33.96295Z","caller":"traceutil/trace.go:171","msg":"trace[1541711522] transaction","detail":"{read_only:false; response_revision:1552; number_of_response:1; }","duration":"101.093782ms","start":"2026-03-17T21:57:33.861812Z","end":"2026-03-17T21:57:33.962906Z","steps":["trace[1541711522] 'process raft request' (duration: 100.795356ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:57:42.247276Z","caller":"traceutil/trace.go:171","msg":"trace[991149413] transaction","detail":"{read_only:false; response_revision:1632; number_of_response:1; }","duration":"137.210759ms","start":"2026-03-17T21:57:42.110021Z","end":"2026-03-17T21:57:42.247231Z","steps":["trace[991149413] 'process raft request' (duration: 137.094406ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:57:43.07762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.094455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-03-17T21:57:43.07771Z","caller":"traceutil/trace.go:171","msg":"trace[806440197] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1634; }","duration":"181.222437ms","start":"2026-03-17T21:57:42.896463Z","end":"2026-03-17T21:57:43.077685Z","steps":["trace[806440197] 'range keys from in-memory index tree' (duration: 180.88383ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:57:43.077632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.980041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-17T21:57:43.077821Z","caller":"traceutil/trace.go:171","msg":"trace[1585259228] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1634; }","duration":"153.206425ms","start":"2026-03-17T21:57:42.924597Z","end":"2026-03-17T21:57:43.077803Z","steps":["trace[1585259228] 'range keys from in-memory index tree' (duration: 152.834138ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:57:43.077623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.074856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T21:57:43.077975Z","caller":"traceutil/trace.go:171","msg":"trace[179522373] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1634; }","duration":"112.495504ms","start":"2026-03-17T21:57:42.965459Z","end":"2026-03-17T21:57:43.077955Z","steps":["trace[179522373] 'range keys from in-memory index tree' (duration: 111.986454ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:58:25.462541Z","caller":"traceutil/trace.go:171","msg":"trace[1462473089] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"147.811866ms","start":"2026-03-17T21:58:25.314695Z","end":"2026-03-17T21:58:25.462507Z","steps":["trace[1462473089] 'process raft request' (duration: 147.614012ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T21:59:47.505066Z","caller":"traceutil/trace.go:171","msg":"trace[293730701] transaction","detail":"{read_only:false; response_revision:2458; number_of_response:1; }","duration":"111.8747ms","start":"2026-03-17T21:59:47.393158Z","end":"2026-03-17T21:59:47.505033Z","steps":["trace[293730701] 'process raft request' (duration: 111.685445ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T21:59:47.649375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.144932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.67\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-17T21:59:47.649475Z","caller":"traceutil/trace.go:171","msg":"trace[94268679] range","detail":"{range_begin:/registry/masterleases/199.19.213.67; range_end:; response_count:1; response_revision:2458; }","duration":"100.267184ms","start":"2026-03-17T21:59:47.549185Z","end":"2026-03-17T21:59:47.649452Z","steps":["trace[94268679] 'range keys from in-memory index tree' (duration: 99.842716ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:02:13.537267Z","caller":"traceutil/trace.go:171","msg":"trace[490057418] linearizableReadLoop","detail":"{readStateIndex:3488; appliedIndex:3487; }","duration":"112.638658ms","start":"2026-03-17T22:02:13.424594Z","end":"2026-03-17T22:02:13.537233Z","steps":["trace[490057418] 'read index received' (duration: 112.360802ms)","trace[490057418] 'applied index is now lower than readState.Index' (duration: 276.766µs)"],"step_count":2} {"level":"info","ts":"2026-03-17T22:02:13.537319Z","caller":"traceutil/trace.go:171","msg":"trace[1382953701] transaction","detail":"{read_only:false; response_revision:3355; number_of_response:1; }","duration":"149.208457ms","start":"2026-03-17T22:02:13.388074Z","end":"2026-03-17T22:02:13.537283Z","steps":["trace[1382953701] 'process raft request' (duration: 148.963652ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:02:13.537565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.374321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-17T22:02:13.537605Z","caller":"traceutil/trace.go:171","msg":"trace[771987363] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3355; }","duration":"110.501693ms","start":"2026-03-17T22:02:13.427091Z","end":"2026-03-17T22:02:13.537593Z","steps":["trace[771987363] 'agreement among raft nodes before linearized reading' (duration: 110.315019ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:02:13.537625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.142914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-03-17T22:02:13.537627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.014947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-17T22:02:13.53769Z","caller":"traceutil/trace.go:171","msg":"trace[599414579] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3355; }","duration":"109.245517ms","start":"2026-03-17T22:02:13.428426Z","end":"2026-03-17T22:02:13.537672Z","steps":["trace[599414579] 'agreement among raft nodes before linearized reading' (duration: 109.077194ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:02:13.537708Z","caller":"traceutil/trace.go:171","msg":"trace[392483243] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3355; }","duration":"113.103528ms","start":"2026-03-17T22:02:13.424581Z","end":"2026-03-17T22:02:13.537684Z","steps":["trace[392483243] 'agreement among raft nodes before linearized reading' (duration: 112.823002ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:03:45.272023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.832753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-17T22:03:45.272132Z","caller":"traceutil/trace.go:171","msg":"trace[1271111393] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4515; }","duration":"161.998916ms","start":"2026-03-17T22:03:45.110115Z","end":"2026-03-17T22:03:45.272114Z","steps":["trace[1271111393] 'range keys from in-memory index tree' (duration: 161.647648ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:03:45.272321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.737264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:719277"} {"level":"info","ts":"2026-03-17T22:03:45.272431Z","caller":"traceutil/trace.go:171","msg":"trace[377583411] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:4515; }","duration":"146.895477ms","start":"2026-03-17T22:03:45.12551Z","end":"2026-03-17T22:03:45.272405Z","steps":["trace[377583411] 'range keys from in-memory index tree' (duration: 146.202924ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:04:14.04235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.092402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/loki-gateway-cf54cb88c-wzmtl\" ","response":"range_response_count:1 size:4756"} {"level":"info","ts":"2026-03-17T22:04:14.042413Z","caller":"traceutil/trace.go:171","msg":"trace[568478589] range","detail":"{range_begin:/registry/pods/monitoring/loki-gateway-cf54cb88c-wzmtl; range_end:; response_count:1; response_revision:5007; }","duration":"121.189734ms","start":"2026-03-17T22:04:13.921211Z","end":"2026-03-17T22:04:14.042401Z","steps":["trace[568478589] 'range keys from in-memory index tree' (duration: 120.9781ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:04:14.179777Z","caller":"traceutil/trace.go:171","msg":"trace[1997868063] transaction","detail":"{read_only:false; response_revision:5008; number_of_response:1; }","duration":"128.606079ms","start":"2026-03-17T22:04:14.051142Z","end":"2026-03-17T22:04:14.179748Z","steps":["trace[1997868063] 'process raft request' (duration: 128.349014ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:04:14.74841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.967355ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T22:04:14.748509Z","caller":"traceutil/trace.go:171","msg":"trace[1636486394] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5009; }","duration":"357.087317ms","start":"2026-03-17T22:04:14.391407Z","end":"2026-03-17T22:04:14.748495Z","steps":["trace[1636486394] 'range keys from in-memory index tree' (duration: 356.949735ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:04:14.749455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.860164ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-17T22:04:14.74977Z","caller":"traceutil/trace.go:171","msg":"trace[441467093] transaction","detail":"{read_only:false; response_revision:5011; number_of_response:1; }","duration":"562.758488ms","start":"2026-03-17T22:04:14.18699Z","end":"2026-03-17T22:04:14.749748Z","steps":["trace[441467093] 'process raft request' (duration: 562.572414ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:04:14.749916Z","caller":"traceutil/trace.go:171","msg":"trace[35499734] transaction","detail":"{read_only:false; response_revision:5010; number_of_response:1; }","duration":"564.509596ms","start":"2026-03-17T22:04:14.185359Z","end":"2026-03-17T22:04:14.749869Z","steps":["trace[35499734] 'process raft request' (duration: 370.65325ms)","trace[35499734] 'compare' (duration: 192.592269ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T22:04:14.749912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T22:04:14.186974Z","time spent":"562.85385ms","remote":"127.0.0.1:41356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1482,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-17T22:04:14.750015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T22:04:14.185351Z","time spent":"564.613549ms","remote":"127.0.0.1:41482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1631,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-17T22:04:14.750186Z","caller":"traceutil/trace.go:171","msg":"trace[72926375] transaction","detail":"{read_only:false; response_revision:5012; number_of_response:1; }","duration":"563.083186ms","start":"2026-03-17T22:04:14.187087Z","end":"2026-03-17T22:04:14.75017Z","steps":["trace[72926375] 'process raft request' (duration: 562.554794ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:04:14.750285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T22:04:14.187056Z","time spent":"563.180158ms","remote":"127.0.0.1:41482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1514,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-17T22:04:14.750493Z","caller":"traceutil/trace.go:171","msg":"trace[1516604387] transaction","detail":"{read_only:false; response_revision:5013; number_of_response:1; }","duration":"562.416432ms","start":"2026-03-17T22:04:14.188049Z","end":"2026-03-17T22:04:14.750465Z","steps":["trace[1516604387] 'process raft request' (duration: 561.640775ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:04:14.750661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T22:04:14.188039Z","time spent":"562.521884ms","remote":"127.0.0.1:41470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-17T22:04:14.750793Z","caller":"traceutil/trace.go:171","msg":"trace[1304865410] transaction","detail":"{read_only:false; response_revision:5014; number_of_response:1; }","duration":"561.930552ms","start":"2026-03-17T22:04:14.188847Z","end":"2026-03-17T22:04:14.750777Z","steps":["trace[1304865410] 'process raft request' (duration: 561.387841ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:04:14.750888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T22:04:14.18883Z","time spent":"562.018784ms","remote":"127.0.0.1:41690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4237,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-17T22:04:14.7518Z","caller":"traceutil/trace.go:171","msg":"trace[1582008083] transaction","detail":"{read_only:false; response_revision:5015; number_of_response:1; }","duration":"556.861406ms","start":"2026-03-17T22:04:14.194909Z","end":"2026-03-17T22:04:14.75177Z","steps":["trace[1582008083] 'process raft request' (duration: 556.58417ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:04:14.751926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T22:04:14.194897Z","time spent":"556.968259ms","remote":"127.0.0.1:41368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13644,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-17T22:04:14.752125Z","caller":"traceutil/trace.go:171","msg":"trace[1455428113] transaction","detail":"{read_only:false; response_revision:5016; number_of_response:1; }","duration":"503.697634ms","start":"2026-03-17T22:04:14.248388Z","end":"2026-03-17T22:04:14.752086Z","steps":["trace[1455428113] 'process raft request' (duration: 503.323816ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:04:14.752247Z","caller":"traceutil/trace.go:171","msg":"trace[737614082] linearizableReadLoop","detail":"{readStateIndex:5208; appliedIndex:5202; }","duration":"514.424948ms","start":"2026-03-17T22:04:14.237803Z","end":"2026-03-17T22:04:14.752228Z","steps":["trace[737614082] 'read index received' (duration: 318.230505ms)","trace[737614082] 'applied index is now lower than readState.Index' (duration: 196.192843ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T22:04:14.752474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.810634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-17T22:04:14.752479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.664073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"warn","ts":"2026-03-17T22:04:14.752523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.812707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-rld2r\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-03-17T22:04:14.752535Z","caller":"traceutil/trace.go:171","msg":"trace[1728332055] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5017; }","duration":"424.906295ms","start":"2026-03-17T22:04:14.327614Z","end":"2026-03-17T22:04:14.75252Z","steps":["trace[1728332055] 'agreement among raft nodes before linearized reading' (duration: 424.737932ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:04:14.75255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.364535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-03-17T22:04:14.752564Z","caller":"traceutil/trace.go:171","msg":"trace[1849423181] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5017; }","duration":"514.762344ms","start":"2026-03-17T22:04:14.237777Z","end":"2026-03-17T22:04:14.752539Z","steps":["trace[1849423181] 'agreement among raft nodes before linearized reading' (duration: 514.54462ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:04:14.752584Z","caller":"traceutil/trace.go:171","msg":"trace[498939601] range","detail":"{range_begin:/registry/pods/openstack/keepalived-rld2r; range_end:; response_count:1; response_revision:5017; }","duration":"209.881638ms","start":"2026-03-17T22:04:14.542686Z","end":"2026-03-17T22:04:14.752568Z","steps":["trace[498939601] 'agreement among raft nodes before linearized reading' (duration: 209.672254ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:04:14.752588Z","caller":"traceutil/trace.go:171","msg":"trace[751154943] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5017; }","duration":"381.403326ms","start":"2026-03-17T22:04:14.371177Z","end":"2026-03-17T22:04:14.75258Z","steps":["trace[751154943] 'agreement among raft nodes before linearized reading' (duration: 381.297594ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:04:14.752594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T22:04:14.327593Z","time spent":"424.988817ms","remote":"127.0.0.1:41470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-17T22:04:14.752624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T22:04:14.371123Z","time spent":"381.495778ms","remote":"127.0.0.1:41470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-03-17T22:04:14.752623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T22:04:14.237762Z","time spent":"514.848417ms","remote":"127.0.0.1:41354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2106,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"warn","ts":"2026-03-17T22:04:14.752725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.167663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-17T22:04:14.752787Z","caller":"traceutil/trace.go:171","msg":"trace[504615763] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5017; }","duration":"382.261645ms","start":"2026-03-17T22:04:14.370509Z","end":"2026-03-17T22:04:14.75277Z","steps":["trace[504615763] 'agreement among raft nodes before linearized reading' (duration: 382.011859ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:04:14.752877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T22:04:14.37049Z","time spent":"382.336706ms","remote":"127.0.0.1:41470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-17T22:04:14.75287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T22:04:14.248368Z","time spent":"503.860277ms","remote":"127.0.0.1:41470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-17T22:04:15.015816Z","caller":"traceutil/trace.go:171","msg":"trace[1024565581] linearizableReadLoop","detail":"{readStateIndex:5211; appliedIndex:5210; }","duration":"257.941455ms","start":"2026-03-17T22:04:14.757845Z","end":"2026-03-17T22:04:15.015787Z","steps":["trace[1024565581] 'read index received' (duration: 228.817416ms)","trace[1024565581] 'applied index is now lower than readState.Index' (duration: 29.122279ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T22:04:15.015892Z","caller":"traceutil/trace.go:171","msg":"trace[989910878] transaction","detail":"{read_only:false; response_revision:5018; number_of_response:1; }","duration":"258.056527ms","start":"2026-03-17T22:04:14.7578Z","end":"2026-03-17T22:04:15.015857Z","steps":["trace[989910878] 'process raft request' (duration: 228.816486ms)","trace[989910878] 'compare' (duration: 28.923445ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T22:04:15.016104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.230172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16547"} {"level":"info","ts":"2026-03-17T22:04:15.016181Z","caller":"traceutil/trace.go:171","msg":"trace[1198147578] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5018; }","duration":"258.359594ms","start":"2026-03-17T22:04:14.757788Z","end":"2026-03-17T22:04:15.016148Z","steps":["trace[1198147578] 'agreement among raft nodes before linearized reading' (duration: 258.128438ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:04:15.037506Z","caller":"traceutil/trace.go:171","msg":"trace[1890889778] transaction","detail":"{read_only:false; response_revision:5019; number_of_response:1; }","duration":"279.168718ms","start":"2026-03-17T22:04:14.758316Z","end":"2026-03-17T22:04:15.037484Z","steps":["trace[1890889778] 'process raft request' (duration: 279.005294ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:04:15.037744Z","caller":"traceutil/trace.go:171","msg":"trace[1634959003] transaction","detail":"{read_only:false; response_revision:5020; number_of_response:1; }","duration":"279.031275ms","start":"2026-03-17T22:04:14.758698Z","end":"2026-03-17T22:04:15.037729Z","steps":["trace[1634959003] 'process raft request' (duration: 278.737639ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:04:15.038136Z","caller":"traceutil/trace.go:171","msg":"trace[812011762] transaction","detail":"{read_only:false; response_revision:5022; number_of_response:1; }","duration":"279.175998ms","start":"2026-03-17T22:04:14.758949Z","end":"2026-03-17T22:04:15.038125Z","steps":["trace[812011762] 'process raft request' (duration: 278.832701ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:04:15.038276Z","caller":"traceutil/trace.go:171","msg":"trace[838939540] transaction","detail":"{read_only:false; response_revision:5021; number_of_response:1; }","duration":"279.400613ms","start":"2026-03-17T22:04:14.758867Z","end":"2026-03-17T22:04:15.038267Z","steps":["trace[838939540] 'process raft request' (duration: 278.79899ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:04:15.038466Z","caller":"traceutil/trace.go:171","msg":"trace[44021991] transaction","detail":"{read_only:false; response_revision:5023; number_of_response:1; }","duration":"279.116847ms","start":"2026-03-17T22:04:14.759318Z","end":"2026-03-17T22:04:15.038434Z","steps":["trace[44021991] 'process raft request' (duration: 278.505253ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:04:15.038587Z","caller":"traceutil/trace.go:171","msg":"trace[700693055] transaction","detail":"{read_only:false; response_revision:5024; number_of_response:1; }","duration":"278.966255ms","start":"2026-03-17T22:04:14.759595Z","end":"2026-03-17T22:04:15.038561Z","steps":["trace[700693055] 'process raft request' (duration: 278.274789ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:04:15.038852Z","caller":"traceutil/trace.go:171","msg":"trace[2075934963] transaction","detail":"{read_only:false; response_revision:5026; number_of_response:1; }","duration":"273.113222ms","start":"2026-03-17T22:04:14.765701Z","end":"2026-03-17T22:04:15.038814Z","steps":["trace[2075934963] 'process raft request' (duration: 272.284934ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:04:15.038634Z","caller":"traceutil/trace.go:171","msg":"trace[1209216469] transaction","detail":"{read_only:false; response_revision:5025; number_of_response:1; }","duration":"278.337131ms","start":"2026-03-17T22:04:14.760248Z","end":"2026-03-17T22:04:15.038585Z","steps":["trace[1209216469] 'process raft request' (duration: 277.693137ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:04:15.040029Z","caller":"traceutil/trace.go:171","msg":"trace[1437784746] transaction","detail":"{read_only:false; response_revision:5027; number_of_response:1; }","duration":"135.579785ms","start":"2026-03-17T22:04:14.904434Z","end":"2026-03-17T22:04:15.040014Z","steps":["trace[1437784746] 'process raft request' (duration: 133.598483ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:04:15.040268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.889634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:9 size:80283"} {"level":"info","ts":"2026-03-17T22:04:15.040354Z","caller":"traceutil/trace.go:171","msg":"trace[647971694] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:9; response_revision:5027; }","duration":"280.983936ms","start":"2026-03-17T22:04:14.759347Z","end":"2026-03-17T22:04:15.040331Z","steps":["trace[647971694] 'agreement among raft nodes before linearized reading' (duration: 280.6681ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:04:21.129865Z","caller":"traceutil/trace.go:171","msg":"trace[856314088] transaction","detail":"{read_only:false; response_revision:5073; number_of_response:1; }","duration":"102.28885ms","start":"2026-03-17T22:04:21.027547Z","end":"2026-03-17T22:04:21.129836Z","steps":["trace[856314088] 'process raft request' (duration: 102.023795ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:04:21.130017Z","caller":"traceutil/trace.go:171","msg":"trace[2036185741] transaction","detail":"{read_only:false; response_revision:5074; number_of_response:1; }","duration":"101.454351ms","start":"2026-03-17T22:04:21.028536Z","end":"2026-03-17T22:04:21.12999Z","steps":["trace[2036185741] 'process raft request' (duration: 101.212746ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:04:25.808092Z","caller":"traceutil/trace.go:171","msg":"trace[271507890] transaction","detail":"{read_only:false; response_revision:5103; number_of_response:1; }","duration":"111.203446ms","start":"2026-03-17T22:04:25.696836Z","end":"2026-03-17T22:04:25.808039Z","steps":["trace[271507890] 'process raft request' (duration: 19.920867ms)","trace[271507890] 'compare' (duration: 90.925242ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T22:05:20.639214Z","caller":"traceutil/trace.go:171","msg":"trace[1412076512] transaction","detail":"{read_only:false; response_revision:5529; number_of_response:1; }","duration":"105.168264ms","start":"2026-03-17T22:05:20.534012Z","end":"2026-03-17T22:05:20.63918Z","steps":["trace[1412076512] 'process raft request' (duration: 104.892079ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:05:44.417104Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2740} {"level":"info","ts":"2026-03-17T22:05:44.476655Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2740,"took":"57.332201ms","hash":102143708,"current-db-size-bytes":41164800,"current-db-size":"41 MB","current-db-size-in-use-bytes":30892032,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-03-17T22:05:44.476747Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":102143708,"revision":2740,"compact-revision":-1} {"level":"warn","ts":"2026-03-17T22:10:09.632022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.98622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-rld2r\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-03-17T22:10:09.63213Z","caller":"traceutil/trace.go:171","msg":"trace[1017921363] range","detail":"{range_begin:/registry/pods/openstack/keepalived-rld2r; range_end:; response_count:1; response_revision:7550; }","duration":"370.141743ms","start":"2026-03-17T22:10:09.261966Z","end":"2026-03-17T22:10:09.632108Z","steps":["trace[1017921363] 'range keys from in-memory index tree' (duration: 369.808796ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:10:09.632174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-17T22:10:09.261938Z","time spent":"370.225425ms","remote":"127.0.0.1:41368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6551,"request content":"key:\"/registry/pods/openstack/keepalived-rld2r\" "} {"level":"warn","ts":"2026-03-17T22:10:09.632221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.936545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T22:10:09.632326Z","caller":"traceutil/trace.go:171","msg":"trace[854618728] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:7550; }","duration":"101.104339ms","start":"2026-03-17T22:10:09.531191Z","end":"2026-03-17T22:10:09.632295Z","steps":["trace[854618728] 'count revisions from in-memory index tree' (duration: 100.824383ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:10:21.239135Z","caller":"traceutil/trace.go:171","msg":"trace[608968838] transaction","detail":"{read_only:false; response_revision:7605; number_of_response:1; }","duration":"136.198199ms","start":"2026-03-17T22:10:21.102895Z","end":"2026-03-17T22:10:21.239093Z","steps":["trace[608968838] 'process raft request' (duration: 96.45304ms)","trace[608968838] 'compare' (duration: 39.453242ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T22:10:21.805227Z","caller":"traceutil/trace.go:171","msg":"trace[873524713] linearizableReadLoop","detail":"{readStateIndex:7886; appliedIndex:7885; }","duration":"110.472069ms","start":"2026-03-17T22:10:21.69472Z","end":"2026-03-17T22:10:21.805192Z","steps":["trace[873524713] 'read index received' (duration: 110.254455ms)","trace[873524713] 'applied index is now lower than readState.Index' (duration: 216.614µs)"],"step_count":2} {"level":"info","ts":"2026-03-17T22:10:21.805339Z","caller":"traceutil/trace.go:171","msg":"trace[566541193] transaction","detail":"{read_only:false; response_revision:7607; number_of_response:1; }","duration":"110.615132ms","start":"2026-03-17T22:10:21.694631Z","end":"2026-03-17T22:10:21.805247Z","steps":["trace[566541193] 'process raft request' (duration: 110.407238ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:10:21.805493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.728925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-03-17T22:10:21.805526Z","caller":"traceutil/trace.go:171","msg":"trace[1269770895] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7607; }","duration":"110.842327ms","start":"2026-03-17T22:10:21.694675Z","end":"2026-03-17T22:10:21.805517Z","steps":["trace[1269770895] 'agreement among raft nodes before linearized reading' (duration: 110.673033ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:10:21.805584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.460352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-rld2r\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-03-17T22:10:21.805658Z","caller":"traceutil/trace.go:171","msg":"trace[1476622125] range","detail":"{range_begin:/registry/pods/openstack/keepalived-rld2r; range_end:; response_count:1; response_revision:7607; }","duration":"104.573984ms","start":"2026-03-17T22:10:21.701064Z","end":"2026-03-17T22:10:21.805638Z","steps":["trace[1476622125] 'agreement among raft nodes before linearized reading' (duration: 104.332798ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:10:22.179431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.894716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T22:10:22.179536Z","caller":"traceutil/trace.go:171","msg":"trace[432709655] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7608; }","duration":"213.060239ms","start":"2026-03-17T22:10:21.966452Z","end":"2026-03-17T22:10:22.179513Z","steps":["trace[432709655] 'range keys from in-memory index tree' (duration: 212.769793ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:10:22.353804Z","caller":"traceutil/trace.go:171","msg":"trace[776575579] transaction","detail":"{read_only:false; response_revision:7610; number_of_response:1; }","duration":"150.34832ms","start":"2026-03-17T22:10:22.203415Z","end":"2026-03-17T22:10:22.353763Z","steps":["trace[776575579] 'process raft request' (duration: 78.738022ms)","trace[776575579] 'compare' (duration: 71.427204ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T22:10:22.985573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.869651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-03-17T22:10:22.985672Z","caller":"traceutil/trace.go:171","msg":"trace[1700071094] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:7611; }","duration":"224.024744ms","start":"2026-03-17T22:10:22.761616Z","end":"2026-03-17T22:10:22.98564Z","steps":["trace[1700071094] 'range keys from in-memory index tree' (duration: 223.680967ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:10:23.300258Z","caller":"traceutil/trace.go:171","msg":"trace[779293612] linearizableReadLoop","detail":"{readStateIndex:7894; appliedIndex:7893; }","duration":"163.611523ms","start":"2026-03-17T22:10:23.136584Z","end":"2026-03-17T22:10:23.300195Z","steps":["trace[779293612] 'read index received' (duration: 163.300147ms)","trace[779293612] 'applied index is now lower than readState.Index' (duration: 309.286µs)"],"step_count":2} {"level":"info","ts":"2026-03-17T22:10:23.300331Z","caller":"traceutil/trace.go:171","msg":"trace[1734671967] transaction","detail":"{read_only:false; response_revision:7614; number_of_response:1; }","duration":"164.757947ms","start":"2026-03-17T22:10:23.135524Z","end":"2026-03-17T22:10:23.300282Z","steps":["trace[1734671967] 'process raft request' (duration: 164.383099ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:10:23.300579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.95885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19544"} {"level":"info","ts":"2026-03-17T22:10:23.300674Z","caller":"traceutil/trace.go:171","msg":"trace[1921737647] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:7614; }","duration":"164.086043ms","start":"2026-03-17T22:10:23.136567Z","end":"2026-03-17T22:10:23.300653Z","steps":["trace[1921737647] 'agreement among raft nodes before linearized reading' (duration: 163.818217ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:10:23.328875Z","caller":"traceutil/trace.go:171","msg":"trace[846080975] transaction","detail":"{read_only:false; response_revision:7615; number_of_response:1; }","duration":"191.015329ms","start":"2026-03-17T22:10:23.137811Z","end":"2026-03-17T22:10:23.328826Z","steps":["trace[846080975] 'process raft request' (duration: 190.713422ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:10:23.511247Z","caller":"traceutil/trace.go:171","msg":"trace[30968647] transaction","detail":"{read_only:false; response_revision:7616; number_of_response:1; }","duration":"177.177714ms","start":"2026-03-17T22:10:23.334037Z","end":"2026-03-17T22:10:23.511215Z","steps":["trace[30968647] 'process raft request' (duration: 176.98635ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:10:23.514145Z","caller":"traceutil/trace.go:171","msg":"trace[2017556731] linearizableReadLoop","detail":"{readStateIndex:7897; appliedIndex:7895; }","duration":"153.57766ms","start":"2026-03-17T22:10:23.36054Z","end":"2026-03-17T22:10:23.514117Z","steps":["trace[2017556731] 'read index received' (duration: 150.625287ms)","trace[2017556731] 'applied index is now lower than readState.Index' (duration: 2.951093ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T22:10:23.514295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.734183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-17T22:10:23.514348Z","caller":"traceutil/trace.go:171","msg":"trace[577526589] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:7617; }","duration":"153.850905ms","start":"2026-03-17T22:10:23.360484Z","end":"2026-03-17T22:10:23.514334Z","steps":["trace[577526589] 'agreement among raft nodes before linearized reading' (duration: 153.751083ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:10:23.514209Z","caller":"traceutil/trace.go:171","msg":"trace[1707108707] transaction","detail":"{read_only:false; response_revision:7617; number_of_response:1; }","duration":"179.725837ms","start":"2026-03-17T22:10:23.334456Z","end":"2026-03-17T22:10:23.514182Z","steps":["trace[1707108707] 'process raft request' (duration: 179.528693ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:10:44.423825Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5698} {"level":"info","ts":"2026-03-17T22:10:44.512798Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5698,"took":"87.112767ms","hash":3139488052,"current-db-size-bytes":42332160,"current-db-size":"42 MB","current-db-size-in-use-bytes":22958080,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-03-17T22:10:44.512883Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3139488052,"revision":5698,"compact-revision":2740} {"level":"info","ts":"2026-03-17T22:10:50.235278Z","caller":"traceutil/trace.go:171","msg":"trace[1327431559] linearizableReadLoop","detail":"{readStateIndex:8088; appliedIndex:8087; }","duration":"243.607456ms","start":"2026-03-17T22:10:49.991649Z","end":"2026-03-17T22:10:50.235256Z","steps":["trace[1327431559] 'read index received' (duration: 243.469333ms)","trace[1327431559] 'applied index is now lower than readState.Index' (duration: 137.363µs)"],"step_count":2} {"level":"info","ts":"2026-03-17T22:10:50.23533Z","caller":"traceutil/trace.go:171","msg":"trace[1279106164] transaction","detail":"{read_only:false; response_revision:7799; number_of_response:1; }","duration":"269.953454ms","start":"2026-03-17T22:10:49.96534Z","end":"2026-03-17T22:10:50.235293Z","steps":["trace[1279106164] 'process raft request' (duration: 269.799631ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:10:50.235516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.159401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-rld2r\" ","response":"range_response_count:1 size:6527"} {"level":"warn","ts":"2026-03-17T22:10:50.235527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.856361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-03-17T22:10:50.235553Z","caller":"traceutil/trace.go:171","msg":"trace[1236423960] range","detail":"{range_begin:/registry/pods/openstack/keepalived-rld2r; range_end:; response_count:1; response_revision:7799; }","duration":"214.241042ms","start":"2026-03-17T22:10:50.021303Z","end":"2026-03-17T22:10:50.235544Z","steps":["trace[1236423960] 'agreement among raft nodes before linearized reading' (duration: 214.099649ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:10:50.23556Z","caller":"traceutil/trace.go:171","msg":"trace[1658731952] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7799; }","duration":"243.940002ms","start":"2026-03-17T22:10:49.99161Z","end":"2026-03-17T22:10:50.23555Z","steps":["trace[1658731952] 'agreement among raft nodes before linearized reading' (duration: 243.763308ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:10:50.362764Z","caller":"traceutil/trace.go:171","msg":"trace[2120381706] linearizableReadLoop","detail":"{readStateIndex:8089; appliedIndex:8088; }","duration":"121.795244ms","start":"2026-03-17T22:10:50.240935Z","end":"2026-03-17T22:10:50.36273Z","steps":["trace[2120381706] 'read index received' (duration: 119.502245ms)","trace[2120381706] 'applied index is now lower than readState.Index' (duration: 2.291799ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T22:10:50.363018Z","caller":"traceutil/trace.go:171","msg":"trace[1020035681] transaction","detail":"{read_only:false; response_revision:7800; number_of_response:1; }","duration":"123.439018ms","start":"2026-03-17T22:10:50.239537Z","end":"2026-03-17T22:10:50.362976Z","steps":["trace[1020035681] 'process raft request' (duration: 120.809032ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:10:50.36325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.280044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228535"} {"level":"info","ts":"2026-03-17T22:10:50.363288Z","caller":"traceutil/trace.go:171","msg":"trace[864534526] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:7800; }","duration":"122.374645ms","start":"2026-03-17T22:10:50.240902Z","end":"2026-03-17T22:10:50.363277Z","steps":["trace[864534526] 'agreement among raft nodes before linearized reading' (duration: 121.946866ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:10:51.685801Z","caller":"traceutil/trace.go:171","msg":"trace[167602297] linearizableReadLoop","detail":"{readStateIndex:8093; appliedIndex:8092; }","duration":"155.705182ms","start":"2026-03-17T22:10:51.530064Z","end":"2026-03-17T22:10:51.685769Z","steps":["trace[167602297] 'read index received' (duration: 137.993127ms)","trace[167602297] 'applied index is now lower than readState.Index' (duration: 17.710955ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T22:10:51.685858Z","caller":"traceutil/trace.go:171","msg":"trace[1443165413] transaction","detail":"{read_only:false; response_revision:7804; number_of_response:1; }","duration":"159.303248ms","start":"2026-03-17T22:10:51.526497Z","end":"2026-03-17T22:10:51.6858Z","steps":["trace[1443165413] 'process raft request' (duration: 141.635403ms)","trace[1443165413] 'compare' (duration: 17.530142ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T22:10:51.686096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.017518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-03-17T22:10:51.686205Z","caller":"traceutil/trace.go:171","msg":"trace[950853863] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:7804; }","duration":"156.180011ms","start":"2026-03-17T22:10:51.529999Z","end":"2026-03-17T22:10:51.686179Z","steps":["trace[950853863] 'agreement among raft nodes before linearized reading' (duration: 155.869435ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:11:02.524957Z","caller":"traceutil/trace.go:171","msg":"trace[146908156] linearizableReadLoop","detail":"{readStateIndex:8194; appliedIndex:8193; }","duration":"195.432803ms","start":"2026-03-17T22:11:02.329483Z","end":"2026-03-17T22:11:02.524916Z","steps":["trace[146908156] 'read index received' (duration: 144.145786ms)","trace[146908156] 'applied index is now lower than readState.Index' (duration: 51.284877ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T22:11:02.525118Z","caller":"traceutil/trace.go:171","msg":"trace[515381624] transaction","detail":"{read_only:false; response_revision:7899; number_of_response:1; }","duration":"195.916585ms","start":"2026-03-17T22:11:02.329166Z","end":"2026-03-17T22:11:02.525082Z","steps":["trace[515381624] 'process raft request' (duration: 144.447854ms)","trace[515381624] 'compare' (duration: 51.084893ms)"],"step_count":2} {"level":"warn","ts":"2026-03-17T22:11:02.525324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.802581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-hwfjq\" ","response":"range_response_count:1 size:4666"} {"level":"info","ts":"2026-03-17T22:11:02.525378Z","caller":"traceutil/trace.go:171","msg":"trace[1848550630] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-hwfjq; range_end:; response_count:1; response_revision:7899; }","duration":"195.914864ms","start":"2026-03-17T22:11:02.329446Z","end":"2026-03-17T22:11:02.525361Z","steps":["trace[1848550630] 'agreement among raft nodes before linearized reading' (duration: 195.696349ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:11:02.54309Z","caller":"traceutil/trace.go:171","msg":"trace[1907031223] transaction","detail":"{read_only:false; response_revision:7900; number_of_response:1; }","duration":"129.505016ms","start":"2026-03-17T22:11:02.413556Z","end":"2026-03-17T22:11:02.543061Z","steps":["trace[1907031223] 'process raft request' (duration: 129.377823ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:11:02.553388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.617099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-hwfjq\" ","response":"range_response_count:1 size:2800"} {"level":"warn","ts":"2026-03-17T22:11:02.553436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.52011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-rld2r\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-03-17T22:11:02.553469Z","caller":"traceutil/trace.go:171","msg":"trace[1723393362] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-hwfjq; range_end:; response_count:1; response_revision:7901; }","duration":"221.746232ms","start":"2026-03-17T22:11:02.331699Z","end":"2026-03-17T22:11:02.553445Z","steps":["trace[1723393362] 'agreement among raft nodes before linearized reading' (duration: 221.492177ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:11:02.553488Z","caller":"traceutil/trace.go:171","msg":"trace[487215819] range","detail":"{range_begin:/registry/pods/openstack/keepalived-rld2r; range_end:; response_count:1; response_revision:7901; }","duration":"125.609764ms","start":"2026-03-17T22:11:02.427863Z","end":"2026-03-17T22:11:02.553473Z","steps":["trace[487215819] 'agreement among raft nodes before linearized reading' (duration: 125.46409ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:11:02.553802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.571423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-03-17T22:11:02.554255Z","caller":"traceutil/trace.go:171","msg":"trace[1648271385] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7901; }","duration":"142.110453ms","start":"2026-03-17T22:11:02.412109Z","end":"2026-03-17T22:11:02.554219Z","steps":["trace[1648271385] 'agreement among raft nodes before linearized reading' (duration: 141.244504ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:11:03.255207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.495808ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-03-17T22:11:03.964502Z","caller":"traceutil/trace.go:171","msg":"trace[606015257] transaction","detail":"{read_only:false; response_revision:7912; number_of_response:1; }","duration":"136.291509ms","start":"2026-03-17T22:11:03.828147Z","end":"2026-03-17T22:11:03.964438Z","steps":["trace[606015257] 'process raft request' (duration: 107.07327ms)","trace[606015257] 'compare' (duration: 29.051616ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T22:11:03.964622Z","caller":"traceutil/trace.go:171","msg":"trace[2094463202] linearizableReadLoop","detail":"{readStateIndex:8209; appliedIndex:8206; }","duration":"107.05048ms","start":"2026-03-17T22:11:03.857537Z","end":"2026-03-17T22:11:03.964588Z","steps":["trace[2094463202] 'read index received' (duration: 33.365738ms)","trace[2094463202] 'applied index is now lower than readState.Index' (duration: 73.683282ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T22:11:03.964707Z","caller":"traceutil/trace.go:171","msg":"trace[591601859] transaction","detail":"{read_only:false; response_revision:7913; number_of_response:1; }","duration":"136.276939ms","start":"2026-03-17T22:11:03.828402Z","end":"2026-03-17T22:11:03.964679Z","steps":["trace[591601859] 'process raft request' (duration: 135.988313ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:11:03.964939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.288885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-03-17T22:11:03.964995Z","caller":"traceutil/trace.go:171","msg":"trace[36391675] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:7913; }","duration":"107.493089ms","start":"2026-03-17T22:11:03.857486Z","end":"2026-03-17T22:11:03.964979Z","steps":["trace[36391675] 'agreement among raft nodes before linearized reading' (duration: 107.196223ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:12:15.115051Z","caller":"traceutil/trace.go:171","msg":"trace[109584657] transaction","detail":"{read_only:false; response_revision:8533; number_of_response:1; }","duration":"142.083203ms","start":"2026-03-17T22:12:14.972937Z","end":"2026-03-17T22:12:15.11502Z","steps":["trace[109584657] 'process raft request' (duration: 141.879779ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:12:15.139101Z","caller":"traceutil/trace.go:171","msg":"trace[1834978260] linearizableReadLoop","detail":"{readStateIndex:8849; appliedIndex:8847; }","duration":"107.717694ms","start":"2026-03-17T22:12:15.031341Z","end":"2026-03-17T22:12:15.139059Z","steps":["trace[1834978260] 'read index received' (duration: 83.517732ms)","trace[1834978260] 'applied index is now lower than readState.Index' (duration: 24.197742ms)"],"step_count":2} {"level":"info","ts":"2026-03-17T22:12:15.139214Z","caller":"traceutil/trace.go:171","msg":"trace[1280097723] transaction","detail":"{read_only:false; response_revision:8534; number_of_response:1; }","duration":"164.726262ms","start":"2026-03-17T22:12:14.974451Z","end":"2026-03-17T22:12:15.139177Z","steps":["trace[1280097723] 'process raft request' (duration: 164.308504ms)"],"step_count":1} {"level":"warn","ts":"2026-03-17T22:12:15.13967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.291476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-rld2r\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-03-17T22:12:15.13975Z","caller":"traceutil/trace.go:171","msg":"trace[75855772] range","detail":"{range_begin:/registry/pods/openstack/keepalived-rld2r; range_end:; response_count:1; response_revision:8534; }","duration":"108.4437ms","start":"2026-03-17T22:12:15.031288Z","end":"2026-03-17T22:12:15.139732Z","steps":["trace[75855772] 'agreement among raft nodes before linearized reading' (duration: 107.934918ms)"],"step_count":1} {"level":"info","ts":"2026-03-17T22:14:38.358132Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"fba6d6d8cd5f215c","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-17T22:14:38.364607Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-03-17T22:14:38.364724Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-03-17T22:15:44.429748Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7726} {"level":"info","ts":"2026-03-17T22:15:44.492271Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7726,"took":"60.161361ms","hash":4282427873,"current-db-size-bytes":42332160,"current-db-size":"42 MB","current-db-size-in-use-bytes":26058752,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-03-17T22:15:44.492328Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4282427873,"revision":7726,"compact-revision":5698} {"level":"info","ts":"2026-03-17T22:20:44.43509Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10006} {"level":"info","ts":"2026-03-17T22:20:44.512968Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10006,"took":"75.686471ms","hash":1992358406,"current-db-size-bytes":42332160,"current-db-size":"42 MB","current-db-size-in-use-bytes":16879616,"current-db-size-in-use":"17 MB"} {"level":"info","ts":"2026-03-17T22:20:44.513027Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1992358406,"revision":10006,"compact-revision":7726}