{"level":"warn","ts":"2026-08-31T21:19:11.568301Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-31T21:19:11.568473Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.97: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.97:2380","--initial-cluster=instance=https://162.253.55.97:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.97:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.97: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-08-31T21:19:11.568608Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-31T21:19:11.568634Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.97:2380"]} {"level":"info","ts":"2026-08-31T21:19:11.568679Z","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-08-31T21:19:11.57003Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.97:2379"]} {"level":"info","ts":"2026-08-31T21:19:11.570196Z","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.97:2380"],"listen-peer-urls":["https://162.253.55.97:2380"],"advertise-client-urls":["https://162.253.55.97:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.97:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.97: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-08-31T21:19:11.574105Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.492341ms"} {"level":"info","ts":"2026-08-31T21:19:11.582047Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fc5e9ff41d4daf7c","cluster-id":"348ac899a3494d23"} {"level":"info","ts":"2026-08-31T21:19:11.582181Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc5e9ff41d4daf7c switched to configuration voters=()"} {"level":"info","ts":"2026-08-31T21:19:11.582224Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc5e9ff41d4daf7c became follower at term 0"} {"level":"info","ts":"2026-08-31T21:19:11.582271Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fc5e9ff41d4daf7c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-31T21:19:11.58229Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc5e9ff41d4daf7c became follower at term 1"} {"level":"info","ts":"2026-08-31T21:19:11.582348Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc5e9ff41d4daf7c switched to configuration voters=(18185148216181108604)"} {"level":"warn","ts":"2026-08-31T21:19:11.592113Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-31T21:19:11.595796Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-31T21:19:11.597974Z","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-08-31T21:19:11.601808Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fc5e9ff41d4daf7c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-31T21:19:11.601971Z","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-08-31T21:19:11.602144Z","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-08-31T21:19:11.602162Z","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-08-31T21:19:11.602278Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fc5e9ff41d4daf7c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-31T21:19:11.608159Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc5e9ff41d4daf7c switched to configuration voters=(18185148216181108604)"} {"level":"info","ts":"2026-08-31T21:19:11.609794Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"348ac899a3494d23","local-member-id":"fc5e9ff41d4daf7c","added-peer-id":"fc5e9ff41d4daf7c","added-peer-peer-urls":["https://162.253.55.97:2380"]} {"level":"info","ts":"2026-08-31T21:19:11.612349Z","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-08-31T21:19:11.612435Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.97:2380"} {"level":"info","ts":"2026-08-31T21:19:11.612716Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.97:2380"} {"level":"info","ts":"2026-08-31T21:19:11.614761Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-31T21:19:11.614703Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fc5e9ff41d4daf7c","initial-advertise-peer-urls":["https://162.253.55.97:2380"],"listen-peer-urls":["https://162.253.55.97:2380"],"advertise-client-urls":["https://162.253.55.97:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.97:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-31T21:19:11.982873Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc5e9ff41d4daf7c is starting a new election at term 1"} {"level":"info","ts":"2026-08-31T21:19:11.983048Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc5e9ff41d4daf7c became pre-candidate at term 1"} {"level":"info","ts":"2026-08-31T21:19:11.983094Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc5e9ff41d4daf7c received MsgPreVoteResp from fc5e9ff41d4daf7c at term 1"} {"level":"info","ts":"2026-08-31T21:19:11.983126Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc5e9ff41d4daf7c became candidate at term 2"} {"level":"info","ts":"2026-08-31T21:19:11.983143Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc5e9ff41d4daf7c received MsgVoteResp from fc5e9ff41d4daf7c at term 2"} {"level":"info","ts":"2026-08-31T21:19:11.98316Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fc5e9ff41d4daf7c became leader at term 2"} {"level":"info","ts":"2026-08-31T21:19:11.98318Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fc5e9ff41d4daf7c elected leader fc5e9ff41d4daf7c at term 2"} {"level":"info","ts":"2026-08-31T21:19:11.984326Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fc5e9ff41d4daf7c","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.97:2379]}","request-path":"/0/members/fc5e9ff41d4daf7c/attributes","cluster-id":"348ac899a3494d23","publish-timeout":"7s"} {"level":"info","ts":"2026-08-31T21:19:11.984378Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-31T21:19:11.984878Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-31T21:19:11.985023Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-31T21:19:11.988474Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"348ac899a3494d23","local-member-id":"fc5e9ff41d4daf7c","cluster-version":"3.5"} {"level":"info","ts":"2026-08-31T21:19:11.988533Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.97:2379"} {"level":"info","ts":"2026-08-31T21:19:11.98863Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-31T21:19:11.988674Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-31T21:19:11.988786Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-31T21:19:11.988803Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-31T21:19:11.990167Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-31T21:20:05.245796Z","caller":"traceutil/trace.go:171","msg":"trace[324160777] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"147.25103ms","start":"2026-08-31T21:20:05.098518Z","end":"2026-08-31T21:20:05.245769Z","steps":["trace[324160777] 'process raft request' (duration: 147.081866ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:20:10.47658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.604555ms","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-08-31T21:20:10.476734Z","caller":"traceutil/trace.go:171","msg":"trace[1396835672] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:663; }","duration":"160.837181ms","start":"2026-08-31T21:20:10.315872Z","end":"2026-08-31T21:20:10.47671Z","steps":["trace[1396835672] 'range keys from in-memory index tree' (duration: 160.277447ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:20:10.756479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.420697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-zbsl6\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-08-31T21:20:10.756643Z","caller":"traceutil/trace.go:171","msg":"trace[1075478044] range","detail":"{range_begin:/registry/pods/kube-system/cilium-zbsl6; range_end:; response_count:1; response_revision:664; }","duration":"149.675463ms","start":"2026-08-31T21:20:10.606939Z","end":"2026-08-31T21:20:10.756615Z","steps":["trace[1075478044] 'range keys from in-memory index tree' (duration: 149.193631ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:20:10.771646Z","caller":"traceutil/trace.go:171","msg":"trace[346428793] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"148.178364ms","start":"2026-08-31T21:20:10.623442Z","end":"2026-08-31T21:20:10.771621Z","steps":["trace[346428793] 'process raft request' (duration: 147.632971ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:20:11.13685Z","caller":"traceutil/trace.go:171","msg":"trace[1953530220] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"361.174952ms","start":"2026-08-31T21:20:10.77565Z","end":"2026-08-31T21:20:11.136825Z","steps":["trace[1953530220] 'process raft request' (duration: 360.989197ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:20:11.137603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T21:20:10.775605Z","time spent":"361.328366ms","remote":"127.0.0.1:49136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-31T21:20:11.35983Z","caller":"traceutil/trace.go:171","msg":"trace[1839982502] linearizableReadLoop","detail":"{readStateIndex:698; appliedIndex:696; }","duration":"463.079853ms","start":"2026-08-31T21:20:10.896713Z","end":"2026-08-31T21:20:11.359792Z","steps":["trace[1839982502] 'read index received' (duration: 239.82622ms)","trace[1839982502] 'applied index is now lower than readState.Index' (duration: 223.252432ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T21:20:11.360007Z","caller":"traceutil/trace.go:171","msg":"trace[529917353] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"515.600839ms","start":"2026-08-31T21:20:10.844347Z","end":"2026-08-31T21:20:11.359948Z","steps":["trace[529917353] 'process raft request' (duration: 514.153491ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:20:11.360082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.3475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T21:20:11.360121Z","caller":"traceutil/trace.go:171","msg":"trace[2079030069] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:667; }","duration":"463.462223ms","start":"2026-08-31T21:20:10.896646Z","end":"2026-08-31T21:20:11.360109Z","steps":["trace[2079030069] 'agreement among raft nodes before linearized reading' (duration: 463.274808ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:20:11.360158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T21:20:10.896621Z","time spent":"463.528614ms","remote":"127.0.0.1:48968","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-08-31T21:20:11.360216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T21:20:10.844316Z","time spent":"515.801404ms","remote":"127.0.0.1:49290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-31T21:20:56.562224Z","caller":"traceutil/trace.go:171","msg":"trace[1287312536] linearizableReadLoop","detail":"{readStateIndex:1276; appliedIndex:1275; }","duration":"216.261495ms","start":"2026-08-31T21:20:56.34593Z","end":"2026-08-31T21:20:56.562192Z","steps":["trace[1287312536] 'read index received' (duration: 195.464936ms)","trace[1287312536] 'applied index is now lower than readState.Index' (duration: 20.794879ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T21:20:56.562377Z","caller":"traceutil/trace.go:171","msg":"trace[116790666] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"297.212942ms","start":"2026-08-31T21:20:56.265119Z","end":"2026-08-31T21:20:56.562332Z","steps":["trace[116790666] 'process raft request' (duration: 276.302021ms)","trace[116790666] 'compare' (duration: 20.611854ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T21:20:56.562524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.571223ms","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-08-31T21:20:56.562628Z","caller":"traceutil/trace.go:171","msg":"trace[203726181] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1234; }","duration":"216.720807ms","start":"2026-08-31T21:20:56.345884Z","end":"2026-08-31T21:20:56.562604Z","steps":["trace[203726181] 'agreement among raft nodes before linearized reading' (duration: 216.48072ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:21:19.208655Z","caller":"traceutil/trace.go:171","msg":"trace[1492309608] transaction","detail":"{read_only:false; response_revision:1495; number_of_response:1; }","duration":"150.558582ms","start":"2026-08-31T21:21:19.058068Z","end":"2026-08-31T21:21:19.208626Z","steps":["trace[1492309608] 'process raft request' (duration: 150.390267ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:21:47.59036Z","caller":"traceutil/trace.go:171","msg":"trace[600571084] transaction","detail":"{read_only:false; response_revision:1926; number_of_response:1; }","duration":"335.914415ms","start":"2026-08-31T21:21:47.254415Z","end":"2026-08-31T21:21:47.59033Z","steps":["trace[600571084] 'process raft request' (duration: 335.70309ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:21:47.590572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T21:21:47.254398Z","time spent":"336.072409ms","remote":"127.0.0.1:49136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3731,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-31T21:21:47.597345Z","caller":"traceutil/trace.go:171","msg":"trace[1099189160] transaction","detail":"{read_only:false; response_revision:1927; number_of_response:1; }","duration":"329.622083ms","start":"2026-08-31T21:21:47.267694Z","end":"2026-08-31T21:21:47.597316Z","steps":["trace[1099189160] 'process raft request' (duration: 329.428067ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:21:47.597533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T21:21:47.267662Z","time spent":"329.773467ms","remote":"127.0.0.1:49214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-31T21:21:47.597533Z","caller":"traceutil/trace.go:171","msg":"trace[1083622268] transaction","detail":"{read_only:false; response_revision:1928; number_of_response:1; }","duration":"237.431593ms","start":"2026-08-31T21:21:47.360066Z","end":"2026-08-31T21:21:47.597497Z","steps":["trace[1083622268] 'process raft request' (duration: 237.208337ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:21:47.597529Z","caller":"traceutil/trace.go:171","msg":"trace[2140597834] linearizableReadLoop","detail":"{readStateIndex:2008; appliedIndex:2006; }","duration":"290.681754ms","start":"2026-08-31T21:21:47.306808Z","end":"2026-08-31T21:21:47.59749Z","steps":["trace[2140597834] 'read index received' (duration: 283.286112ms)","trace[2140597834] 'applied index is now lower than readState.Index' (duration: 7.391672ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T21:21:47.597924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.081994ms","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-08-31T21:21:47.598046Z","caller":"traceutil/trace.go:171","msg":"trace[450771561] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1928; }","duration":"291.259818ms","start":"2026-08-31T21:21:47.306749Z","end":"2026-08-31T21:21:47.598009Z","steps":["trace[450771561] 'agreement among raft nodes before linearized reading' (duration: 290.859148ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:21:47.598098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.878855ms","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-08-31T21:21:47.59818Z","caller":"traceutil/trace.go:171","msg":"trace[2024323097] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1928; }","duration":"223.022429ms","start":"2026-08-31T21:21:47.375134Z","end":"2026-08-31T21:21:47.598157Z","steps":["trace[2024323097] 'agreement among raft nodes before linearized reading' (duration: 222.66127ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:21:48.00782Z","caller":"traceutil/trace.go:171","msg":"trace[1649903432] transaction","detail":"{read_only:false; response_revision:1932; number_of_response:1; }","duration":"296.213057ms","start":"2026-08-31T21:21:47.711562Z","end":"2026-08-31T21:21:48.007775Z","steps":["trace[1649903432] 'process raft request' (duration: 224.17508ms)","trace[1649903432] 'compare' (duration: 71.708798ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T21:21:48.008133Z","caller":"traceutil/trace.go:171","msg":"trace[1463290916] transaction","detail":"{read_only:false; response_revision:1934; number_of_response:1; }","duration":"296.182617ms","start":"2026-08-31T21:21:47.71193Z","end":"2026-08-31T21:21:48.008112Z","steps":["trace[1463290916] 'process raft request' (duration: 296.023142ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:21:48.00818Z","caller":"traceutil/trace.go:171","msg":"trace[816708115] transaction","detail":"{read_only:false; response_revision:1933; number_of_response:1; }","duration":"296.377641ms","start":"2026-08-31T21:21:47.711788Z","end":"2026-08-31T21:21:48.008166Z","steps":["trace[816708115] 'process raft request' (duration: 295.907229ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:21:48.008199Z","caller":"traceutil/trace.go:171","msg":"trace[1921493981] linearizableReadLoop","detail":"{readStateIndex:2015; appliedIndex:2012; }","duration":"102.087937ms","start":"2026-08-31T21:21:47.906061Z","end":"2026-08-31T21:21:48.008149Z","steps":["trace[1921493981] 'read index received' (duration: 29.68562ms)","trace[1921493981] 'applied index is now lower than readState.Index' (duration: 72.397907ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T21:21:48.00843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.349833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-33a3a78c-d002-4896-8a9e-0a4d285377e7\" ","response":"range_response_count:1 size:3746"} {"level":"info","ts":"2026-08-31T21:21:48.008514Z","caller":"traceutil/trace.go:171","msg":"trace[1673000299] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-33a3a78c-d002-4896-8a9e-0a4d285377e7; range_end:; response_count:1; response_revision:1935; }","duration":"102.479736ms","start":"2026-08-31T21:21:47.906014Z","end":"2026-08-31T21:21:48.008494Z","steps":["trace[1673000299] 'agreement among raft nodes before linearized reading' (duration: 102.291182ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:22:00.445092Z","caller":"traceutil/trace.go:171","msg":"trace[838056231] transaction","detail":"{read_only:false; response_revision:2048; number_of_response:1; }","duration":"100.692847ms","start":"2026-08-31T21:22:00.344363Z","end":"2026-08-31T21:22:00.445056Z","steps":["trace[838056231] 'process raft request' (duration: 100.431131ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:22:00.905995Z","caller":"traceutil/trace.go:171","msg":"trace[1196489233] transaction","detail":"{read_only:false; response_revision:2049; number_of_response:1; }","duration":"410.764214ms","start":"2026-08-31T21:22:00.4952Z","end":"2026-08-31T21:22:00.905965Z","steps":["trace[1196489233] 'process raft request' (duration: 410.428175ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:22:00.905983Z","caller":"traceutil/trace.go:171","msg":"trace[867600774] linearizableReadLoop","detail":"{readStateIndex:2133; appliedIndex:2132; }","duration":"207.561214ms","start":"2026-08-31T21:22:00.698295Z","end":"2026-08-31T21:22:00.905856Z","steps":["trace[867600774] 'read index received' (duration: 207.39959ms)","trace[867600774] 'applied index is now lower than readState.Index' (duration: 159.964µs)"],"step_count":2} {"level":"warn","ts":"2026-08-31T21:22:00.906182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T21:22:00.495167Z","time spent":"410.934808ms","remote":"127.0.0.1:49214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-31T21:22:00.906564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.23859ms","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-08-31T21:22:00.906643Z","caller":"traceutil/trace.go:171","msg":"trace[1112091294] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2049; }","duration":"208.421666ms","start":"2026-08-31T21:22:00.698203Z","end":"2026-08-31T21:22:00.906625Z","steps":["trace[1112091294] 'agreement among raft nodes before linearized reading' (duration: 207.864321ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:22:00.917501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.35352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11981"} {"level":"info","ts":"2026-08-31T21:22:00.917588Z","caller":"traceutil/trace.go:171","msg":"trace[68380129] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2049; }","duration":"160.480244ms","start":"2026-08-31T21:22:00.757089Z","end":"2026-08-31T21:22:00.917569Z","steps":["trace[68380129] 'agreement among raft nodes before linearized reading' (duration: 160.217447ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:22:04.055782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.352684ms","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-08-31T21:22:04.055895Z","caller":"traceutil/trace.go:171","msg":"trace[1185764430] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2068; }","duration":"294.530519ms","start":"2026-08-31T21:22:03.761331Z","end":"2026-08-31T21:22:04.055862Z","steps":["trace[1185764430] 'range keys from in-memory index tree' (duration: 294.103047ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:22:04.055881Z","caller":"traceutil/trace.go:171","msg":"trace[1172445145] linearizableReadLoop","detail":"{readStateIndex:2154; appliedIndex:2153; }","duration":"123.512505ms","start":"2026-08-31T21:22:03.932305Z","end":"2026-08-31T21:22:04.055818Z","steps":["trace[1172445145] 'read index received' (duration: 121.491423ms)","trace[1172445145] 'applied index is now lower than readState.Index' (duration: 2.019172ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T21:22:04.055923Z","caller":"traceutil/trace.go:171","msg":"trace[328269188] transaction","detail":"{read_only:false; response_revision:2069; number_of_response:1; }","duration":"178.63813ms","start":"2026-08-31T21:22:03.877215Z","end":"2026-08-31T21:22:04.055853Z","steps":["trace[328269188] 'process raft request' (duration: 176.578808ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:22:04.056264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.915244ms","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-08-31T21:22:04.056333Z","caller":"traceutil/trace.go:171","msg":"trace[2131072721] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2069; }","duration":"124.034507ms","start":"2026-08-31T21:22:03.932277Z","end":"2026-08-31T21:22:04.056311Z","steps":["trace[2131072721] 'agreement among raft nodes before linearized reading' (duration: 123.696348ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:22:36.791178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.522439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T21:22:36.791303Z","caller":"traceutil/trace.go:171","msg":"trace[118126346] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2248; }","duration":"101.727094ms","start":"2026-08-31T21:22:36.689551Z","end":"2026-08-31T21:22:36.791279Z","steps":["trace[118126346] 'range keys from in-memory index tree' (duration: 101.347084ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:22:37.396604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.888938ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-31T21:22:37.396954Z","caller":"traceutil/trace.go:171","msg":"trace[148477260] linearizableReadLoop","detail":"{readStateIndex:2346; appliedIndex:2344; }","duration":"119.400348ms","start":"2026-08-31T21:22:37.277497Z","end":"2026-08-31T21:22:37.396897Z","steps":["trace[148477260] 'read index received' (duration: 16.823302ms)","trace[148477260] 'applied index is now lower than readState.Index' (duration: 102.575136ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T21:22:37.396962Z","caller":"traceutil/trace.go:171","msg":"trace[866290073] transaction","detail":"{read_only:false; response_revision:2253; number_of_response:1; }","duration":"190.841844ms","start":"2026-08-31T21:22:37.206091Z","end":"2026-08-31T21:22:37.396933Z","steps":["trace[866290073] 'process raft request' (duration: 190.670409ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:22:37.397053Z","caller":"traceutil/trace.go:171","msg":"trace[416963536] transaction","detail":"{read_only:false; response_revision:2252; number_of_response:1; }","duration":"225.228687ms","start":"2026-08-31T21:22:37.171773Z","end":"2026-08-31T21:22:37.397002Z","steps":["trace[416963536] 'process raft request' (duration: 122.58372ms)","trace[416963536] 'compare' (duration: 101.667302ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T21:22:37.397229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.698705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-08-31T21:22:37.397301Z","caller":"traceutil/trace.go:171","msg":"trace[96297927] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2253; }","duration":"119.804497ms","start":"2026-08-31T21:22:37.277481Z","end":"2026-08-31T21:22:37.397286Z","steps":["trace[96297927] 'agreement among raft nodes before linearized reading' (duration: 119.562872ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:22:43.561408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.282159ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-31T21:22:43.561685Z","caller":"traceutil/trace.go:171","msg":"trace[1077377438] transaction","detail":"{read_only:false; response_revision:2281; number_of_response:1; }","duration":"303.95017ms","start":"2026-08-31T21:22:43.257688Z","end":"2026-08-31T21:22:43.561638Z","steps":["trace[1077377438] 'process raft request' (duration: 155.339452ms)","trace[1077377438] 'compare' (duration: 148.147376ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T21:22:43.561753Z","caller":"traceutil/trace.go:171","msg":"trace[580596571] linearizableReadLoop","detail":"{readStateIndex:2376; appliedIndex:2374; }","duration":"140.432278ms","start":"2026-08-31T21:22:43.421268Z","end":"2026-08-31T21:22:43.5617Z","steps":["trace[580596571] 'read index received' (duration: 137.00975ms)","trace[580596571] 'applied index is now lower than readState.Index' (duration: 3.421248ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T21:22:43.561839Z","caller":"traceutil/trace.go:171","msg":"trace[1985573178] transaction","detail":"{read_only:false; response_revision:2282; number_of_response:1; }","duration":"303.987381ms","start":"2026-08-31T21:22:43.257804Z","end":"2026-08-31T21:22:43.561792Z","steps":["trace[1985573178] 'process raft request' (duration: 303.720924ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:22:43.561936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T21:22:43.257661Z","time spent":"304.130195ms","remote":"127.0.0.1:49214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-31T21:22:43.561984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T21:22:43.257771Z","time spent":"304.131564ms","remote":"127.0.0.1:49214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-31T21:22:43.562088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.781077ms","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-08-31T21:22:43.562211Z","caller":"traceutil/trace.go:171","msg":"trace[1657596947] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2282; }","duration":"140.946031ms","start":"2026-08-31T21:22:43.421231Z","end":"2026-08-31T21:22:43.562177Z","steps":["trace[1657596947] 'agreement among raft nodes before linearized reading' (duration: 140.627143ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:22:43.564376Z","caller":"traceutil/trace.go:171","msg":"trace[1412698856] transaction","detail":"{read_only:false; response_revision:2283; number_of_response:1; }","duration":"142.207665ms","start":"2026-08-31T21:22:43.422139Z","end":"2026-08-31T21:22:43.564347Z","steps":["trace[1412698856] 'process raft request' (duration: 142.008569ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:22:43.564408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.375212ms","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-08-31T21:22:43.564459Z","caller":"traceutil/trace.go:171","msg":"trace[1413945850] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2283; }","duration":"125.467554ms","start":"2026-08-31T21:22:43.438978Z","end":"2026-08-31T21:22:43.564446Z","steps":["trace[1413945850] 'agreement among raft nodes before linearized reading' (duration: 125.29682ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:22:43.564568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.240335ms","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-08-31T21:22:43.564626Z","caller":"traceutil/trace.go:171","msg":"trace[1627989388] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2283; }","duration":"121.318317ms","start":"2026-08-31T21:22:43.443291Z","end":"2026-08-31T21:22:43.56461Z","steps":["trace[1627989388] 'agreement among raft nodes before linearized reading' (duration: 121.092112ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:23:23.030558Z","caller":"traceutil/trace.go:171","msg":"trace[324230515] transaction","detail":"{read_only:false; response_revision:2677; number_of_response:1; }","duration":"186.685731ms","start":"2026-08-31T21:23:22.843838Z","end":"2026-08-31T21:23:23.030523Z","steps":["trace[324230515] 'process raft request' (duration: 186.474215ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:23:58.224714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.742708ms","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-08-31T21:23:58.224812Z","caller":"traceutil/trace.go:171","msg":"trace[765203252] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2883; }","duration":"196.881721ms","start":"2026-08-31T21:23:58.027908Z","end":"2026-08-31T21:23:58.22479Z","steps":["trace[765203252] 'range keys from in-memory index tree' (duration: 196.536503ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:23:58.224907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.198705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-08-31T21:23:58.224999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.029674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-31T21:23:58.225106Z","caller":"traceutil/trace.go:171","msg":"trace[807485215] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2883; }","duration":"240.224478ms","start":"2026-08-31T21:23:57.984858Z","end":"2026-08-31T21:23:58.225082Z","steps":["trace[807485215] 'range keys from in-memory index tree' (duration: 239.805128ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:23:58.225228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.924853ms","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-08-31T21:23:58.225374Z","caller":"traceutil/trace.go:171","msg":"trace[1541390877] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2883; }","duration":"208.228011ms","start":"2026-08-31T21:23:58.017122Z","end":"2026-08-31T21:23:58.22535Z","steps":["trace[1541390877] 'range keys from in-memory index tree' (duration: 207.540973ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:23:58.225303Z","caller":"traceutil/trace.go:171","msg":"trace[299852572] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2883; }","duration":"207.281026ms","start":"2026-08-31T21:23:58.017692Z","end":"2026-08-31T21:23:58.224973Z","steps":["trace[299852572] 'range keys from in-memory index tree' (duration: 206.978449ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:23:58.251457Z","caller":"traceutil/trace.go:171","msg":"trace[1502429213] transaction","detail":"{read_only:false; response_revision:2884; number_of_response:1; }","duration":"139.100344ms","start":"2026-08-31T21:23:58.112326Z","end":"2026-08-31T21:23:58.251427Z","steps":["trace[1502429213] 'process raft request' (duration: 138.91759ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:27:22.550741Z","caller":"traceutil/trace.go:171","msg":"trace[838535820] transaction","detail":"{read_only:false; response_revision:4156; number_of_response:1; }","duration":"152.825119ms","start":"2026-08-31T21:27:22.397887Z","end":"2026-08-31T21:27:22.550712Z","steps":["trace[838535820] 'process raft request' (duration: 152.512751ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:27:22.783918Z","caller":"traceutil/trace.go:171","msg":"trace[1017612253] transaction","detail":"{read_only:false; response_revision:4157; number_of_response:1; }","duration":"223.113293ms","start":"2026-08-31T21:27:22.560775Z","end":"2026-08-31T21:27:22.783888Z","steps":["trace[1017612253] 'process raft request' (duration: 161.505141ms)","trace[1017612253] 'compare' (duration: 61.433078ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T21:28:54.531652Z","caller":"traceutil/trace.go:171","msg":"trace[1091320760] transaction","detail":"{read_only:false; response_revision:5276; number_of_response:1; }","duration":"110.722046ms","start":"2026-08-31T21:28:54.420879Z","end":"2026-08-31T21:28:54.531601Z","steps":["trace[1091320760] 'process raft request' (duration: 104.016365ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:28:54.531814Z","caller":"traceutil/trace.go:171","msg":"trace[2013104416] transaction","detail":"{read_only:false; response_revision:5277; number_of_response:1; }","duration":"110.637213ms","start":"2026-08-31T21:28:54.421135Z","end":"2026-08-31T21:28:54.531773Z","steps":["trace[2013104416] 'process raft request' (duration: 110.334765ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:28:54.531862Z","caller":"traceutil/trace.go:171","msg":"trace[1863547546] transaction","detail":"{read_only:false; response_revision:5278; number_of_response:1; }","duration":"110.326355ms","start":"2026-08-31T21:28:54.42151Z","end":"2026-08-31T21:28:54.531836Z","steps":["trace[1863547546] 'process raft request' (duration: 110.12724ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:28:54.53193Z","caller":"traceutil/trace.go:171","msg":"trace[11767830] transaction","detail":"{read_only:false; response_revision:5280; number_of_response:1; }","duration":"108.701143ms","start":"2026-08-31T21:28:54.423199Z","end":"2026-08-31T21:28:54.531901Z","steps":["trace[11767830] 'process raft request' (duration: 108.527469ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:28:54.531912Z","caller":"traceutil/trace.go:171","msg":"trace[1092034427] linearizableReadLoop","detail":"{readStateIndex:5486; appliedIndex:5484; }","duration":"110.401677ms","start":"2026-08-31T21:28:54.421463Z","end":"2026-08-31T21:28:54.531864Z","steps":["trace[1092034427] 'read index received' (duration: 291.417µs)","trace[1092034427] 'applied index is now lower than readState.Index' (duration: 110.10393ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T21:28:54.531878Z","caller":"traceutil/trace.go:171","msg":"trace[354040475] transaction","detail":"{read_only:false; response_revision:5279; number_of_response:1; }","duration":"110.207192ms","start":"2026-08-31T21:28:54.421629Z","end":"2026-08-31T21:28:54.531836Z","steps":["trace[354040475] 'process raft request' (duration: 110.062739ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:28:54.53214Z","caller":"traceutil/trace.go:171","msg":"trace[729470771] transaction","detail":"{read_only:false; response_revision:5281; number_of_response:1; }","duration":"108.842968ms","start":"2026-08-31T21:28:54.423257Z","end":"2026-08-31T21:28:54.5321Z","steps":["trace[729470771] 'process raft request' (duration: 108.497378ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:28:54.532238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.742096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/loki-memcached-chunks-cache.18d1011916887d1f\" ","response":"range_response_count:1 size:722"} {"level":"info","ts":"2026-08-31T21:28:54.532342Z","caller":"traceutil/trace.go:171","msg":"trace[164805193] range","detail":"{range_begin:/registry/events/monitoring/loki-memcached-chunks-cache.18d1011916887d1f; range_end:; response_count:1; response_revision:5282; }","duration":"110.89779ms","start":"2026-08-31T21:28:54.421416Z","end":"2026-08-31T21:28:54.532314Z","steps":["trace[164805193] 'agreement among raft nodes before linearized reading' (duration: 110.607952ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:28:56.223089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.51472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-9dsnt\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-08-31T21:28:56.223171Z","caller":"traceutil/trace.go:171","msg":"trace[781637332] range","detail":"{range_begin:/registry/pods/openstack/keepalived-9dsnt; range_end:; response_count:1; response_revision:5365; }","duration":"206.630863ms","start":"2026-08-31T21:28:56.016521Z","end":"2026-08-31T21:28:56.223152Z","steps":["trace[781637332] 'range keys from in-memory index tree' (duration: 206.355836ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:28:56.223205Z","caller":"traceutil/trace.go:171","msg":"trace[716976931] transaction","detail":"{read_only:false; response_revision:5366; number_of_response:1; }","duration":"114.324278ms","start":"2026-08-31T21:28:56.108847Z","end":"2026-08-31T21:28:56.223172Z","steps":["trace[716976931] 'process raft request' (duration: 29.563034ms)","trace[716976931] 'compare' (duration: 84.566149ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T21:28:57.859103Z","caller":"traceutil/trace.go:171","msg":"trace[1356003845] transaction","detail":"{read_only:false; response_revision:5390; number_of_response:1; }","duration":"113.827685ms","start":"2026-08-31T21:28:57.745227Z","end":"2026-08-31T21:28:57.859055Z","steps":["trace[1356003845] 'process raft request' (duration: 113.553398ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:29:00.65942Z","caller":"traceutil/trace.go:171","msg":"trace[662562787] transaction","detail":"{read_only:false; response_revision:5447; number_of_response:1; }","duration":"104.040315ms","start":"2026-08-31T21:29:00.555352Z","end":"2026-08-31T21:29:00.659392Z","steps":["trace[662562787] 'process raft request' (duration: 103.881511ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:29:00.684242Z","caller":"traceutil/trace.go:171","msg":"trace[1943027313] linearizableReadLoop","detail":"{readStateIndex:5661; appliedIndex:5660; }","duration":"114.02976ms","start":"2026-08-31T21:29:00.570187Z","end":"2026-08-31T21:29:00.684217Z","steps":["trace[1943027313] 'read index received' (duration: 89.239158ms)","trace[1943027313] 'applied index is now lower than readState.Index' (duration: 24.789922ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T21:29:00.684272Z","caller":"traceutil/trace.go:171","msg":"trace[692701397] transaction","detail":"{read_only:false; response_revision:5448; number_of_response:1; }","duration":"128.585201ms","start":"2026-08-31T21:29:00.555667Z","end":"2026-08-31T21:29:00.684252Z","steps":["trace[692701397] 'process raft request' (duration: 128.372106ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:29:00.684716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.505653ms","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-08-31T21:29:00.684818Z","caller":"traceutil/trace.go:171","msg":"trace[1972183913] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5449; }","duration":"114.665907ms","start":"2026-08-31T21:29:00.570138Z","end":"2026-08-31T21:29:00.684804Z","steps":["trace[1972183913] 'agreement among raft nodes before linearized reading' (duration: 114.442701ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:29:00.81316Z","caller":"traceutil/trace.go:171","msg":"trace[704006419] linearizableReadLoop","detail":"{readStateIndex:5664; appliedIndex:5663; }","duration":"119.736965ms","start":"2026-08-31T21:29:00.693405Z","end":"2026-08-31T21:29:00.813142Z","steps":["trace[704006419] 'read index received' (duration: 9.177374ms)","trace[704006419] 'applied index is now lower than readState.Index' (duration: 110.558981ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T21:29:00.813272Z","caller":"traceutil/trace.go:171","msg":"trace[821372738] transaction","detail":"{read_only:false; response_revision:5451; number_of_response:1; }","duration":"119.974242ms","start":"2026-08-31T21:29:00.693286Z","end":"2026-08-31T21:29:00.81326Z","steps":["trace[821372738] 'process raft request' (duration: 99.581472ms)","trace[821372738] 'compare' (duration: 20.02785ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T21:29:00.813555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.123146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T21:29:00.8136Z","caller":"traceutil/trace.go:171","msg":"trace[14281942] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5451; }","duration":"120.208268ms","start":"2026-08-31T21:29:00.693381Z","end":"2026-08-31T21:29:00.813589Z","steps":["trace[14281942] 'agreement among raft nodes before linearized reading' (duration: 120.085865ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:29:00.813872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.000942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/loki-0\" ","response":"range_response_count:1 size:4702"} {"level":"info","ts":"2026-08-31T21:29:00.813998Z","caller":"traceutil/trace.go:171","msg":"trace[1321580748] range","detail":"{range_begin:/registry/pods/monitoring/loki-0; range_end:; response_count:1; response_revision:5451; }","duration":"120.160196ms","start":"2026-08-31T21:29:00.693815Z","end":"2026-08-31T21:29:00.813975Z","steps":["trace[1321580748] 'agreement among raft nodes before linearized reading' (duration: 119.866708ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:29:12.081877Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2960} {"level":"info","ts":"2026-08-31T21:29:12.168012Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2960,"took":"84.382403ms","hash":843967287,"current-db-size-bytes":56279040,"current-db-size":"56 MB","current-db-size-in-use-bytes":31637504,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-31T21:29:12.168115Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":843967287,"revision":2960,"compact-revision":-1} {"level":"info","ts":"2026-08-31T21:29:13.072129Z","caller":"traceutil/trace.go:171","msg":"trace[1338981742] linearizableReadLoop","detail":"{readStateIndex:5852; appliedIndex:5851; }","duration":"383.99894ms","start":"2026-08-31T21:29:12.688099Z","end":"2026-08-31T21:29:13.072098Z","steps":["trace[1338981742] 'read index received' (duration: 383.683972ms)","trace[1338981742] 'applied index is now lower than readState.Index' (duration: 313.318µs)"],"step_count":2} {"level":"info","ts":"2026-08-31T21:29:13.072243Z","caller":"traceutil/trace.go:171","msg":"trace[1103253199] transaction","detail":"{read_only:false; response_revision:5632; number_of_response:1; }","duration":"695.971251ms","start":"2026-08-31T21:29:12.37623Z","end":"2026-08-31T21:29:13.072201Z","steps":["trace[1103253199] 'process raft request' (duration: 695.565381ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:29:13.072377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.239976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T21:29:13.072466Z","caller":"traceutil/trace.go:171","msg":"trace[447486134] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5632; }","duration":"384.39616ms","start":"2026-08-31T21:29:12.688049Z","end":"2026-08-31T21:29:13.072445Z","steps":["trace[447486134] 'agreement among raft nodes before linearized reading' (duration: 384.164094ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:29:13.072489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T21:29:12.376209Z","time spent":"696.135457ms","remote":"127.0.0.1:49016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":130253,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-31T21:29:13.072503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T21:29:12.688029Z","time spent":"384.468252ms","remote":"127.0.0.1:48968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-31T21:29:13.084778Z","caller":"traceutil/trace.go:171","msg":"trace[366512325] transaction","detail":"{read_only:false; response_revision:5634; number_of_response:1; }","duration":"196.121175ms","start":"2026-08-31T21:29:12.888618Z","end":"2026-08-31T21:29:13.084739Z","steps":["trace[366512325] 'process raft request' (duration: 196.026803ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:29:13.08512Z","caller":"traceutil/trace.go:171","msg":"trace[814893707] transaction","detail":"{read_only:false; response_revision:5633; number_of_response:1; }","duration":"366.900434ms","start":"2026-08-31T21:29:12.718192Z","end":"2026-08-31T21:29:13.085093Z","steps":["trace[814893707] 'process raft request' (duration: 366.292969ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:29:13.08514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.00902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-31T21:29:13.08523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T21:29:12.718168Z","time spent":"366.979336ms","remote":"127.0.0.1:49214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-31T21:29:13.085312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.241403ms","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-08-31T21:29:13.085327Z","caller":"traceutil/trace.go:171","msg":"trace[1637301197] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:5634; }","duration":"203.159975ms","start":"2026-08-31T21:29:12.88206Z","end":"2026-08-31T21:29:13.08522Z","steps":["trace[1637301197] 'agreement among raft nodes before linearized reading' (duration: 202.945259ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:29:13.085373Z","caller":"traceutil/trace.go:171","msg":"trace[1341937071] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5634; }","duration":"311.395968ms","start":"2026-08-31T21:29:12.773958Z","end":"2026-08-31T21:29:13.085354Z","steps":["trace[1341937071] 'agreement among raft nodes before linearized reading' (duration: 310.932356ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:29:13.085489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T21:29:12.773937Z","time spent":"311.538561ms","remote":"127.0.0.1:49214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-31T21:29:13.091274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.312867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-6bf75\" ","response":"range_response_count:1 size:7817"} {"level":"info","ts":"2026-08-31T21:29:13.091334Z","caller":"traceutil/trace.go:171","msg":"trace[1790809845] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-6bf75; range_end:; response_count:1; response_revision:5634; }","duration":"219.41702ms","start":"2026-08-31T21:29:12.871899Z","end":"2026-08-31T21:29:13.091316Z","steps":["trace[1790809845] 'agreement among raft nodes before linearized reading' (duration: 212.851702ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:29:19.220308Z","caller":"traceutil/trace.go:171","msg":"trace[782855565] transaction","detail":"{read_only:false; response_revision:5714; number_of_response:1; }","duration":"105.097543ms","start":"2026-08-31T21:29:19.115169Z","end":"2026-08-31T21:29:19.220266Z","steps":["trace[782855565] 'process raft request' (duration: 104.823716ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:29:22.165457Z","caller":"traceutil/trace.go:171","msg":"trace[1217457460] linearizableReadLoop","detail":"{readStateIndex:6014; appliedIndex:6013; }","duration":"163.565316ms","start":"2026-08-31T21:29:22.001862Z","end":"2026-08-31T21:29:22.165428Z","steps":["trace[1217457460] 'read index received' (duration: 163.310108ms)","trace[1217457460] 'applied index is now lower than readState.Index' (duration: 254.088µs)"],"step_count":2} {"level":"info","ts":"2026-08-31T21:29:22.165795Z","caller":"traceutil/trace.go:171","msg":"trace[1250361352] transaction","detail":"{read_only:false; response_revision:5792; number_of_response:1; }","duration":"171.035015ms","start":"2026-08-31T21:29:21.994745Z","end":"2026-08-31T21:29:22.16578Z","steps":["trace[1250361352] 'process raft request' (duration: 170.513871ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:29:22.166112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.202981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T21:29:22.166164Z","caller":"traceutil/trace.go:171","msg":"trace[535158512] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:5792; }","duration":"164.303953ms","start":"2026-08-31T21:29:22.001845Z","end":"2026-08-31T21:29:22.166149Z","steps":["trace[535158512] 'agreement among raft nodes before linearized reading' (duration: 164.18314ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:29:22.166451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.301146ms","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-08-31T21:29:22.166494Z","caller":"traceutil/trace.go:171","msg":"trace[1443231730] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5792; }","duration":"101.396548ms","start":"2026-08-31T21:29:22.065087Z","end":"2026-08-31T21:29:22.166483Z","steps":["trace[1443231730] 'agreement among raft nodes before linearized reading' (duration: 101.248705ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:29:22.360625Z","caller":"traceutil/trace.go:171","msg":"trace[1482361727] transaction","detail":"{read_only:false; response_revision:5794; number_of_response:1; }","duration":"187.094566ms","start":"2026-08-31T21:29:22.173481Z","end":"2026-08-31T21:29:22.360576Z","steps":["trace[1482361727] 'process raft request' (duration: 148.296015ms)","trace[1482361727] 'compare' (duration: 38.095163ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T21:29:22.360867Z","caller":"traceutil/trace.go:171","msg":"trace[500447742] transaction","detail":"{read_only:false; response_revision:5796; number_of_response:1; }","duration":"187.009023ms","start":"2026-08-31T21:29:22.173839Z","end":"2026-08-31T21:29:22.360848Z","steps":["trace[500447742] 'process raft request' (duration: 186.665574ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:29:22.360888Z","caller":"traceutil/trace.go:171","msg":"trace[1030594128] transaction","detail":"{read_only:false; response_revision:5795; number_of_response:1; }","duration":"187.27437ms","start":"2026-08-31T21:29:22.173549Z","end":"2026-08-31T21:29:22.360824Z","steps":["trace[1030594128] 'process raft request' (duration: 186.768207ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:29:22.36077Z","caller":"traceutil/trace.go:171","msg":"trace[1682540720] transaction","detail":"{read_only:false; response_revision:5797; number_of_response:1; }","duration":"117.13068ms","start":"2026-08-31T21:29:22.243518Z","end":"2026-08-31T21:29:22.360649Z","steps":["trace[1682540720] 'process raft request' (duration: 117.060778ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:29:22.571969Z","caller":"traceutil/trace.go:171","msg":"trace[685259612] transaction","detail":"{read_only:false; response_revision:5799; number_of_response:1; }","duration":"157.635283ms","start":"2026-08-31T21:29:22.414313Z","end":"2026-08-31T21:29:22.571948Z","steps":["trace[685259612] 'process raft request' (duration: 157.463588ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:29:22.851911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.523165ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:1727"} {"level":"info","ts":"2026-08-31T21:29:22.852445Z","caller":"traceutil/trace.go:171","msg":"trace[1093026752] linearizableReadLoop","detail":"{readStateIndex:6023; appliedIndex:6020; }","duration":"343.329901ms","start":"2026-08-31T21:29:22.509093Z","end":"2026-08-31T21:29:22.852423Z","steps":["trace[1093026752] 'read index received' (duration: 62.71894ms)","trace[1093026752] 'applied index is now lower than readState.Index' (duration: 280.609711ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T21:29:22.85249Z","caller":"traceutil/trace.go:171","msg":"trace[1988564928] transaction","detail":"{read_only:false; response_revision:5800; number_of_response:1; }","duration":"365.866316ms","start":"2026-08-31T21:29:22.486603Z","end":"2026-08-31T21:29:22.852469Z","steps":["trace[1988564928] 'process raft request' (duration: 365.425605ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:29:22.852519Z","caller":"traceutil/trace.go:171","msg":"trace[384386660] transaction","detail":"{read_only:false; number_of_response:1; response_revision:5799; }","duration":"437.73634ms","start":"2026-08-31T21:29:22.414741Z","end":"2026-08-31T21:29:22.852478Z","steps":["trace[384386660] 'process raft request' (duration: 260.543909ms)","trace[384386660] 'compare' (duration: 176.432273ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T21:29:22.852581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.649613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T21:29:22.852612Z","caller":"traceutil/trace.go:171","msg":"trace[1942948961] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:5800; }","duration":"242.734284ms","start":"2026-08-31T21:29:22.609868Z","end":"2026-08-31T21:29:22.852602Z","steps":["trace[1942948961] 'agreement among raft nodes before linearized reading' (duration: 242.671683ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:29:22.852628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.501586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1986"} {"level":"warn","ts":"2026-08-31T21:29:22.852626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T21:29:22.486576Z","time spent":"365.96753ms","remote":"127.0.0.1:49034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":800,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-31T21:29:22.852723Z","caller":"traceutil/trace.go:171","msg":"trace[28000366] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:5800; }","duration":"343.63295ms","start":"2026-08-31T21:29:22.509066Z","end":"2026-08-31T21:29:22.852699Z","steps":["trace[28000366] 'agreement among raft nodes before linearized reading' (duration: 343.412133ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:29:22.852706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.534882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-31T21:29:22.852799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T21:29:22.509053Z","time spent":"343.735552ms","remote":"127.0.0.1:49140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":2009,"request content":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" "} {"level":"info","ts":"2026-08-31T21:29:22.852826Z","caller":"traceutil/trace.go:171","msg":"trace[829297930] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:5800; }","duration":"159.712806ms","start":"2026-08-31T21:29:22.693087Z","end":"2026-08-31T21:29:22.8528Z","steps":["trace[829297930] 'agreement among raft nodes before linearized reading' (duration: 159.502031ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:29:22.852837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.710773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-31T21:29:22.852919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T21:29:22.41472Z","time spent":"437.885355ms","remote":"127.0.0.1:48444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":103,"response count":0,"response size":1750,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-31T21:29:22.853034Z","caller":"traceutil/trace.go:171","msg":"trace[1104008062] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5800; }","duration":"162.948858ms","start":"2026-08-31T21:29:22.690057Z","end":"2026-08-31T21:29:22.853006Z","steps":["trace[1104008062] 'agreement among raft nodes before linearized reading' (duration: 162.565059ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:34:12.089129Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5628} {"level":"info","ts":"2026-08-31T21:34:12.180534Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5628,"took":"89.061067ms","hash":1338802555,"current-db-size-bytes":56279040,"current-db-size":"56 MB","current-db-size-in-use-bytes":28622848,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-08-31T21:34:12.180616Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1338802555,"revision":5628,"compact-revision":2960} {"level":"info","ts":"2026-08-31T21:35:50.747968Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000237a.wal"} {"level":"info","ts":"2026-08-31T21:36:03.683025Z","caller":"traceutil/trace.go:171","msg":"trace[1653793070] transaction","detail":"{read_only:false; response_revision:8828; number_of_response:1; }","duration":"260.609292ms","start":"2026-08-31T21:36:03.422383Z","end":"2026-08-31T21:36:03.682992Z","steps":["trace[1653793070] 'process raft request' (duration: 260.419407ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:36:03.691762Z","caller":"traceutil/trace.go:171","msg":"trace[825788492] transaction","detail":"{read_only:false; response_revision:8829; number_of_response:1; }","duration":"180.919244ms","start":"2026-08-31T21:36:03.510808Z","end":"2026-08-31T21:36:03.691728Z","steps":["trace[825788492] 'process raft request' (duration: 180.552084ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:36:31.103514Z","caller":"traceutil/trace.go:171","msg":"trace[867753536] transaction","detail":"{read_only:false; response_revision:9026; number_of_response:1; }","duration":"117.987492ms","start":"2026-08-31T21:36:30.985492Z","end":"2026-08-31T21:36:31.103479Z","steps":["trace[867753536] 'process raft request' (duration: 106.8547ms)","trace[867753536] 'compare' (duration: 10.851905ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T21:36:34.132844Z","caller":"traceutil/trace.go:171","msg":"trace[220064049] transaction","detail":"{read_only:false; response_revision:9046; number_of_response:1; }","duration":"141.093851ms","start":"2026-08-31T21:36:33.991722Z","end":"2026-08-31T21:36:34.132816Z","steps":["trace[220064049] 'process raft request' (duration: 140.947897ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:36:37.834962Z","caller":"traceutil/trace.go:171","msg":"trace[96674066] linearizableReadLoop","detail":"{readStateIndex:9398; appliedIndex:9397; }","duration":"145.651846ms","start":"2026-08-31T21:36:37.689276Z","end":"2026-08-31T21:36:37.834928Z","steps":["trace[96674066] 'read index received' (duration: 145.44778ms)","trace[96674066] 'applied index is now lower than readState.Index' (duration: 202.276µs)"],"step_count":2} {"level":"warn","ts":"2026-08-31T21:36:37.835124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.826911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T21:36:37.835161Z","caller":"traceutil/trace.go:171","msg":"trace[927455367] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9066; }","duration":"145.949944ms","start":"2026-08-31T21:36:37.689198Z","end":"2026-08-31T21:36:37.835148Z","steps":["trace[927455367] 'agreement among raft nodes before linearized reading' (duration: 145.860411ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:36:37.835147Z","caller":"traceutil/trace.go:171","msg":"trace[331037542] transaction","detail":"{read_only:false; response_revision:9066; number_of_response:1; }","duration":"150.809169ms","start":"2026-08-31T21:36:37.684301Z","end":"2026-08-31T21:36:37.83511Z","steps":["trace[331037542] 'process raft request' (duration: 150.429928ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:36:38.130769Z","caller":"traceutil/trace.go:171","msg":"trace[388422574] linearizableReadLoop","detail":"{readStateIndex:9399; appliedIndex:9398; }","duration":"294.26422ms","start":"2026-08-31T21:36:37.836444Z","end":"2026-08-31T21:36:38.130708Z","steps":["trace[388422574] 'read index received' (duration: 282.959511ms)","trace[388422574] 'applied index is now lower than readState.Index' (duration: 11.302469ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T21:36:38.13085Z","caller":"traceutil/trace.go:171","msg":"trace[676747424] transaction","detail":"{read_only:false; response_revision:9067; number_of_response:1; }","duration":"298.743503ms","start":"2026-08-31T21:36:37.832051Z","end":"2026-08-31T21:36:38.130795Z","steps":["trace[676747424] 'process raft request' (duration: 287.319151ms)","trace[676747424] 'compare' (duration: 10.99681ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T21:36:38.13095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.475335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T21:36:38.130988Z","caller":"traceutil/trace.go:171","msg":"trace[1306740327] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9067; }","duration":"294.582367ms","start":"2026-08-31T21:36:37.836395Z","end":"2026-08-31T21:36:38.130977Z","steps":["trace[1306740327] 'agreement among raft nodes before linearized reading' (duration: 294.474455ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:36:46.477424Z","caller":"traceutil/trace.go:171","msg":"trace[2096798544] transaction","detail":"{read_only:false; response_revision:9109; number_of_response:1; }","duration":"259.35395ms","start":"2026-08-31T21:36:46.218022Z","end":"2026-08-31T21:36:46.477376Z","steps":["trace[2096798544] 'process raft request' (duration: 172.48836ms)","trace[2096798544] 'compare' (duration: 86.404408ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T21:36:46.489265Z","caller":"traceutil/trace.go:171","msg":"trace[1035756247] transaction","detail":"{read_only:false; response_revision:9110; number_of_response:1; }","duration":"261.473104ms","start":"2026-08-31T21:36:46.227756Z","end":"2026-08-31T21:36:46.489229Z","steps":["trace[1035756247] 'process raft request' (duration: 261.286168ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:36:46.64673Z","caller":"traceutil/trace.go:171","msg":"trace[994215097] transaction","detail":"{read_only:false; response_revision:9111; number_of_response:1; }","duration":"138.255348ms","start":"2026-08-31T21:36:46.508442Z","end":"2026-08-31T21:36:46.646698Z","steps":["trace[994215097] 'process raft request' (duration: 131.597389ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:36:47.366829Z","caller":"traceutil/trace.go:171","msg":"trace[1371490555] transaction","detail":"{read_only:false; response_revision:9117; number_of_response:1; }","duration":"138.655379ms","start":"2026-08-31T21:36:47.228142Z","end":"2026-08-31T21:36:47.366798Z","steps":["trace[1371490555] 'process raft request' (duration: 138.394361ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:36:47.367092Z","caller":"traceutil/trace.go:171","msg":"trace[1873904118] transaction","detail":"{read_only:false; response_revision:9118; number_of_response:1; }","duration":"131.364013ms","start":"2026-08-31T21:36:47.23569Z","end":"2026-08-31T21:36:47.367055Z","steps":["trace[1873904118] 'process raft request' (duration: 131.057276ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:37:48.720366Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"fc5e9ff41d4daf7c","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-31T21:37:48.731057Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-31T21:37:48.73118Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-31T21:38:20.19336Z","caller":"traceutil/trace.go:171","msg":"trace[1888316271] transaction","detail":"{read_only:false; response_revision:9996; number_of_response:1; }","duration":"289.188884ms","start":"2026-08-31T21:38:19.904102Z","end":"2026-08-31T21:38:20.193291Z","steps":["trace[1888316271] 'process raft request' (duration: 262.059573ms)","trace[1888316271] 'compare' (duration: 26.781223ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T21:38:20.201255Z","caller":"traceutil/trace.go:171","msg":"trace[292525735] linearizableReadLoop","detail":"{readStateIndex:10356; appliedIndex:10354; }","duration":"105.834855ms","start":"2026-08-31T21:38:20.095379Z","end":"2026-08-31T21:38:20.201214Z","steps":["trace[292525735] 'read index received' (duration: 70.797373ms)","trace[292525735] 'applied index is now lower than readState.Index' (duration: 35.035262ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T21:38:20.20133Z","caller":"traceutil/trace.go:171","msg":"trace[245960852] transaction","detail":"{read_only:false; response_revision:9997; number_of_response:1; }","duration":"252.13971ms","start":"2026-08-31T21:38:19.94914Z","end":"2026-08-31T21:38:20.201279Z","steps":["trace[245960852] 'process raft request' (duration: 251.896023ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:38:20.201451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.047671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-9dsnt\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-08-31T21:38:20.2015Z","caller":"traceutil/trace.go:171","msg":"trace[934375390] range","detail":"{range_begin:/registry/pods/openstack/keepalived-9dsnt; range_end:; response_count:1; response_revision:9997; }","duration":"106.174654ms","start":"2026-08-31T21:38:20.09531Z","end":"2026-08-31T21:38:20.201485Z","steps":["trace[934375390] 'agreement among raft nodes before linearized reading' (duration: 106.045021ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:39:12.099918Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7990} {"level":"info","ts":"2026-08-31T21:39:12.19635Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7990,"took":"93.267015ms","hash":4137647859,"current-db-size-bytes":56279040,"current-db-size":"56 MB","current-db-size-in-use-bytes":24899584,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-08-31T21:39:12.196482Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4137647859,"revision":7990,"compact-revision":5628} {"level":"info","ts":"2026-08-31T21:44:01.017104Z","caller":"traceutil/trace.go:171","msg":"trace[1567821012] linearizableReadLoop","detail":"{readStateIndex:12873; appliedIndex:12872; }","duration":"102.784033ms","start":"2026-08-31T21:44:00.914287Z","end":"2026-08-31T21:44:01.017071Z","steps":["trace[1567821012] 'read index received' (duration: 78.621636ms)","trace[1567821012] 'applied index is now lower than readState.Index' (duration: 24.160327ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T21:44:01.01732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.008248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T21:44:01.017453Z","caller":"traceutil/trace.go:171","msg":"trace[7929930] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:12433; }","duration":"103.306626ms","start":"2026-08-31T21:44:00.914119Z","end":"2026-08-31T21:44:01.017426Z","steps":["trace[7929930] 'agreement among raft nodes before linearized reading' (duration: 103.05965ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:44:01.066349Z","caller":"traceutil/trace.go:171","msg":"trace[1856811458] transaction","detail":"{read_only:false; response_revision:12434; number_of_response:1; }","duration":"151.307022ms","start":"2026-08-31T21:44:00.91499Z","end":"2026-08-31T21:44:01.066297Z","steps":["trace[1856811458] 'process raft request' (duration: 150.995073ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:44:01.066512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.787179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-31T21:44:01.06653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.819815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T21:44:01.06663Z","caller":"traceutil/trace.go:171","msg":"trace[1649467892] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:12435; }","duration":"150.934622ms","start":"2026-08-31T21:44:00.915673Z","end":"2026-08-31T21:44:01.066608Z","steps":["trace[1649467892] 'agreement among raft nodes before linearized reading' (duration: 150.778818ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:44:01.066654Z","caller":"traceutil/trace.go:171","msg":"trace[48490170] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:12435; }","duration":"151.961579ms","start":"2026-08-31T21:44:00.914663Z","end":"2026-08-31T21:44:01.066625Z","steps":["trace[48490170] 'agreement among raft nodes before linearized reading' (duration: 151.735343ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:44:01.066646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.073161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T21:44:01.066742Z","caller":"traceutil/trace.go:171","msg":"trace[1049431703] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:12435; }","duration":"152.213095ms","start":"2026-08-31T21:44:00.914497Z","end":"2026-08-31T21:44:01.06671Z","steps":["trace[1049431703] 'agreement among raft nodes before linearized reading' (duration: 152.038311ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:44:01.066828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.905277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5077"} {"level":"info","ts":"2026-08-31T21:44:01.06696Z","caller":"traceutil/trace.go:171","msg":"trace[1116466825] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:12435; }","duration":"152.091051ms","start":"2026-08-31T21:44:00.914837Z","end":"2026-08-31T21:44:01.066929Z","steps":["trace[1116466825] 'agreement among raft nodes before linearized reading' (duration: 151.760083ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:44:01.553342Z","caller":"traceutil/trace.go:171","msg":"trace[1982568159] linearizableReadLoop","detail":"{readStateIndex:12877; appliedIndex:12876; }","duration":"221.529154ms","start":"2026-08-31T21:44:01.331781Z","end":"2026-08-31T21:44:01.55331Z","steps":["trace[1982568159] 'read index received' (duration: 142.358453ms)","trace[1982568159] 'applied index is now lower than readState.Index' (duration: 79.169181ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T21:44:01.553415Z","caller":"traceutil/trace.go:171","msg":"trace[844181068] transaction","detail":"{read_only:false; response_revision:12437; number_of_response:1; }","duration":"310.703869ms","start":"2026-08-31T21:44:01.242674Z","end":"2026-08-31T21:44:01.553378Z","steps":["trace[844181068] 'process raft request' (duration: 231.54708ms)","trace[844181068] 'compare' (duration: 78.910053ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T21:44:01.553608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.550005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-08-31T21:44:01.553618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T21:44:01.242645Z","time spent":"310.845952ms","remote":"127.0.0.1:49214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-31T21:44:01.553652Z","caller":"traceutil/trace.go:171","msg":"trace[313940256] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:12437; }","duration":"210.658307ms","start":"2026-08-31T21:44:01.342981Z","end":"2026-08-31T21:44:01.553639Z","steps":["trace[313940256] 'agreement among raft nodes before linearized reading' (duration: 210.522012ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:44:01.553721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.868973ms","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-08-31T21:44:01.553861Z","caller":"traceutil/trace.go:171","msg":"trace[404126035] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:12437; }","duration":"222.119309ms","start":"2026-08-31T21:44:01.331708Z","end":"2026-08-31T21:44:01.553827Z","steps":["trace[404126035] 'agreement among raft nodes before linearized reading' (duration: 221.756089ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:44:01.553797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.910819ms","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-08-31T21:44:01.554089Z","caller":"traceutil/trace.go:171","msg":"trace[1206539641] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:12437; }","duration":"165.221057ms","start":"2026-08-31T21:44:01.388855Z","end":"2026-08-31T21:44:01.554076Z","steps":["trace[1206539641] 'agreement among raft nodes before linearized reading' (duration: 164.90759ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:44:02.143618Z","caller":"traceutil/trace.go:171","msg":"trace[1789191459] transaction","detail":"{read_only:false; response_revision:12441; number_of_response:1; }","duration":"153.209311ms","start":"2026-08-31T21:44:01.990376Z","end":"2026-08-31T21:44:02.143586Z","steps":["trace[1789191459] 'process raft request' (duration: 152.990685ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:44:02.145644Z","caller":"traceutil/trace.go:171","msg":"trace[405654295] transaction","detail":"{read_only:false; response_revision:12442; number_of_response:1; }","duration":"128.296585ms","start":"2026-08-31T21:44:02.01732Z","end":"2026-08-31T21:44:02.145617Z","steps":["trace[405654295] 'process raft request' (duration: 127.802512ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:44:12.108197Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10357} {"level":"info","ts":"2026-08-31T21:44:12.195889Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10357,"took":"84.657271ms","hash":3537210893,"current-db-size-bytes":56279040,"current-db-size":"56 MB","current-db-size-in-use-bytes":25755648,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-31T21:44:12.196012Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3537210893,"revision":10357,"compact-revision":7990} {"level":"info","ts":"2026-08-31T21:49:12.116384Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12522} {"level":"info","ts":"2026-08-31T21:49:12.205183Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12522,"took":"84.69924ms","hash":3640634944,"current-db-size-bytes":56279040,"current-db-size":"56 MB","current-db-size-in-use-bytes":27791360,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-31T21:49:12.205279Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3640634944,"revision":12522,"compact-revision":10357} {"level":"info","ts":"2026-08-31T21:50:14.865321Z","caller":"traceutil/trace.go:171","msg":"trace[198483686] transaction","detail":"{read_only:false; response_revision:14946; number_of_response:1; }","duration":"132.544012ms","start":"2026-08-31T21:50:14.732744Z","end":"2026-08-31T21:50:14.865288Z","steps":["trace[198483686] 'process raft request' (duration: 132.386398ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:50:14.892637Z","caller":"traceutil/trace.go:171","msg":"trace[1132441883] transaction","detail":"{read_only:false; response_revision:14947; number_of_response:1; }","duration":"125.48965ms","start":"2026-08-31T21:50:14.767115Z","end":"2026-08-31T21:50:14.892604Z","steps":["trace[1132441883] 'process raft request' (duration: 125.256454ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:50:54.812183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.305062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-31T21:50:54.812309Z","caller":"traceutil/trace.go:171","msg":"trace[2117045134] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15171; }","duration":"377.497907ms","start":"2026-08-31T21:50:54.434784Z","end":"2026-08-31T21:50:54.812282Z","steps":["trace[2117045134] 'range keys from in-memory index tree' (duration: 377.109106ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:50:54.812358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T21:50:54.434765Z","time spent":"377.581719ms","remote":"127.0.0.1:49214","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-08-31T21:50:54.812353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.276814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-31T21:50:54.812461Z","caller":"traceutil/trace.go:171","msg":"trace[1214197129] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:15171; }","duration":"442.440077ms","start":"2026-08-31T21:50:54.369999Z","end":"2026-08-31T21:50:54.812439Z","steps":["trace[1214197129] 'count revisions from in-memory index tree' (duration: 442.203472ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:50:54.812584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T21:50:54.36998Z","time spent":"442.51741ms","remote":"127.0.0.1:49064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":115,"response size":30,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"info","ts":"2026-08-31T21:50:54.840797Z","caller":"traceutil/trace.go:171","msg":"trace[2048729495] transaction","detail":"{read_only:false; response_revision:15172; number_of_response:1; }","duration":"193.290625ms","start":"2026-08-31T21:50:54.647472Z","end":"2026-08-31T21:50:54.840763Z","steps":["trace[2048729495] 'process raft request' (duration: 193.09013ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:50:54.843553Z","caller":"traceutil/trace.go:171","msg":"trace[406175426] transaction","detail":"{read_only:false; response_revision:15173; number_of_response:1; }","duration":"179.989733ms","start":"2026-08-31T21:50:54.663518Z","end":"2026-08-31T21:50:54.843508Z","steps":["trace[406175426] 'process raft request' (duration: 179.787578ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:51:20.514015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.373066ms","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-08-31T21:51:20.514211Z","caller":"traceutil/trace.go:171","msg":"trace[1275806770] transaction","detail":"{read_only:false; response_revision:15497; number_of_response:1; }","duration":"385.268537ms","start":"2026-08-31T21:51:20.128878Z","end":"2026-08-31T21:51:20.514147Z","steps":["trace[1275806770] 'process raft request' (duration: 302.505236ms)","trace[1275806770] 'compare' (duration: 82.492314ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T21:51:20.514204Z","caller":"traceutil/trace.go:171","msg":"trace[1112976360] transaction","detail":"{read_only:false; response_revision:15498; number_of_response:1; }","duration":"383.119762ms","start":"2026-08-31T21:51:20.13105Z","end":"2026-08-31T21:51:20.51417Z","steps":["trace[1112976360] 'process raft request' (duration: 383.017429ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:51:20.514306Z","caller":"traceutil/trace.go:171","msg":"trace[1457301149] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:15496; }","duration":"102.692963ms","start":"2026-08-31T21:51:20.411586Z","end":"2026-08-31T21:51:20.514279Z","steps":["trace[1457301149] 'range keys from in-memory index tree' (duration: 102.346365ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:51:20.514542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T21:51:20.131035Z","time spent":"383.350168ms","remote":"127.0.0.1:49214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-31T21:51:20.514467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T21:51:20.128857Z","time spent":"385.489872ms","remote":"127.0.0.1:49034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":986,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-31T21:51:20.514833Z","caller":"traceutil/trace.go:171","msg":"trace[398169068] transaction","detail":"{read_only:false; response_revision:15499; number_of_response:1; }","duration":"378.620516ms","start":"2026-08-31T21:51:20.136177Z","end":"2026-08-31T21:51:20.514797Z","steps":["trace[398169068] 'process raft request' (duration: 377.971699ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:51:20.515017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T21:51:20.136142Z","time spent":"378.77313ms","remote":"127.0.0.1:49214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-31T21:51:20.515109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.303598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T21:51:20.515167Z","caller":"traceutil/trace.go:171","msg":"trace[1865417535] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:15499; }","duration":"298.426101ms","start":"2026-08-31T21:51:20.216729Z","end":"2026-08-31T21:51:20.515155Z","steps":["trace[1865417535] 'agreement among raft nodes before linearized reading' (duration: 298.307938ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:51:20.514605Z","caller":"traceutil/trace.go:171","msg":"trace[321466103] linearizableReadLoop","detail":"{readStateIndex:16050; appliedIndex:16047; }","duration":"297.797275ms","start":"2026-08-31T21:51:20.216777Z","end":"2026-08-31T21:51:20.514574Z","steps":["trace[321466103] 'read index received' (duration: 214.627374ms)","trace[321466103] 'applied index is now lower than readState.Index' (duration: 83.168111ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T21:51:20.515726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.785585ms","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-08-31T21:51:20.515816Z","caller":"traceutil/trace.go:171","msg":"trace[1848426606] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15499; }","duration":"257.93564ms","start":"2026-08-31T21:51:20.257867Z","end":"2026-08-31T21:51:20.515803Z","steps":["trace[1848426606] 'agreement among raft nodes before linearized reading' (duration: 257.698863ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:52:14.936025Z","caller":"traceutil/trace.go:171","msg":"trace[2024244744] linearizableReadLoop","detail":"{readStateIndex:16708; appliedIndex:16707; }","duration":"111.771237ms","start":"2026-08-31T21:52:14.824194Z","end":"2026-08-31T21:52:14.935965Z","steps":["trace[2024244744] 'read index received' (duration: 110.242587ms)","trace[2024244744] 'applied index is now lower than readState.Index' (duration: 1.52747ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T21:52:14.936071Z","caller":"traceutil/trace.go:171","msg":"trace[670048612] transaction","detail":"{read_only:false; response_revision:16141; number_of_response:1; }","duration":"119.750032ms","start":"2026-08-31T21:52:14.816283Z","end":"2026-08-31T21:52:14.936033Z","steps":["trace[670048612] 'process raft request' (duration: 119.490205ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:52:14.936297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.074124ms","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-08-31T21:52:14.936368Z","caller":"traceutil/trace.go:171","msg":"trace[1601966461] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:16141; }","duration":"112.189398ms","start":"2026-08-31T21:52:14.82416Z","end":"2026-08-31T21:52:14.936349Z","steps":["trace[1601966461] 'agreement among raft nodes before linearized reading' (duration: 111.984042ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:52:49.274115Z","caller":"traceutil/trace.go:171","msg":"trace[977594295] transaction","detail":"{read_only:false; response_revision:16321; number_of_response:1; }","duration":"126.268541ms","start":"2026-08-31T21:52:49.147814Z","end":"2026-08-31T21:52:49.274083Z","steps":["trace[977594295] 'process raft request' (duration: 126.083615ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:52:49.369532Z","caller":"traceutil/trace.go:171","msg":"trace[1769878722] linearizableReadLoop","detail":"{readStateIndex:16896; appliedIndex:16894; }","duration":"216.176045ms","start":"2026-08-31T21:52:49.15331Z","end":"2026-08-31T21:52:49.369487Z","steps":["trace[1769878722] 'read index received' (duration: 120.645445ms)","trace[1769878722] 'applied index is now lower than readState.Index' (duration: 95.5297ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T21:52:49.36967Z","caller":"traceutil/trace.go:171","msg":"trace[1418208272] transaction","detail":"{read_only:false; response_revision:16322; number_of_response:1; }","duration":"221.217075ms","start":"2026-08-31T21:52:49.14844Z","end":"2026-08-31T21:52:49.369657Z","steps":["trace[1418208272] 'process raft request' (duration: 209.257127ms)","trace[1418208272] 'compare' (duration: 11.666661ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T21:52:49.36986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.523753ms","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-08-31T21:52:49.36993Z","caller":"traceutil/trace.go:171","msg":"trace[1856054188] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:16322; }","duration":"216.630116ms","start":"2026-08-31T21:52:49.153284Z","end":"2026-08-31T21:52:49.369914Z","steps":["trace[1856054188] 'agreement among raft nodes before linearized reading' (duration: 216.426301ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:52:49.421904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.692359ms","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-08-31T21:52:49.421995Z","caller":"traceutil/trace.go:171","msg":"trace[844920406] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:16323; }","duration":"258.813601ms","start":"2026-08-31T21:52:49.163158Z","end":"2026-08-31T21:52:49.421972Z","steps":["trace[844920406] 'agreement among raft nodes before linearized reading' (duration: 258.672118ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:54:12.124308Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14550} {"level":"info","ts":"2026-08-31T21:54:12.208515Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14550,"took":"80.350295ms","hash":215858966,"current-db-size-bytes":56279040,"current-db-size":"56 MB","current-db-size-in-use-bytes":30978048,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-31T21:54:12.208594Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":215858966,"revision":14550,"compact-revision":12522} {"level":"info","ts":"2026-08-31T21:59:12.139036Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16855} {"level":"info","ts":"2026-08-31T21:59:12.246439Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16855,"took":"103.042411ms","hash":2201410596,"current-db-size-bytes":56279040,"current-db-size":"56 MB","current-db-size-in-use-bytes":31666176,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-31T21:59:12.246548Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2201410596,"revision":16855,"compact-revision":14550} {"level":"warn","ts":"2026-08-31T21:59:24.566644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.996097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-08-31T21:59:24.566803Z","caller":"traceutil/trace.go:171","msg":"trace[820383456] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:19151; }","duration":"115.235644ms","start":"2026-08-31T21:59:24.451542Z","end":"2026-08-31T21:59:24.566777Z","steps":["trace[820383456] 'range keys from in-memory index tree' (duration: 114.737441ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:59:24.919397Z","caller":"traceutil/trace.go:171","msg":"trace[465933357] transaction","detail":"{read_only:false; response_revision:19152; number_of_response:1; }","duration":"210.157677ms","start":"2026-08-31T21:59:24.709212Z","end":"2026-08-31T21:59:24.91937Z","steps":["trace[465933357] 'process raft request' (duration: 209.991923ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:59:24.949648Z","caller":"traceutil/trace.go:171","msg":"trace[52685245] transaction","detail":"{read_only:false; response_revision:19153; number_of_response:1; }","duration":"121.971815ms","start":"2026-08-31T21:59:24.827636Z","end":"2026-08-31T21:59:24.949608Z","steps":["trace[52685245] 'process raft request' (duration: 121.616486ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:59:25.443027Z","caller":"traceutil/trace.go:171","msg":"trace[1421343059] linearizableReadLoop","detail":"{readStateIndex:19826; appliedIndex:19825; }","duration":"367.192529ms","start":"2026-08-31T21:59:25.075805Z","end":"2026-08-31T21:59:25.442997Z","steps":["trace[1421343059] 'read index received' (duration: 367.048455ms)","trace[1421343059] 'applied index is now lower than readState.Index' (duration: 142.824µs)"],"step_count":2} {"level":"info","ts":"2026-08-31T21:59:25.443064Z","caller":"traceutil/trace.go:171","msg":"trace[459528037] transaction","detail":"{read_only:false; response_revision:19154; number_of_response:1; }","duration":"467.283325ms","start":"2026-08-31T21:59:24.975745Z","end":"2026-08-31T21:59:25.443028Z","steps":["trace[459528037] 'process raft request' (duration: 467.006199ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:59:25.443282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.451165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-31T21:59:25.443393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T21:59:24.975682Z","time spent":"467.599625ms","remote":"127.0.0.1:49136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20860,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-31T21:59:25.443488Z","caller":"traceutil/trace.go:171","msg":"trace[97732315] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:19154; }","duration":"367.723723ms","start":"2026-08-31T21:59:25.075715Z","end":"2026-08-31T21:59:25.443439Z","steps":["trace[97732315] 'agreement among raft nodes before linearized reading' (duration: 367.403135ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:59:25.443616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T21:59:25.075685Z","time spent":"367.914067ms","remote":"127.0.0.1:50236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"info","ts":"2026-08-31T21:59:25.619591Z","caller":"traceutil/trace.go:171","msg":"trace[686852015] transaction","detail":"{read_only:false; response_revision:19155; number_of_response:1; }","duration":"411.648015ms","start":"2026-08-31T21:59:25.207905Z","end":"2026-08-31T21:59:25.619553Z","steps":["trace[686852015] 'process raft request' (duration: 410.566177ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:59:25.619829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T21:59:25.207856Z","time spent":"411.835529ms","remote":"127.0.0.1:49214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-31T21:59:25.620026Z","caller":"traceutil/trace.go:171","msg":"trace[1964320021] transaction","detail":"{read_only:false; response_revision:19156; number_of_response:1; }","duration":"381.689339ms","start":"2026-08-31T21:59:25.238322Z","end":"2026-08-31T21:59:25.620011Z","steps":["trace[1964320021] 'process raft request' (duration: 380.394115ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T21:59:25.620103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T21:59:25.238292Z","time spent":"381.770792ms","remote":"127.0.0.1:49214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-31T21:59:25.620295Z","caller":"traceutil/trace.go:171","msg":"trace[676516050] transaction","detail":"{read_only:false; response_revision:19157; number_of_response:1; }","duration":"272.49048ms","start":"2026-08-31T21:59:25.347789Z","end":"2026-08-31T21:59:25.62028Z","steps":["trace[676516050] 'process raft request' (duration: 270.985831ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:59:25.620512Z","caller":"traceutil/trace.go:171","msg":"trace[1478690531] transaction","detail":"{read_only:false; response_revision:19158; number_of_response:1; }","duration":"207.218963ms","start":"2026-08-31T21:59:25.413272Z","end":"2026-08-31T21:59:25.620491Z","steps":["trace[1478690531] 'process raft request' (duration: 205.556041ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T21:59:35.445214Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"fc5e9ff41d4daf7c","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-31T21:59:35.458503Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-31T21:59:35.458658Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-31T22:04:12.146759Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19053} {"level":"info","ts":"2026-08-31T22:04:12.253352Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19053,"took":"102.087717ms","hash":2996255934,"current-db-size-bytes":56279040,"current-db-size":"56 MB","current-db-size-in-use-bytes":34123776,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-31T22:04:12.25346Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2996255934,"revision":19053,"compact-revision":16855} {"level":"info","ts":"2026-08-31T22:05:53.435157Z","caller":"traceutil/trace.go:171","msg":"trace[796066611] transaction","detail":"{read_only:false; response_revision:22179; number_of_response:1; }","duration":"133.353825ms","start":"2026-08-31T22:05:53.301759Z","end":"2026-08-31T22:05:53.435113Z","steps":["trace[796066611] 'process raft request' (duration: 133.13878ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T22:05:53.437258Z","caller":"traceutil/trace.go:171","msg":"trace[7012213] transaction","detail":"{read_only:false; response_revision:22180; number_of_response:1; }","duration":"131.062368ms","start":"2026-08-31T22:05:53.30616Z","end":"2026-08-31T22:05:53.437223Z","steps":["trace[7012213] 'process raft request' (duration: 130.044892ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T22:05:53.437494Z","caller":"traceutil/trace.go:171","msg":"trace[1781824380] transaction","detail":"{read_only:false; response_revision:22181; number_of_response:1; }","duration":"114.431743ms","start":"2026-08-31T22:05:53.323046Z","end":"2026-08-31T22:05:53.437478Z","steps":["trace[1781824380] 'process raft request' (duration: 113.52443ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T22:05:59.56805Z","caller":"traceutil/trace.go:171","msg":"trace[1023855025] transaction","detail":"{read_only:false; response_revision:22210; number_of_response:1; }","duration":"105.180126ms","start":"2026-08-31T22:05:59.462838Z","end":"2026-08-31T22:05:59.568018Z","steps":["trace[1023855025] 'process raft request' (duration: 104.97137ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T22:05:59.592109Z","caller":"traceutil/trace.go:171","msg":"trace[1654242744] transaction","detail":"{read_only:false; response_revision:22211; number_of_response:1; }","duration":"129.068896ms","start":"2026-08-31T22:05:59.463009Z","end":"2026-08-31T22:05:59.592078Z","steps":["trace[1654242744] 'process raft request' (duration: 128.850831ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T22:05:59.592278Z","caller":"traceutil/trace.go:171","msg":"trace[631979560] linearizableReadLoop","detail":"{readStateIndex:22983; appliedIndex:22980; }","duration":"114.669359ms","start":"2026-08-31T22:05:59.477567Z","end":"2026-08-31T22:05:59.592236Z","steps":["trace[631979560] 'read index received' (duration: 90.288895ms)","trace[631979560] 'applied index is now lower than readState.Index' (duration: 24.376944ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T22:05:59.592302Z","caller":"traceutil/trace.go:171","msg":"trace[1589802227] transaction","detail":"{read_only:false; response_revision:22212; number_of_response:1; }","duration":"129.070548ms","start":"2026-08-31T22:05:59.463194Z","end":"2026-08-31T22:05:59.592265Z","steps":["trace[1589802227] 'process raft request' (duration: 128.833221ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T22:05:59.592613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.015677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-08-31T22:05:59.592654Z","caller":"traceutil/trace.go:171","msg":"trace[1524246105] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:22212; }","duration":"115.144661ms","start":"2026-08-31T22:05:59.477493Z","end":"2026-08-31T22:05:59.592637Z","steps":["trace[1524246105] 'agreement among raft nodes before linearized reading' (duration: 114.877304ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T22:05:59.761476Z","caller":"traceutil/trace.go:171","msg":"trace[307538375] linearizableReadLoop","detail":"{readStateIndex:22984; appliedIndex:22983; }","duration":"135.753758ms","start":"2026-08-31T22:05:59.625671Z","end":"2026-08-31T22:05:59.761425Z","steps":["trace[307538375] 'read index received' (duration: 128.743999ms)","trace[307538375] 'applied index is now lower than readState.Index' (duration: 7.008569ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T22:05:59.761612Z","caller":"traceutil/trace.go:171","msg":"trace[2081415846] transaction","detail":"{read_only:false; response_revision:22213; number_of_response:1; }","duration":"163.832315ms","start":"2026-08-31T22:05:59.597743Z","end":"2026-08-31T22:05:59.761576Z","steps":["trace[2081415846] 'process raft request' (duration: 156.733813ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T22:05:59.761694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.993624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-31T22:05:59.761744Z","caller":"traceutil/trace.go:171","msg":"trace[1483201151] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:22213; }","duration":"136.108997ms","start":"2026-08-31T22:05:59.62562Z","end":"2026-08-31T22:05:59.761729Z","steps":["trace[1483201151] 'agreement among raft nodes before linearized reading' (duration: 135.989704ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T22:05:59.76186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.114472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-08-31T22:05:59.761932Z","caller":"traceutil/trace.go:171","msg":"trace[1353899991] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:22213; }","duration":"108.245865ms","start":"2026-08-31T22:05:59.65367Z","end":"2026-08-31T22:05:59.761916Z","steps":["trace[1353899991] 'agreement among raft nodes before linearized reading' (duration: 108.02669ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T22:06:00.150298Z","caller":"traceutil/trace.go:171","msg":"trace[909635981] transaction","detail":"{read_only:false; response_revision:22214; number_of_response:1; }","duration":"149.775225ms","start":"2026-08-31T22:06:00.000492Z","end":"2026-08-31T22:06:00.150267Z","steps":["trace[909635981] 'process raft request' (duration: 149.548099ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T22:06:00.408968Z","caller":"traceutil/trace.go:171","msg":"trace[1256000165] linearizableReadLoop","detail":"{readStateIndex:22986; appliedIndex:22984; }","duration":"365.450324ms","start":"2026-08-31T22:06:00.043481Z","end":"2026-08-31T22:06:00.408931Z","steps":["trace[1256000165] 'read index received' (duration: 106.616133ms)","trace[1256000165] 'applied index is now lower than readState.Index' (duration: 258.833011ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T22:06:00.409241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.742741ms","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-08-31T22:06:00.40928Z","caller":"traceutil/trace.go:171","msg":"trace[307024047] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:22215; }","duration":"365.886975ms","start":"2026-08-31T22:06:00.043383Z","end":"2026-08-31T22:06:00.40927Z","steps":["trace[307024047] 'agreement among raft nodes before linearized reading' (duration: 365.6901ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T22:06:00.409314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T22:06:00.043361Z","time spent":"365.944247ms","remote":"127.0.0.1:49214","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-08-31T22:06:00.409648Z","caller":"traceutil/trace.go:171","msg":"trace[1920956246] transaction","detail":"{read_only:false; response_revision:22215; number_of_response:1; }","duration":"405.22058ms","start":"2026-08-31T22:06:00.004415Z","end":"2026-08-31T22:06:00.409636Z","steps":["trace[1920956246] 'process raft request' (duration: 404.333927ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T22:06:00.409756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T22:06:00.004375Z","time spent":"405.308472ms","remote":"127.0.0.1:49214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-31T22:06:00.41019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.634374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-31T22:06:00.410223Z","caller":"traceutil/trace.go:171","msg":"trace[1724717506] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:22215; }","duration":"274.705326ms","start":"2026-08-31T22:06:00.135508Z","end":"2026-08-31T22:06:00.410214Z","steps":["trace[1724717506] 'agreement among raft nodes before linearized reading' (duration: 274.612304ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T22:06:00.410479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.927485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-08-31T22:06:00.410513Z","caller":"traceutil/trace.go:171","msg":"trace[1020178344] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:22215; }","duration":"271.986287ms","start":"2026-08-31T22:06:00.138518Z","end":"2026-08-31T22:06:00.410505Z","steps":["trace[1020178344] 'agreement among raft nodes before linearized reading' (duration: 271.857844ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T22:09:12.155732Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21454} {"level":"info","ts":"2026-08-31T22:09:12.292676Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21454,"took":"132.024761ms","hash":3151696539,"current-db-size-bytes":56279040,"current-db-size":"56 MB","current-db-size-in-use-bytes":30511104,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-31T22:09:12.29277Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3151696539,"revision":21454,"compact-revision":19053} {"level":"info","ts":"2026-08-31T22:10:29.107536Z","caller":"traceutil/trace.go:171","msg":"trace[1730737194] transaction","detail":"{read_only:false; response_revision:24852; number_of_response:1; }","duration":"292.295152ms","start":"2026-08-31T22:10:28.815203Z","end":"2026-08-31T22:10:29.107498Z","steps":["trace[1730737194] 'process raft request' (duration: 274.080588ms)","trace[1730737194] 'compare' (duration: 17.61952ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T22:10:29.10918Z","caller":"traceutil/trace.go:171","msg":"trace[373350675] transaction","detail":"{read_only:false; response_revision:24853; number_of_response:1; }","duration":"290.400014ms","start":"2026-08-31T22:10:28.817682Z","end":"2026-08-31T22:10:29.108082Z","steps":["trace[373350675] 'process raft request' (duration: 289.760508ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T22:11:50.773803Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000067ee.wal"} {"level":"info","ts":"2026-08-31T22:14:12.165708Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23655} {"level":"info","ts":"2026-08-31T22:14:12.304525Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23655,"took":"134.094544ms","hash":500329005,"current-db-size-bytes":56279040,"current-db-size":"56 MB","current-db-size-in-use-bytes":43143168,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-08-31T22:14:12.304638Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":500329005,"revision":23655,"compact-revision":21454} {"level":"info","ts":"2026-08-31T22:19:12.173504Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26799} {"level":"info","ts":"2026-08-31T22:19:12.332111Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26799,"took":"152.657215ms","hash":55611283,"current-db-size-bytes":56279040,"current-db-size":"56 MB","current-db-size-in-use-bytes":30330880,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-31T22:19:12.332207Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":55611283,"revision":26799,"compact-revision":23655} {"level":"info","ts":"2026-08-31T22:19:13.327384Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"fc5e9ff41d4daf7c","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-31T22:19:13.333086Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-08-31T22:19:13.333387Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003}