{"level":"warn","ts":"2026-03-09T06:16:32.877583Z","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-09T06:16:32.877684Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.49: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://162.253.55.49:2380","--initial-cluster=instance=https://162.253.55.49:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.49:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.49: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-09T06:16:32.877751Z","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-09T06:16:32.877763Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.49:2380"]} {"level":"info","ts":"2026-03-09T06:16:32.877784Z","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-09T06:16:32.878427Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.49:2379"]} {"level":"info","ts":"2026-03-09T06:16:32.878498Z","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://162.253.55.49:2380"],"listen-peer-urls":["https://162.253.55.49:2380"],"advertise-client-urls":["https://162.253.55.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.49: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-09T06:16:32.882954Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.252057ms"} {"level":"info","ts":"2026-03-09T06:16:32.891135Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6a9ba61e55fef5be","cluster-id":"9612db6e19ac6ce7"} {"level":"info","ts":"2026-03-09T06:16:32.891238Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be switched to configuration voters=()"} {"level":"info","ts":"2026-03-09T06:16:32.891275Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became follower at term 0"} {"level":"info","ts":"2026-03-09T06:16:32.891295Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6a9ba61e55fef5be [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-09T06:16:32.891318Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became follower at term 1"} {"level":"info","ts":"2026-03-09T06:16:32.891377Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be switched to configuration voters=(7681916238632515006)"} {"level":"warn","ts":"2026-03-09T06:16:32.896794Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-09T06:16:32.898616Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-09T06:16:32.900096Z","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-09T06:16:32.902999Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6a9ba61e55fef5be","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-09T06:16:32.903232Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6a9ba61e55fef5be","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-09T06:16:32.903278Z","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-09T06:16:32.903344Z","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-09T06:16:32.903356Z","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-09T06:16:32.90522Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be switched to configuration voters=(7681916238632515006)"} {"level":"info","ts":"2026-03-09T06:16:32.905523Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9612db6e19ac6ce7","local-member-id":"6a9ba61e55fef5be","added-peer-id":"6a9ba61e55fef5be","added-peer-peer-urls":["https://162.253.55.49:2380"]} {"level":"info","ts":"2026-03-09T06:16:32.909038Z","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-09T06:16:32.90919Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.49:2380"} {"level":"info","ts":"2026-03-09T06:16:32.909267Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.49:2380"} {"level":"info","ts":"2026-03-09T06:16:32.909651Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6a9ba61e55fef5be","initial-advertise-peer-urls":["https://162.253.55.49:2380"],"listen-peer-urls":["https://162.253.55.49:2380"],"advertise-client-urls":["https://162.253.55.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-09T06:16:32.909732Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-09T06:16:32.991825Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be is starting a new election at term 1"} {"level":"info","ts":"2026-03-09T06:16:32.991914Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became pre-candidate at term 1"} {"level":"info","ts":"2026-03-09T06:16:32.991945Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be received MsgPreVoteResp from 6a9ba61e55fef5be at term 1"} {"level":"info","ts":"2026-03-09T06:16:32.991962Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became candidate at term 2"} {"level":"info","ts":"2026-03-09T06:16:32.991972Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be received MsgVoteResp from 6a9ba61e55fef5be at term 2"} {"level":"info","ts":"2026-03-09T06:16:32.992122Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became leader at term 2"} {"level":"info","ts":"2026-03-09T06:16:32.992136Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6a9ba61e55fef5be elected leader 6a9ba61e55fef5be at term 2"} {"level":"info","ts":"2026-03-09T06:16:32.993232Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6a9ba61e55fef5be","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.49:2379]}","request-path":"/0/members/6a9ba61e55fef5be/attributes","cluster-id":"9612db6e19ac6ce7","publish-timeout":"7s"} {"level":"info","ts":"2026-03-09T06:16:32.993287Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-09T06:16:32.99332Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-09T06:16:32.993481Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-09T06:16:32.993578Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-09T06:16:32.993652Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-09T06:16:32.996689Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.49:2379"} {"level":"info","ts":"2026-03-09T06:16:32.996875Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9612db6e19ac6ce7","local-member-id":"6a9ba61e55fef5be","cluster-version":"3.5"} {"level":"info","ts":"2026-03-09T06:16:32.99699Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-09T06:16:32.997032Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-09T06:16:32.997176Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-09T06:16:52.410672Z","caller":"traceutil/trace.go:171","msg":"trace[1920751261] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"180.184951ms","start":"2026-03-09T06:16:52.230447Z","end":"2026-03-09T06:16:52.410632Z","steps":["trace[1920751261] 'process raft request' (duration: 179.934457ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:16:58.144274Z","caller":"traceutil/trace.go:171","msg":"trace[1402324427] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"157.60599ms","start":"2026-03-09T06:16:57.986645Z","end":"2026-03-09T06:16:58.144251Z","steps":["trace[1402324427] 'process raft request' (duration: 157.493688ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:17:02.890397Z","caller":"traceutil/trace.go:171","msg":"trace[1523791444] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"115.575889ms","start":"2026-03-09T06:17:02.774799Z","end":"2026-03-09T06:17:02.890375Z","steps":["trace[1523791444] 'process raft request' (duration: 102.783117ms)","trace[1523791444] 'compare' (duration: 12.695561ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T06:17:08.918202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.003001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-09T06:17:08.918324Z","caller":"traceutil/trace.go:171","msg":"trace[1503226566] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:550; }","duration":"178.172845ms","start":"2026-03-09T06:17:08.74013Z","end":"2026-03-09T06:17:08.918302Z","steps":["trace[1503226566] 'range keys from in-memory index tree' (duration: 177.816508ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:17:09.053937Z","caller":"traceutil/trace.go:171","msg":"trace[978835333] linearizableReadLoop","detail":"{readStateIndex:570; appliedIndex:569; }","duration":"114.535639ms","start":"2026-03-09T06:17:08.939366Z","end":"2026-03-09T06:17:09.053902Z","steps":["trace[978835333] 'read index received' (duration: 114.289104ms)","trace[978835333] 'applied index is now lower than readState.Index' (duration: 244.915µs)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:17:09.054036Z","caller":"traceutil/trace.go:171","msg":"trace[852554823] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"130.268527ms","start":"2026-03-09T06:17:08.923732Z","end":"2026-03-09T06:17:09.054Z","steps":["trace[852554823] 'process raft request' (duration: 129.957902ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:17:09.054448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.976137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-09T06:17:09.05457Z","caller":"traceutil/trace.go:171","msg":"trace[651985611] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:551; }","duration":"115.249022ms","start":"2026-03-09T06:17:08.93929Z","end":"2026-03-09T06:17:09.054539Z","steps":["trace[651985611] 'agreement among raft nodes before linearized reading' (duration: 114.750163ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:17:11.513271Z","caller":"traceutil/trace.go:171","msg":"trace[56467086] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"134.699128ms","start":"2026-03-09T06:17:11.378547Z","end":"2026-03-09T06:17:11.513246Z","steps":["trace[56467086] 'process raft request' (duration: 134.530555ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:17:11.521117Z","caller":"traceutil/trace.go:171","msg":"trace[1690203959] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"139.278832ms","start":"2026-03-09T06:17:11.381816Z","end":"2026-03-09T06:17:11.521094Z","steps":["trace[1690203959] 'process raft request' (duration: 138.876765ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:17:31.875837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.414909ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-09T06:17:31.876199Z","caller":"traceutil/trace.go:171","msg":"trace[210233682] linearizableReadLoop","detail":"{readStateIndex:971; appliedIndex:969; }","duration":"311.07224ms","start":"2026-03-09T06:17:31.565102Z","end":"2026-03-09T06:17:31.876174Z","steps":["trace[210233682] 'read index received' (duration: 305.529588ms)","trace[210233682] 'applied index is now lower than readState.Index' (duration: 5.541522ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:17:31.876269Z","caller":"traceutil/trace.go:171","msg":"trace[2080844928] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"383.66681ms","start":"2026-03-09T06:17:31.492574Z","end":"2026-03-09T06:17:31.87624Z","steps":["trace[2080844928] 'process raft request' (duration: 383.447726ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:17:31.876321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.217782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T06:17:31.87637Z","caller":"traceutil/trace.go:171","msg":"trace[1386173024] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:942; }","duration":"311.328644ms","start":"2026-03-09T06:17:31.565028Z","end":"2026-03-09T06:17:31.876357Z","steps":["trace[1386173024] 'agreement among raft nodes before linearized reading' (duration: 311.235143ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:17:31.876411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:17:31.564985Z","time spent":"311.415056ms","remote":"127.0.0.1:52022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-09T06:17:31.877263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:17:31.492547Z","time spent":"383.792102ms","remote":"127.0.0.1:52442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4734,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-09T06:17:57.739001Z","caller":"traceutil/trace.go:171","msg":"trace[1834488997] transaction","detail":"{read_only:false; response_revision:1207; number_of_response:1; }","duration":"125.598802ms","start":"2026-03-09T06:17:57.613377Z","end":"2026-03-09T06:17:57.738976Z","steps":["trace[1834488997] 'process raft request' (duration: 125.428609ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:18:20.115183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.071209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-09T06:18:20.115317Z","caller":"traceutil/trace.go:171","msg":"trace[106186055] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1598; }","duration":"269.288893ms","start":"2026-03-09T06:18:19.846Z","end":"2026-03-09T06:18:20.115289Z","steps":["trace[106186055] 'range keys from in-memory index tree' (duration: 268.695481ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:18:20.115417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.739185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-03-09T06:18:20.115569Z","caller":"traceutil/trace.go:171","msg":"trace[617657098] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1598; }","duration":"322.978859ms","start":"2026-03-09T06:18:19.792558Z","end":"2026-03-09T06:18:20.115537Z","steps":["trace[617657098] 'range keys from in-memory index tree' (duration: 322.388528ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:18:20.115633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:18:19.792532Z","time spent":"323.088043ms","remote":"127.0.0.1:53688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-03-09T06:18:20.237978Z","caller":"traceutil/trace.go:171","msg":"trace[663871656] transaction","detail":"{read_only:false; response_revision:1599; number_of_response:1; }","duration":"116.893176ms","start":"2026-03-09T06:18:20.121043Z","end":"2026-03-09T06:18:20.237936Z","steps":["trace[663871656] 'process raft request' (duration: 116.681072ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:18:20.733171Z","caller":"traceutil/trace.go:171","msg":"trace[192632529] transaction","detail":"{read_only:false; response_revision:1600; number_of_response:1; }","duration":"137.693879ms","start":"2026-03-09T06:18:20.595445Z","end":"2026-03-09T06:18:20.733139Z","steps":["trace[192632529] 'process raft request' (duration: 137.467554ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:18:37.357629Z","caller":"traceutil/trace.go:171","msg":"trace[1132957866] transaction","detail":"{read_only:false; response_revision:1730; number_of_response:1; }","duration":"191.909936ms","start":"2026-03-09T06:18:37.165685Z","end":"2026-03-09T06:18:37.357594Z","steps":["trace[1132957866] 'process raft request' (duration: 191.658242ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:18:37.669482Z","caller":"traceutil/trace.go:171","msg":"trace[98346754] transaction","detail":"{read_only:false; response_revision:1732; number_of_response:1; }","duration":"224.010185ms","start":"2026-03-09T06:18:37.445239Z","end":"2026-03-09T06:18:37.669249Z","steps":["trace[98346754] 'process raft request' (duration: 223.466474ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:18:37.669578Z","caller":"traceutil/trace.go:171","msg":"trace[2003665948] linearizableReadLoop","detail":"{readStateIndex:1804; appliedIndex:1802; }","duration":"163.148972ms","start":"2026-03-09T06:18:37.50638Z","end":"2026-03-09T06:18:37.669529Z","steps":["trace[2003665948] 'read index received' (duration: 162.312676ms)","trace[2003665948] 'applied index is now lower than readState.Index' (duration: 834.956µs)"],"step_count":2} {"level":"warn","ts":"2026-03-09T06:18:37.669834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.427757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-09T06:18:37.6699Z","caller":"traceutil/trace.go:171","msg":"trace[803524430] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1733; }","duration":"163.56634ms","start":"2026-03-09T06:18:37.506317Z","end":"2026-03-09T06:18:37.669884Z","steps":["trace[803524430] 'agreement among raft nodes before linearized reading' (duration: 163.413917ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:18:37.669851Z","caller":"traceutil/trace.go:171","msg":"trace[662721896] transaction","detail":"{read_only:false; response_revision:1733; number_of_response:1; }","duration":"200.250324ms","start":"2026-03-09T06:18:37.469543Z","end":"2026-03-09T06:18:37.669793Z","steps":["trace[662721896] 'process raft request' (duration: 199.824576ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:18:37.66991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.550433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-09T06:18:37.66997Z","caller":"traceutil/trace.go:171","msg":"trace[1590260057] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1733; }","duration":"141.670884ms","start":"2026-03-09T06:18:37.528285Z","end":"2026-03-09T06:18:37.669955Z","steps":["trace[1590260057] 'agreement among raft nodes before linearized reading' (duration: 141.46676ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:18:37.670031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.957264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-09T06:18:37.670101Z","caller":"traceutil/trace.go:171","msg":"trace[1959821512] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1733; }","duration":"120.080205ms","start":"2026-03-09T06:18:37.550001Z","end":"2026-03-09T06:18:37.670081Z","steps":["trace[1959821512] 'agreement among raft nodes before linearized reading' (duration: 119.77838ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:19:11.406484Z","caller":"traceutil/trace.go:171","msg":"trace[70157239] transaction","detail":"{read_only:false; response_revision:1907; number_of_response:1; }","duration":"246.305556ms","start":"2026-03-09T06:19:11.160156Z","end":"2026-03-09T06:19:11.406461Z","steps":["trace[70157239] 'process raft request' (duration: 246.063822ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:19:11.406939Z","caller":"traceutil/trace.go:171","msg":"trace[338085784] transaction","detail":"{read_only:false; response_revision:1906; number_of_response:1; }","duration":"247.306115ms","start":"2026-03-09T06:19:11.158984Z","end":"2026-03-09T06:19:11.406291Z","steps":["trace[338085784] 'process raft request' (duration: 178.749796ms)","trace[338085784] 'compare' (duration: 68.214503ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:19:11.933817Z","caller":"traceutil/trace.go:171","msg":"trace[1471613649] transaction","detail":"{read_only:false; response_revision:1908; number_of_response:1; }","duration":"377.701157ms","start":"2026-03-09T06:19:11.556091Z","end":"2026-03-09T06:19:11.933793Z","steps":["trace[1471613649] 'process raft request' (duration: 377.32314ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:19:11.933996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:19:11.556059Z","time spent":"377.8406ms","remote":"127.0.0.1:52272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-09T06:19:11.970575Z","caller":"traceutil/trace.go:171","msg":"trace[1747866630] transaction","detail":"{read_only:false; response_revision:1909; number_of_response:1; }","duration":"129.084496ms","start":"2026-03-09T06:19:11.84147Z","end":"2026-03-09T06:19:11.970554Z","steps":["trace[1747866630] 'process raft request' (duration: 128.917933ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:19:11.970684Z","caller":"traceutil/trace.go:171","msg":"trace[1147325135] linearizableReadLoop","detail":"{readStateIndex:1987; appliedIndex:1985; }","duration":"355.165519ms","start":"2026-03-09T06:19:11.615491Z","end":"2026-03-09T06:19:11.970656Z","steps":["trace[1147325135] 'read index received' (duration: 317.949694ms)","trace[1147325135] 'applied index is now lower than readState.Index' (duration: 37.214125ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T06:19:11.970894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.402363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-03-09T06:19:11.97097Z","caller":"traceutil/trace.go:171","msg":"trace[752734534] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1910; }","duration":"355.532897ms","start":"2026-03-09T06:19:11.615418Z","end":"2026-03-09T06:19:11.970951Z","steps":["trace[752734534] 'agreement among raft nodes before linearized reading' (duration: 355.351142ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:19:11.971036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:19:11.615394Z","time spent":"355.610539ms","remote":"127.0.0.1:53688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-03-09T06:19:11.970919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.870406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T06:19:11.971179Z","caller":"traceutil/trace.go:171","msg":"trace[1261086269] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1910; }","duration":"258.175693ms","start":"2026-03-09T06:19:11.712962Z","end":"2026-03-09T06:19:11.971138Z","steps":["trace[1261086269] 'agreement among raft nodes before linearized reading' (duration: 257.905707ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:19:17.566825Z","caller":"traceutil/trace.go:171","msg":"trace[1924741065] transaction","detail":"{read_only:false; response_revision:1934; number_of_response:1; }","duration":"130.807512ms","start":"2026-03-09T06:19:17.435983Z","end":"2026-03-09T06:19:17.56679Z","steps":["trace[1924741065] 'process raft request' (duration: 130.671ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:19:17.585462Z","caller":"traceutil/trace.go:171","msg":"trace[1030987823] transaction","detail":"{read_only:false; response_revision:1935; number_of_response:1; }","duration":"149.257333ms","start":"2026-03-09T06:19:17.436178Z","end":"2026-03-09T06:19:17.585435Z","steps":["trace[1030987823] 'process raft request' (duration: 149.09789ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:20:14.527228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.31908ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-09T06:20:14.52732Z","caller":"traceutil/trace.go:171","msg":"trace[535334792] linearizableReadLoop","detail":"{readStateIndex:2482; appliedIndex:2481; }","duration":"253.836538ms","start":"2026-03-09T06:20:14.273471Z","end":"2026-03-09T06:20:14.527308Z","steps":["trace[535334792] 'read index received' (duration: 120.281504ms)","trace[535334792] 'applied index is now lower than readState.Index' (duration: 133.554134ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:20:14.527394Z","caller":"traceutil/trace.go:171","msg":"trace[658149797] transaction","detail":"{read_only:false; response_revision:2383; number_of_response:1; }","duration":"255.359087ms","start":"2026-03-09T06:20:14.271991Z","end":"2026-03-09T06:20:14.52735Z","steps":["trace[658149797] 'process raft request' (duration: 121.852144ms)","trace[658149797] 'compare' (duration: 133.221288ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T06:20:14.527484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.094823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-03-09T06:20:14.527504Z","caller":"traceutil/trace.go:171","msg":"trace[1401035649] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2383; }","duration":"215.163274ms","start":"2026-03-09T06:20:14.312334Z","end":"2026-03-09T06:20:14.527497Z","steps":["trace[1401035649] 'agreement among raft nodes before linearized reading' (duration: 215.058181ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:20:14.527587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.096374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-09T06:20:14.527695Z","caller":"traceutil/trace.go:171","msg":"trace[2080164106] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2383; }","duration":"254.216886ms","start":"2026-03-09T06:20:14.273452Z","end":"2026-03-09T06:20:14.527669Z","steps":["trace[2080164106] 'agreement among raft nodes before linearized reading' (duration: 253.9393ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:20:48.14944Z","caller":"traceutil/trace.go:171","msg":"trace[1666558643] transaction","detail":"{read_only:false; response_revision:2568; number_of_response:1; }","duration":"115.165452ms","start":"2026-03-09T06:20:48.034246Z","end":"2026-03-09T06:20:48.149411Z","steps":["trace[1666558643] 'process raft request' (duration: 115.01115ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:20:48.398097Z","caller":"traceutil/trace.go:171","msg":"trace[2124087789] transaction","detail":"{read_only:false; response_revision:2569; number_of_response:1; }","duration":"363.613583ms","start":"2026-03-09T06:20:48.034446Z","end":"2026-03-09T06:20:48.398059Z","steps":["trace[2124087789] 'process raft request' (duration: 363.138945ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:20:48.398272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:20:48.034421Z","time spent":"363.753246ms","remote":"127.0.0.1:52272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-09T06:24:36.154196Z","caller":"traceutil/trace.go:171","msg":"trace[1885400527] linearizableReadLoop","detail":"{readStateIndex:4661; appliedIndex:4659; }","duration":"248.036857ms","start":"2026-03-09T06:24:35.906123Z","end":"2026-03-09T06:24:36.15416Z","steps":["trace[1885400527] 'read index received' (duration: 34.569362ms)","trace[1885400527] 'applied index is now lower than readState.Index' (duration: 213.465185ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:24:36.154285Z","caller":"traceutil/trace.go:171","msg":"trace[847221707] transaction","detail":"{read_only:false; response_revision:4477; number_of_response:1; }","duration":"262.760801ms","start":"2026-03-09T06:24:35.891463Z","end":"2026-03-09T06:24:36.154224Z","steps":["trace[847221707] 'process raft request' (duration: 212.06527ms)","trace[847221707] 'compare' (duration: 50.425806ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T06:24:36.154521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.362673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12209"} {"level":"info","ts":"2026-03-09T06:24:36.154577Z","caller":"traceutil/trace.go:171","msg":"trace[1734193354] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4477; }","duration":"248.478655ms","start":"2026-03-09T06:24:35.906086Z","end":"2026-03-09T06:24:36.154564Z","steps":["trace[1734193354] 'agreement among raft nodes before linearized reading' (duration: 248.253281ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:24:36.166885Z","caller":"traceutil/trace.go:171","msg":"trace[67439959] transaction","detail":"{read_only:false; response_revision:4478; number_of_response:1; }","duration":"221.716268ms","start":"2026-03-09T06:24:35.945152Z","end":"2026-03-09T06:24:36.166868Z","steps":["trace[67439959] 'process raft request' (duration: 221.571845ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:24:36.166959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.682722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:11454"} {"level":"info","ts":"2026-03-09T06:24:36.167022Z","caller":"traceutil/trace.go:171","msg":"trace[1292400258] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4478; }","duration":"218.798363ms","start":"2026-03-09T06:24:35.948208Z","end":"2026-03-09T06:24:36.167006Z","steps":["trace[1292400258] 'agreement among raft nodes before linearized reading' (duration: 218.64119ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:24:36.167045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.50278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-03-09T06:24:36.167116Z","caller":"traceutil/trace.go:171","msg":"trace[1651619587] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4478; }","duration":"181.636033ms","start":"2026-03-09T06:24:35.98546Z","end":"2026-03-09T06:24:36.167096Z","steps":["trace[1651619587] 'agreement among raft nodes before linearized reading' (duration: 181.379898ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:24:38.745896Z","caller":"traceutil/trace.go:171","msg":"trace[252710667] transaction","detail":"{read_only:false; response_revision:4608; number_of_response:1; }","duration":"116.959402ms","start":"2026-03-09T06:24:38.628921Z","end":"2026-03-09T06:24:38.74588Z","steps":["trace[252710667] 'process raft request' (duration: 116.84943ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:24:38.858523Z","caller":"traceutil/trace.go:171","msg":"trace[2004218005] linearizableReadLoop","detail":"{readStateIndex:4796; appliedIndex:4795; }","duration":"103.269497ms","start":"2026-03-09T06:24:38.75523Z","end":"2026-03-09T06:24:38.8585Z","steps":["trace[2004218005] 'read index received' (duration: 103.183116ms)","trace[2004218005] 'applied index is now lower than readState.Index' (duration: 85.541µs)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:24:38.858553Z","caller":"traceutil/trace.go:171","msg":"trace[1113905599] transaction","detail":"{read_only:false; response_revision:4609; number_of_response:1; }","duration":"224.018291ms","start":"2026-03-09T06:24:38.634512Z","end":"2026-03-09T06:24:38.858531Z","steps":["trace[1113905599] 'process raft request' (duration: 223.852847ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:24:38.859518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.272477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:719273"} {"level":"info","ts":"2026-03-09T06:24:38.859602Z","caller":"traceutil/trace.go:171","msg":"trace[190382221] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:4609; }","duration":"104.383958ms","start":"2026-03-09T06:24:38.755184Z","end":"2026-03-09T06:24:38.859568Z","steps":["trace[190382221] 'agreement among raft nodes before linearized reading' (duration: 103.411611ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:24:50.060122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.233295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-03-09T06:24:50.060196Z","caller":"traceutil/trace.go:171","msg":"trace[122881013] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4834; }","duration":"112.379578ms","start":"2026-03-09T06:24:49.947803Z","end":"2026-03-09T06:24:50.060182Z","steps":["trace[122881013] 'range keys from in-memory index tree' (duration: 111.94107ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:24:57.644209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.016208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/prometheus-operated\" ","response":"range_response_count:1 size:993"} {"level":"info","ts":"2026-03-09T06:24:57.644301Z","caller":"traceutil/trace.go:171","msg":"trace[1525307394] range","detail":"{range_begin:/registry/services/specs/monitoring/prometheus-operated; range_end:; response_count:1; response_revision:4995; }","duration":"121.13725ms","start":"2026-03-09T06:24:57.523142Z","end":"2026-03-09T06:24:57.644279Z","steps":["trace[1525307394] 'range keys from in-memory index tree' (duration: 120.888655ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:24:57.859151Z","caller":"traceutil/trace.go:171","msg":"trace[714686238] linearizableReadLoop","detail":"{readStateIndex:5195; appliedIndex:5194; }","duration":"182.208423ms","start":"2026-03-09T06:24:57.676913Z","end":"2026-03-09T06:24:57.859121Z","steps":["trace[714686238] 'read index received' (duration: 170.14167ms)","trace[714686238] 'applied index is now lower than readState.Index' (duration: 12.065542ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:24:57.85926Z","caller":"traceutil/trace.go:171","msg":"trace[680995958] transaction","detail":"{read_only:false; response_revision:4997; number_of_response:1; }","duration":"182.791063ms","start":"2026-03-09T06:24:57.676439Z","end":"2026-03-09T06:24:57.85923Z","steps":["trace[680995958] 'process raft request' (duration: 170.65885ms)","trace[680995958] 'compare' (duration: 11.893009ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T06:24:57.859414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.522327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/monitoring/kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:7613"} {"level":"info","ts":"2026-03-09T06:24:57.859459Z","caller":"traceutil/trace.go:171","msg":"trace[1342553559] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/monitoring/kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4997; }","duration":"177.607328ms","start":"2026-03-09T06:24:57.68184Z","end":"2026-03-09T06:24:57.859447Z","steps":["trace[1342553559] 'agreement among raft nodes before linearized reading' (duration: 177.433335ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:24:57.859927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.951927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:9 size:80208"} {"level":"info","ts":"2026-03-09T06:24:57.860009Z","caller":"traceutil/trace.go:171","msg":"trace[874949695] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:9; response_revision:4997; }","duration":"183.10501ms","start":"2026-03-09T06:24:57.676886Z","end":"2026-03-09T06:24:57.859991Z","steps":["trace[874949695] 'agreement among raft nodes before linearized reading' (duration: 182.359856ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:24:58.167724Z","caller":"traceutil/trace.go:171","msg":"trace[242608797] transaction","detail":"{read_only:false; response_revision:4998; number_of_response:1; }","duration":"305.947379ms","start":"2026-03-09T06:24:57.861751Z","end":"2026-03-09T06:24:58.167698Z","steps":["trace[242608797] 'process raft request' (duration: 236.885199ms)","trace[242608797] 'compare' (duration: 68.952489ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T06:24:58.167873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:24:57.861728Z","time spent":"306.068632ms","remote":"127.0.0.1:52090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":997,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-09T06:24:58.39902Z","caller":"traceutil/trace.go:171","msg":"trace[1455776829] linearizableReadLoop","detail":"{readStateIndex:5197; appliedIndex:5195; }","duration":"510.392343ms","start":"2026-03-09T06:24:57.888599Z","end":"2026-03-09T06:24:58.398992Z","steps":["trace[1455776829] 'read index received' (duration: 210.128207ms)","trace[1455776829] 'applied index is now lower than readState.Index' (duration: 300.263136ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:24:58.399074Z","caller":"traceutil/trace.go:171","msg":"trace[759871503] transaction","detail":"{read_only:false; response_revision:4999; number_of_response:1; }","duration":"529.77033ms","start":"2026-03-09T06:24:57.869279Z","end":"2026-03-09T06:24:58.399049Z","steps":["trace[759871503] 'process raft request' (duration: 471.06622ms)","trace[759871503] 'compare' (duration: 58.500666ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T06:24:58.399288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.720798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-09T06:24:58.399354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.906393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-09T06:24:58.399373Z","caller":"traceutil/trace.go:171","msg":"trace[2111573036] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:4999; }","duration":"316.86457ms","start":"2026-03-09T06:24:58.082495Z","end":"2026-03-09T06:24:58.399359Z","steps":["trace[2111573036] 'agreement among raft nodes before linearized reading' (duration: 316.724098ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:24:58.399386Z","caller":"traceutil/trace.go:171","msg":"trace[1998940238] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4999; }","duration":"215.999405ms","start":"2026-03-09T06:24:58.183377Z","end":"2026-03-09T06:24:58.399376Z","steps":["trace[1998940238] 'agreement among raft nodes before linearized reading' (duration: 215.843642ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:24:58.399355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.88113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-09T06:24:58.399415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:24:58.082474Z","time spent":"316.930222ms","remote":"127.0.0.1:35910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"info","ts":"2026-03-09T06:24:58.399432Z","caller":"traceutil/trace.go:171","msg":"trace[1960325542] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4999; }","duration":"242.991612ms","start":"2026-03-09T06:24:58.156423Z","end":"2026-03-09T06:24:58.399415Z","steps":["trace[1960325542] 'agreement among raft nodes before linearized reading' (duration: 242.830749ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:24:58.399689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.263361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-03-09T06:24:58.399717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:24:57.869259Z","time spent":"529.866151ms","remote":"127.0.0.1:52194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3750,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-09T06:24:58.399765Z","caller":"traceutil/trace.go:171","msg":"trace[1911096621] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4999; }","duration":"103.386132ms","start":"2026-03-09T06:24:58.296363Z","end":"2026-03-09T06:24:58.399749Z","steps":["trace[1911096621] 'agreement among raft nodes before linearized reading' (duration: 103.016076ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:24:58.40058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.970611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2210796"} {"level":"info","ts":"2026-03-09T06:24:58.400924Z","caller":"traceutil/trace.go:171","msg":"trace[285284483] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:4999; }","duration":"512.317998ms","start":"2026-03-09T06:24:57.888583Z","end":"2026-03-09T06:24:58.400901Z","steps":["trace[285284483] 'agreement among raft nodes before linearized reading' (duration: 510.599366ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:24:58.400985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:24:57.888513Z","time spent":"512.456381ms","remote":"127.0.0.1:52112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":57,"response size":2210819,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"info","ts":"2026-03-09T06:24:58.659291Z","caller":"traceutil/trace.go:171","msg":"trace[92265819] transaction","detail":"{read_only:false; response_revision:5001; number_of_response:1; }","duration":"254.354431ms","start":"2026-03-09T06:24:58.404897Z","end":"2026-03-09T06:24:58.659251Z","steps":["trace[92265819] 'process raft request' (duration: 158.6662ms)","trace[92265819] 'compare' (duration: 95.176541ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:24:58.659504Z","caller":"traceutil/trace.go:171","msg":"trace[1460273643] linearizableReadLoop","detail":"{readStateIndex:5200; appliedIndex:5197; }","duration":"253.547625ms","start":"2026-03-09T06:24:58.405931Z","end":"2026-03-09T06:24:58.659479Z","steps":["trace[1460273643] 'read index received' (duration: 58.155579ms)","trace[1460273643] 'applied index is now lower than readState.Index' (duration: 195.390796ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T06:24:58.659821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.684524ms","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-09T06:24:58.659871Z","caller":"traceutil/trace.go:171","msg":"trace[583085136] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5002; }","duration":"231.777576ms","start":"2026-03-09T06:24:58.428082Z","end":"2026-03-09T06:24:58.65986Z","steps":["trace[583085136] 'agreement among raft nodes before linearized reading' (duration: 231.600333ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:24:58.660003Z","caller":"traceutil/trace.go:171","msg":"trace[2083608553] transaction","detail":"{read_only:false; response_revision:5002; number_of_response:1; }","duration":"255.038453ms","start":"2026-03-09T06:24:58.404956Z","end":"2026-03-09T06:24:58.659994Z","steps":["trace[2083608553] 'process raft request' (duration: 253.939523ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:24:58.6601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.995571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-09T06:24:58.660138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.189297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a7314753-121a-4579-bcba-7036e437083d\" ","response":"range_response_count:1 size:3765"} {"level":"info","ts":"2026-03-09T06:24:58.660165Z","caller":"traceutil/trace.go:171","msg":"trace[763229937] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a7314753-121a-4579-bcba-7036e437083d; range_end:; response_count:1; response_revision:5002; }","duration":"254.256388ms","start":"2026-03-09T06:24:58.405901Z","end":"2026-03-09T06:24:58.660157Z","steps":["trace[763229937] 'agreement among raft nodes before linearized reading' (duration: 254.177487ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:24:58.660144Z","caller":"traceutil/trace.go:171","msg":"trace[1421562875] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:5002; }","duration":"112.080783ms","start":"2026-03-09T06:24:58.548049Z","end":"2026-03-09T06:24:58.660129Z","steps":["trace[1421562875] 'agreement among raft nodes before linearized reading' (duration: 111.999671ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:25:00.003525Z","caller":"traceutil/trace.go:171","msg":"trace[1075171261] transaction","detail":"{read_only:false; response_revision:5020; number_of_response:1; }","duration":"159.123169ms","start":"2026-03-09T06:24:59.844372Z","end":"2026-03-09T06:25:00.003495Z","steps":["trace[1075171261] 'process raft request' (duration: 106.541641ms)","trace[1075171261] 'compare' (duration: 52.381023ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:25:00.003637Z","caller":"traceutil/trace.go:171","msg":"trace[1456761093] linearizableReadLoop","detail":"{readStateIndex:5219; appliedIndex:5217; }","duration":"139.800343ms","start":"2026-03-09T06:24:59.863803Z","end":"2026-03-09T06:25:00.003603Z","steps":["trace[1456761093] 'read index received' (duration: 87.052072ms)","trace[1456761093] 'applied index is now lower than readState.Index' (duration: 52.746571ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:25:00.003673Z","caller":"traceutil/trace.go:171","msg":"trace[388206995] transaction","detail":"{read_only:false; response_revision:5021; number_of_response:1; }","duration":"159.21544ms","start":"2026-03-09T06:24:59.844426Z","end":"2026-03-09T06:25:00.003641Z","steps":["trace[388206995] 'process raft request' (duration: 159.021387ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:25:00.003843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.025697ms","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-09T06:25:00.003906Z","caller":"traceutil/trace.go:171","msg":"trace[1302834953] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5021; }","duration":"140.116939ms","start":"2026-03-09T06:24:59.86376Z","end":"2026-03-09T06:25:00.003877Z","steps":["trace[1302834953] 'agreement among raft nodes before linearized reading' (duration: 139.959816ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:25:00.00413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.155804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-t5rzg\" ","response":"range_response_count:1 size:6527"} {"level":"warn","ts":"2026-03-09T06:25:00.004145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.965553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-03-09T06:25:00.004232Z","caller":"traceutil/trace.go:171","msg":"trace[254222513] range","detail":"{range_begin:/registry/pods/openstack/keepalived-t5rzg; range_end:; response_count:1; response_revision:5021; }","duration":"137.312327ms","start":"2026-03-09T06:24:59.866897Z","end":"2026-03-09T06:25:00.004209Z","steps":["trace[254222513] 'agreement among raft nodes before linearized reading' (duration: 136.977331ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:25:00.004243Z","caller":"traceutil/trace.go:171","msg":"trace[2129752154] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5021; }","duration":"110.111156ms","start":"2026-03-09T06:24:59.894109Z","end":"2026-03-09T06:25:00.00422Z","steps":["trace[2129752154] 'agreement among raft nodes before linearized reading' (duration: 109.818161ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:26:33.132281Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2796} {"level":"info","ts":"2026-03-09T06:26:33.195685Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2796,"took":"60.646876ms","hash":1488339696,"current-db-size-bytes":41664512,"current-db-size":"42 MB","current-db-size-in-use-bytes":31260672,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-03-09T06:26:33.195752Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1488339696,"revision":2796,"compact-revision":-1} {"level":"info","ts":"2026-03-09T06:31:06.087849Z","caller":"traceutil/trace.go:171","msg":"trace[989588090] transaction","detail":"{read_only:false; response_revision:7658; number_of_response:1; }","duration":"205.194095ms","start":"2026-03-09T06:31:05.882628Z","end":"2026-03-09T06:31:06.087822Z","steps":["trace[989588090] 'process raft request' (duration: 205.026652ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:31:16.673933Z","caller":"traceutil/trace.go:171","msg":"trace[186975308] transaction","detail":"{read_only:false; response_revision:7710; number_of_response:1; }","duration":"140.27048ms","start":"2026-03-09T06:31:16.533634Z","end":"2026-03-09T06:31:16.673904Z","steps":["trace[186975308] 'process raft request' (duration: 126.441465ms)","trace[186975308] 'compare' (duration: 13.688933ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:31:17.580105Z","caller":"traceutil/trace.go:171","msg":"trace[1022845589] transaction","detail":"{read_only:false; response_revision:7715; number_of_response:1; }","duration":"246.97032ms","start":"2026-03-09T06:31:17.333103Z","end":"2026-03-09T06:31:17.580073Z","steps":["trace[1022845589] 'process raft request' (duration: 246.761107ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:31:18.82038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.415072ms","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-09T06:31:18.820407Z","caller":"traceutil/trace.go:171","msg":"trace[1903046225] transaction","detail":"{read_only:false; response_revision:7719; number_of_response:1; }","duration":"122.681576ms","start":"2026-03-09T06:31:18.697702Z","end":"2026-03-09T06:31:18.820384Z","steps":["trace[1903046225] 'process raft request' (duration: 122.597503ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:31:18.820403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.395159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-03-09T06:31:18.820477Z","caller":"traceutil/trace.go:171","msg":"trace[1550812852] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7717; }","duration":"141.561206ms","start":"2026-03-09T06:31:18.678895Z","end":"2026-03-09T06:31:18.820456Z","steps":["trace[1550812852] 'range keys from in-memory index tree' (duration: 141.246228ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:31:18.82049Z","caller":"traceutil/trace.go:171","msg":"trace[401755687] transaction","detail":"{read_only:false; response_revision:7718; number_of_response:1; }","duration":"122.757107ms","start":"2026-03-09T06:31:18.697683Z","end":"2026-03-09T06:31:18.82044Z","steps":["trace[401755687] 'process raft request' (duration: 48.428637ms)","trace[401755687] 'compare' (duration: 74.030743ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:31:18.820634Z","caller":"traceutil/trace.go:171","msg":"trace[1914763681] transaction","detail":"{read_only:false; response_revision:7720; number_of_response:1; }","duration":"118.918606ms","start":"2026-03-09T06:31:18.701691Z","end":"2026-03-09T06:31:18.82061Z","steps":["trace[1914763681] 'process raft request' (duration: 118.65771ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:31:18.820514Z","caller":"traceutil/trace.go:171","msg":"trace[6384676] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7717; }","duration":"300.569603ms","start":"2026-03-09T06:31:18.519919Z","end":"2026-03-09T06:31:18.820489Z","steps":["trace[6384676] 'range keys from in-memory index tree' (duration: 300.111814ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:31:18.820804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T06:31:18.519853Z","time spent":"300.895899ms","remote":"127.0.0.1:52272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-09T06:31:19.85872Z","caller":"traceutil/trace.go:171","msg":"trace[85729485] transaction","detail":"{read_only:false; response_revision:7723; number_of_response:1; }","duration":"269.12163ms","start":"2026-03-09T06:31:19.589574Z","end":"2026-03-09T06:31:19.858695Z","steps":["trace[85729485] 'process raft request' (duration: 268.981007ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:31:20.310804Z","caller":"traceutil/trace.go:171","msg":"trace[1131025541] linearizableReadLoop","detail":"{readStateIndex:8012; appliedIndex:8011; }","duration":"106.083948ms","start":"2026-03-09T06:31:20.204698Z","end":"2026-03-09T06:31:20.310782Z","steps":["trace[1131025541] 'read index received' (duration: 105.860203ms)","trace[1131025541] 'applied index is now lower than readState.Index' (duration: 222.734µs)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:31:20.310886Z","caller":"traceutil/trace.go:171","msg":"trace[1366282338] transaction","detail":"{read_only:false; response_revision:7724; number_of_response:1; }","duration":"110.900297ms","start":"2026-03-09T06:31:20.199948Z","end":"2026-03-09T06:31:20.310848Z","steps":["trace[1366282338] 'process raft request' (duration: 110.707733ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:31:20.310965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.24726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-t5rzg\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-03-09T06:31:20.311016Z","caller":"traceutil/trace.go:171","msg":"trace[628497891] range","detail":"{range_begin:/registry/pods/openstack/keepalived-t5rzg; range_end:; response_count:1; response_revision:7724; }","duration":"106.333321ms","start":"2026-03-09T06:31:20.204668Z","end":"2026-03-09T06:31:20.311001Z","steps":["trace[628497891] 'agreement among raft nodes before linearized reading' (duration: 106.201259ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:31:33.139838Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5802} {"level":"info","ts":"2026-03-09T06:31:33.232759Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5802,"took":"90.875955ms","hash":3033776058,"current-db-size-bytes":43438080,"current-db-size":"43 MB","current-db-size-in-use-bytes":22978560,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-03-09T06:31:33.232824Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3033776058,"revision":5802,"compact-revision":2796} {"level":"info","ts":"2026-03-09T06:31:43.642702Z","caller":"traceutil/trace.go:171","msg":"trace[1694167342] transaction","detail":"{read_only:false; response_revision:7892; number_of_response:1; }","duration":"118.905615ms","start":"2026-03-09T06:31:43.523767Z","end":"2026-03-09T06:31:43.642673Z","steps":["trace[1694167342] 'process raft request' (duration: 118.13108ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:31:46.740358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.281831ms","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-09T06:31:46.740474Z","caller":"traceutil/trace.go:171","msg":"trace[934550561] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7908; }","duration":"277.403225ms","start":"2026-03-09T06:31:46.463052Z","end":"2026-03-09T06:31:46.740456Z","steps":["trace[934550561] 'range keys from in-memory index tree' (duration: 277.085968ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:31:46.740675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.108063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-t5rzg\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-03-09T06:31:46.740701Z","caller":"traceutil/trace.go:171","msg":"trace[329151236] range","detail":"{range_begin:/registry/pods/openstack/keepalived-t5rzg; range_end:; response_count:1; response_revision:7908; }","duration":"267.200444ms","start":"2026-03-09T06:31:46.473493Z","end":"2026-03-09T06:31:46.740693Z","steps":["trace[329151236] 'range keys from in-memory index tree' (duration: 266.96186ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:31:46.741231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.454951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-03-09T06:31:46.741301Z","caller":"traceutil/trace.go:171","msg":"trace[1275923555] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7908; }","duration":"197.589724ms","start":"2026-03-09T06:31:46.543693Z","end":"2026-03-09T06:31:46.741283Z","steps":["trace[1275923555] 'range keys from in-memory index tree' (duration: 197.284249ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:31:46.934817Z","caller":"traceutil/trace.go:171","msg":"trace[1695094073] linearizableReadLoop","detail":"{readStateIndex:8206; appliedIndex:8206; }","duration":"186.044229ms","start":"2026-03-09T06:31:46.748747Z","end":"2026-03-09T06:31:46.934791Z","steps":["trace[1695094073] 'read index received' (duration: 186.034069ms)","trace[1695094073] 'applied index is now lower than readState.Index' (duration: 8.14µs)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:31:46.9349Z","caller":"traceutil/trace.go:171","msg":"trace[86026307] transaction","detail":"{read_only:false; response_revision:7909; number_of_response:1; }","duration":"189.33547ms","start":"2026-03-09T06:31:46.745456Z","end":"2026-03-09T06:31:46.934792Z","steps":["trace[86026307] 'process raft request' (duration: 189.224258ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:31:46.935139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.366446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228857"} {"level":"info","ts":"2026-03-09T06:31:46.935219Z","caller":"traceutil/trace.go:171","msg":"trace[1822620862] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:7909; }","duration":"186.463538ms","start":"2026-03-09T06:31:46.74874Z","end":"2026-03-09T06:31:46.935204Z","steps":["trace[1822620862] 'agreement among raft nodes before linearized reading' (duration: 186.07587ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:31:46.957728Z","caller":"traceutil/trace.go:171","msg":"trace[1714219330] transaction","detail":"{read_only:false; response_revision:7910; number_of_response:1; }","duration":"208.953065ms","start":"2026-03-09T06:31:46.74876Z","end":"2026-03-09T06:31:46.957713Z","steps":["trace[1714219330] 'process raft request' (duration: 208.789892ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:31:46.957799Z","caller":"traceutil/trace.go:171","msg":"trace[1593990070] transaction","detail":"{read_only:false; response_revision:7911; number_of_response:1; }","duration":"134.603696ms","start":"2026-03-09T06:31:46.823144Z","end":"2026-03-09T06:31:46.957747Z","steps":["trace[1593990070] 'process raft request' (duration: 134.502084ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:31:50.218756Z","caller":"traceutil/trace.go:171","msg":"trace[272262149] transaction","detail":"{read_only:false; response_revision:7925; number_of_response:1; }","duration":"214.674191ms","start":"2026-03-09T06:31:50.004059Z","end":"2026-03-09T06:31:50.218733Z","steps":["trace[272262149] 'process raft request' (duration: 214.554809ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:31:59.430912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.869796ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-09T06:31:59.431022Z","caller":"traceutil/trace.go:171","msg":"trace[2122401144] transaction","detail":"{read_only:false; response_revision:8013; number_of_response:1; }","duration":"168.005725ms","start":"2026-03-09T06:31:59.262997Z","end":"2026-03-09T06:31:59.431003Z","steps":["trace[2122401144] 'process raft request' (duration: 22.967356ms)","trace[2122401144] 'compare' (duration: 144.55264ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:31:59.437717Z","caller":"traceutil/trace.go:171","msg":"trace[278689682] linearizableReadLoop","detail":"{readStateIndex:8316; appliedIndex:8315; }","duration":"127.29185ms","start":"2026-03-09T06:31:59.310406Z","end":"2026-03-09T06:31:59.437698Z","steps":["trace[278689682] 'read index received' (duration: 127.181388ms)","trace[278689682] 'applied index is now lower than readState.Index' (duration: 109.212µs)"],"step_count":2} {"level":"warn","ts":"2026-03-09T06:31:59.437879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.452834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-09T06:31:59.437926Z","caller":"traceutil/trace.go:171","msg":"trace[1265793060] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:8014; }","duration":"127.545575ms","start":"2026-03-09T06:31:59.310369Z","end":"2026-03-09T06:31:59.437914Z","steps":["trace[1265793060] 'agreement among raft nodes before linearized reading' (duration: 127.435753ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:31:59.437774Z","caller":"traceutil/trace.go:171","msg":"trace[131895295] transaction","detail":"{read_only:false; response_revision:8014; number_of_response:1; }","duration":"167.450524ms","start":"2026-03-09T06:31:59.270274Z","end":"2026-03-09T06:31:59.437725Z","steps":["trace[131895295] 'process raft request' (duration: 167.298262ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:32:01.031149Z","caller":"traceutil/trace.go:171","msg":"trace[1484471153] transaction","detail":"{read_only:false; response_revision:8020; number_of_response:1; }","duration":"122.406499ms","start":"2026-03-09T06:32:00.908702Z","end":"2026-03-09T06:32:01.031109Z","steps":["trace[1484471153] 'process raft request' (duration: 122.125164ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:33:13.619359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.932086ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-09T06:33:13.619539Z","caller":"traceutil/trace.go:171","msg":"trace[1798646619] linearizableReadLoop","detail":"{readStateIndex:9034; appliedIndex:9031; }","duration":"186.243733ms","start":"2026-03-09T06:33:13.433275Z","end":"2026-03-09T06:33:13.619519Z","steps":["trace[1798646619] 'read index received' (duration: 40.361579ms)","trace[1798646619] 'applied index is now lower than readState.Index' (duration: 145.881034ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:33:13.619738Z","caller":"traceutil/trace.go:171","msg":"trace[638403066] transaction","detail":"{read_only:false; response_revision:8712; number_of_response:1; }","duration":"190.220067ms","start":"2026-03-09T06:33:13.429503Z","end":"2026-03-09T06:33:13.619723Z","steps":["trace[638403066] 'process raft request' (duration: 71.815852ms)","trace[638403066] 'compare' (duration: 117.659712ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T06:33:13.619855Z","caller":"traceutil/trace.go:171","msg":"trace[1227475799] transaction","detail":"{read_only:false; response_revision:8713; number_of_response:1; }","duration":"188.179179ms","start":"2026-03-09T06:33:13.431642Z","end":"2026-03-09T06:33:13.619821Z","steps":["trace[1227475799] 'process raft request' (duration: 187.822262ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:33:13.619915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.62003ms","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-09T06:33:13.619983Z","caller":"traceutil/trace.go:171","msg":"trace[1432056275] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8713; }","duration":"186.740652ms","start":"2026-03-09T06:33:13.433225Z","end":"2026-03-09T06:33:13.619966Z","steps":["trace[1432056275] 'agreement among raft nodes before linearized reading' (duration: 186.537059ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T06:33:13.619865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.649768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-09T06:33:13.620113Z","caller":"traceutil/trace.go:171","msg":"trace[297573644] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:8713; }","duration":"105.950005ms","start":"2026-03-09T06:33:13.514148Z","end":"2026-03-09T06:33:13.620098Z","steps":["trace[297573644] 'agreement among raft nodes before linearized reading' (duration: 105.661248ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:35:40.345923Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"6a9ba61e55fef5be","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-09T06:35:40.351487Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-03-09T06:35:40.351592Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-03-09T06:35:49.608386Z","caller":"traceutil/trace.go:171","msg":"trace[688909987] transaction","detail":"{read_only:false; response_revision:9769; number_of_response:1; }","duration":"119.580696ms","start":"2026-03-09T06:35:49.488782Z","end":"2026-03-09T06:35:49.608363Z","steps":["trace[688909987] 'process raft request' (duration: 119.416774ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T06:36:33.145771Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7795} {"level":"info","ts":"2026-03-09T06:36:33.213301Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7795,"took":"65.056089ms","hash":4268950366,"current-db-size-bytes":43438080,"current-db-size":"43 MB","current-db-size-in-use-bytes":26120192,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-03-09T06:36:33.213365Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4268950366,"revision":7795,"compact-revision":5802} {"level":"info","ts":"2026-03-09T06:41:33.154047Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10032} {"level":"info","ts":"2026-03-09T06:41:33.228082Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10032,"took":"72.685659ms","hash":2077621059,"current-db-size-bytes":43438080,"current-db-size":"43 MB","current-db-size-in-use-bytes":17678336,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-03-09T06:41:33.228131Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2077621059,"revision":10032,"compact-revision":7795}