{"level":"warn","ts":"2026-09-23T10:24:09.773808Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-23T10:24:09.773955Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.80: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.80:2380","--initial-cluster=instance=https://162.253.55.80:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.80:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.80: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-09-23T10:24:09.774015Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-23T10:24:09.774023Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.80:2380"]} {"level":"info","ts":"2026-09-23T10:24:09.774038Z","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-09-23T10:24:09.77464Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.80:2379"]} {"level":"info","ts":"2026-09-23T10:24:09.774711Z","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":8,"max-cpu-available":8,"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.80:2380"],"listen-peer-urls":["https://162.253.55.80:2380"],"advertise-client-urls":["https://162.253.55.80:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.80:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.80: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-09-23T10:24:09.778654Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.742926ms"} {"level":"info","ts":"2026-09-23T10:24:09.785268Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a87e3d9cf178847b","cluster-id":"df2e96c7e71d56b9"} {"level":"info","ts":"2026-09-23T10:24:09.785327Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a87e3d9cf178847b switched to configuration voters=()"} {"level":"info","ts":"2026-09-23T10:24:09.785343Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a87e3d9cf178847b became follower at term 0"} {"level":"info","ts":"2026-09-23T10:24:09.78535Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a87e3d9cf178847b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-23T10:24:09.785359Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a87e3d9cf178847b became follower at term 1"} {"level":"info","ts":"2026-09-23T10:24:09.785387Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a87e3d9cf178847b switched to configuration voters=(12141209389712835707)"} {"level":"warn","ts":"2026-09-23T10:24:09.792454Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-23T10:24:09.794023Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-23T10:24:09.795276Z","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-09-23T10:24:09.796955Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a87e3d9cf178847b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-23T10:24:09.797058Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a87e3d9cf178847b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-23T10:24:09.797361Z","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-09-23T10:24:09.800269Z","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-09-23T10:24:09.80033Z","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-09-23T10:24:09.802914Z","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-09-23T10:24:09.803023Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.80:2380"} {"level":"info","ts":"2026-09-23T10:24:09.80306Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.80:2380"} {"level":"info","ts":"2026-09-23T10:24:09.804207Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a87e3d9cf178847b","initial-advertise-peer-urls":["https://162.253.55.80:2380"],"listen-peer-urls":["https://162.253.55.80:2380"],"advertise-client-urls":["https://162.253.55.80:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.80:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-23T10:24:09.80424Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-23T10:24:09.804493Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a87e3d9cf178847b switched to configuration voters=(12141209389712835707)"} {"level":"info","ts":"2026-09-23T10:24:09.804703Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"df2e96c7e71d56b9","local-member-id":"a87e3d9cf178847b","added-peer-id":"a87e3d9cf178847b","added-peer-peer-urls":["https://162.253.55.80:2380"]} {"level":"info","ts":"2026-09-23T10:24:10.486546Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a87e3d9cf178847b is starting a new election at term 1"} {"level":"info","ts":"2026-09-23T10:24:10.486726Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a87e3d9cf178847b became pre-candidate at term 1"} {"level":"info","ts":"2026-09-23T10:24:10.486799Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a87e3d9cf178847b received MsgPreVoteResp from a87e3d9cf178847b at term 1"} {"level":"info","ts":"2026-09-23T10:24:10.48687Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a87e3d9cf178847b became candidate at term 2"} {"level":"info","ts":"2026-09-23T10:24:10.486965Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a87e3d9cf178847b received MsgVoteResp from a87e3d9cf178847b at term 2"} {"level":"info","ts":"2026-09-23T10:24:10.487023Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a87e3d9cf178847b became leader at term 2"} {"level":"info","ts":"2026-09-23T10:24:10.487083Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a87e3d9cf178847b elected leader a87e3d9cf178847b at term 2"} {"level":"info","ts":"2026-09-23T10:24:10.497324Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-23T10:24:10.497647Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a87e3d9cf178847b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.80:2379]}","request-path":"/0/members/a87e3d9cf178847b/attributes","cluster-id":"df2e96c7e71d56b9","publish-timeout":"7s"} {"level":"info","ts":"2026-09-23T10:24:10.497673Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-23T10:24:10.497748Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-23T10:24:10.497989Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-23T10:24:10.498032Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-23T10:24:10.499221Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"df2e96c7e71d56b9","local-member-id":"a87e3d9cf178847b","cluster-version":"3.5"} {"level":"info","ts":"2026-09-23T10:24:10.499565Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-23T10:24:10.499604Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-23T10:24:10.501122Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-23T10:24:10.502989Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.80:2379"} {"level":"warn","ts":"2026-09-23T10:24:13.250087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.485414ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-09-23T10:24:13.250207Z","caller":"traceutil/trace.go:171","msg":"trace[1371684929] linearizableReadLoop","detail":"{readStateIndex:119; appliedIndex:118; }","duration":"383.920158ms","start":"2026-09-23T10:24:12.866268Z","end":"2026-09-23T10:24:13.250188Z","steps":["trace[1371684929] 'read index received' (duration: 205.699328ms)","trace[1371684929] 'applied index is now lower than readState.Index' (duration: 178.21936ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T10:24:13.250326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.049271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-23T10:24:13.250357Z","caller":"traceutil/trace.go:171","msg":"trace[1763524230] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:0; response_revision:114; }","duration":"384.101372ms","start":"2026-09-23T10:24:12.866247Z","end":"2026-09-23T10:24:13.250348Z","steps":["trace[1763524230] 'agreement among raft nodes before linearized reading' (duration: 384.026831ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:24:13.250329Z","caller":"traceutil/trace.go:171","msg":"trace[1931606092] transaction","detail":"{read_only:false; response_revision:114; number_of_response:1; }","duration":"384.872557ms","start":"2026-09-23T10:24:12.865403Z","end":"2026-09-23T10:24:13.250276Z","steps":["trace[1931606092] 'process raft request' (duration: 206.689388ms)","trace[1931606092] 'compare' (duration: 177.365392ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T10:24:13.250374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.004269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-09-23T10:24:13.250399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:24:12.866238Z","time spent":"384.153383ms","remote":"127.0.0.1:40906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":28,"request content":"key:\"/registry/clusterroles/edit\" "} {"level":"info","ts":"2026-09-23T10:24:13.250453Z","caller":"traceutil/trace.go:171","msg":"trace[314108661] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:114; }","duration":"378.088181ms","start":"2026-09-23T10:24:12.872343Z","end":"2026-09-23T10:24:13.250432Z","steps":["trace[314108661] 'agreement among raft nodes before linearized reading' (duration: 377.965888ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:24:13.250489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:24:12.872311Z","time spent":"378.170822ms","remote":"127.0.0.1:40548","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-09-23T10:24:13.250524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:24:12.865388Z","time spent":"385.037101ms","remote":"127.0.0.1:40932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-23T10:24:13.463457Z","caller":"traceutil/trace.go:171","msg":"trace[1032842681] transaction","detail":"{read_only:false; response_revision:115; number_of_response:1; }","duration":"199.731171ms","start":"2026-09-23T10:24:13.263696Z","end":"2026-09-23T10:24:13.463427Z","steps":["trace[1032842681] 'process raft request' (duration: 198.443065ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:24:53.0169Z","caller":"traceutil/trace.go:171","msg":"trace[1869664646] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"269.15528ms","start":"2026-09-23T10:24:52.747711Z","end":"2026-09-23T10:24:53.016866Z","steps":["trace[1869664646] 'process raft request' (duration: 269.033505ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:24:53.017118Z","caller":"traceutil/trace.go:171","msg":"trace[801925158] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"269.669833ms","start":"2026-09-23T10:24:52.7473Z","end":"2026-09-23T10:24:53.01697Z","steps":["trace[801925158] 'process raft request' (duration: 266.682815ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:24:53.019028Z","caller":"traceutil/trace.go:171","msg":"trace[1704111824] linearizableReadLoop","detail":"{readStateIndex:598; appliedIndex:595; }","duration":"145.173252ms","start":"2026-09-23T10:24:52.873838Z","end":"2026-09-23T10:24:53.019011Z","steps":["trace[1704111824] 'read index received' (duration: 140.175312ms)","trace[1704111824] 'applied index is now lower than readState.Index' (duration: 4.99669ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T10:24:53.019243Z","caller":"traceutil/trace.go:171","msg":"trace[1943669229] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"245.661931ms","start":"2026-09-23T10:24:52.773559Z","end":"2026-09-23T10:24:53.019221Z","steps":["trace[1943669229] 'process raft request' (duration: 245.265453ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:24:53.019284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.430294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-23T10:24:53.01948Z","caller":"traceutil/trace.go:171","msg":"trace[612877443] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:580; }","duration":"145.650014ms","start":"2026-09-23T10:24:52.87379Z","end":"2026-09-23T10:24:53.01944Z","steps":["trace[612877443] 'agreement among raft nodes before linearized reading' (duration: 145.376991ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:24:53.475704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.477394ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-23T10:24:53.476012Z","caller":"traceutil/trace.go:171","msg":"trace[1031187485] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"336.920753ms","start":"2026-09-23T10:24:53.139079Z","end":"2026-09-23T10:24:53.476Z","steps":["trace[1031187485] 'process raft request' (duration: 336.814048ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:24:53.47606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:24:53.139063Z","time spent":"336.976045ms","remote":"127.0.0.1:40604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T10:24:53.476367Z","caller":"traceutil/trace.go:171","msg":"trace[697351851] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"361.455792ms","start":"2026-09-23T10:24:53.1149Z","end":"2026-09-23T10:24:53.476356Z","steps":["trace[697351851] 'process raft request' (duration: 97.160451ms)","trace[697351851] 'compare' (duration: 263.279765ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T10:24:53.476408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:24:53.114884Z","time spent":"361.504404ms","remote":"127.0.0.1:40604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80679,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T10:24:53.476646Z","caller":"traceutil/trace.go:171","msg":"trace[1610131253] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"361.454991ms","start":"2026-09-23T10:24:53.115171Z","end":"2026-09-23T10:24:53.476626Z","steps":["trace[1610131253] 'process raft request' (duration: 360.626534ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:24:53.476751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:24:53.115164Z","time spent":"361.532116ms","remote":"127.0.0.1:40604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T10:24:53.476662Z","caller":"traceutil/trace.go:171","msg":"trace[1287762721] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"356.884891ms","start":"2026-09-23T10:24:53.119755Z","end":"2026-09-23T10:24:53.47664Z","steps":["trace[1287762721] 'process raft request' (duration: 356.099365ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:24:53.476828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:24:53.119689Z","time spent":"357.114742ms","remote":"127.0.0.1:40604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-23T10:24:53.635863Z","caller":"traceutil/trace.go:171","msg":"trace[719476628] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"140.272544ms","start":"2026-09-23T10:24:53.495557Z","end":"2026-09-23T10:24:53.63583Z","steps":["trace[719476628] 'process raft request' (duration: 127.213074ms)","trace[719476628] 'compare' (duration: 12.933405ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T10:24:53.636414Z","caller":"traceutil/trace.go:171","msg":"trace[1921775828] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"135.042353ms","start":"2026-09-23T10:24:53.501357Z","end":"2026-09-23T10:24:53.6364Z","steps":["trace[1921775828] 'process raft request' (duration: 134.777781ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:24:59.384741Z","caller":"traceutil/trace.go:171","msg":"trace[1832539625] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"167.664836ms","start":"2026-09-23T10:24:59.217036Z","end":"2026-09-23T10:24:59.384701Z","steps":["trace[1832539625] 'process raft request' (duration: 167.216254ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:25:00.114264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.740914ms","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-09-23T10:25:00.114403Z","caller":"traceutil/trace.go:171","msg":"trace[1303078776] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:648; }","duration":"371.953225ms","start":"2026-09-23T10:24:59.742422Z","end":"2026-09-23T10:25:00.114375Z","steps":["trace[1303078776] 'range keys from in-memory index tree' (duration: 371.071232ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:25:00.114459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:24:59.742405Z","time spent":"372.04247ms","remote":"127.0.0.1:40842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-23T10:25:00.115313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.649245ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-09-23T10:25:00.115574Z","caller":"traceutil/trace.go:171","msg":"trace[1000276457] linearizableReadLoop","detail":"{readStateIndex:670; appliedIndex:669; }","duration":"241.728429ms","start":"2026-09-23T10:24:59.873812Z","end":"2026-09-23T10:25:00.11554Z","steps":["trace[1000276457] 'read index received' (duration: 20.879514ms)","trace[1000276457] 'applied index is now lower than readState.Index' (duration: 220.845485ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T10:25:00.115966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.139438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-23T10:25:00.116067Z","caller":"traceutil/trace.go:171","msg":"trace[1736274734] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:648; }","duration":"242.255055ms","start":"2026-09-23T10:24:59.873795Z","end":"2026-09-23T10:25:00.11605Z","steps":["trace[1736274734] 'agreement among raft nodes before linearized reading' (duration: 241.940879ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:25:00.116125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.637012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-23T10:25:00.116153Z","caller":"traceutil/trace.go:171","msg":"trace[21728214] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:649; }","duration":"151.733457ms","start":"2026-09-23T10:24:59.964409Z","end":"2026-09-23T10:25:00.116142Z","steps":["trace[21728214] 'agreement among raft nodes before linearized reading' (duration: 151.633242ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:25:00.116783Z","caller":"traceutil/trace.go:171","msg":"trace[1047012341] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"224.24964ms","start":"2026-09-23T10:24:59.892475Z","end":"2026-09-23T10:25:00.116725Z","steps":["trace[1047012341] 'process raft request' (duration: 223.063402ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:25:04.91487Z","caller":"traceutil/trace.go:171","msg":"trace[540871810] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"218.729494ms","start":"2026-09-23T10:25:04.696093Z","end":"2026-09-23T10:25:04.914822Z","steps":["trace[540871810] 'process raft request' (duration: 200.556356ms)","trace[540871810] 'compare' (duration: 17.730176ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T10:25:04.91485Z","caller":"traceutil/trace.go:171","msg":"trace[43458093] linearizableReadLoop","detail":"{readStateIndex:747; appliedIndex:746; }","duration":"213.271289ms","start":"2026-09-23T10:25:04.701523Z","end":"2026-09-23T10:25:04.914794Z","steps":["trace[43458093] 'read index received' (duration: 195.121492ms)","trace[43458093] 'applied index is now lower than readState.Index' (duration: 18.148057ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T10:25:04.915165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.604236ms","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-09-23T10:25:04.915221Z","caller":"traceutil/trace.go:171","msg":"trace[878769451] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:724; }","duration":"213.733262ms","start":"2026-09-23T10:25:04.701475Z","end":"2026-09-23T10:25:04.915208Z","steps":["trace[878769451] 'agreement among raft nodes before linearized reading' (duration: 213.463668ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:25:04.969965Z","caller":"traceutil/trace.go:171","msg":"trace[1925506482] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"260.283145ms","start":"2026-09-23T10:25:04.709661Z","end":"2026-09-23T10:25:04.969945Z","steps":["trace[1925506482] 'process raft request' (duration: 259.913686ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:25:04.970065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.025058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-09-23T10:25:04.970176Z","caller":"traceutil/trace.go:171","msg":"trace[1040846821] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:725; }","duration":"106.221219ms","start":"2026-09-23T10:25:04.863926Z","end":"2026-09-23T10:25:04.970147Z","steps":["trace[1040846821] 'agreement among raft nodes before linearized reading' (duration: 105.862411ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:25:16.062181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.917161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-23T10:25:16.062446Z","caller":"traceutil/trace.go:171","msg":"trace[896714018] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:807; }","duration":"188.220449ms","start":"2026-09-23T10:25:15.87419Z","end":"2026-09-23T10:25:16.062411Z","steps":["trace[896714018] 'range keys from in-memory index tree' (duration: 187.831077ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:25:16.062238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.211191ms","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-09-23T10:25:16.062849Z","caller":"traceutil/trace.go:171","msg":"trace[1059382117] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:807; }","duration":"267.881888ms","start":"2026-09-23T10:25:15.79494Z","end":"2026-09-23T10:25:16.062822Z","steps":["trace[1059382117] 'range keys from in-memory index tree' (duration: 267.194259ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:25:46.817358Z","caller":"traceutil/trace.go:171","msg":"trace[1908492480] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"102.754814ms","start":"2026-09-23T10:25:46.714567Z","end":"2026-09-23T10:25:46.817322Z","steps":["trace[1908492480] 'process raft request' (duration: 102.453396ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:26:13.297028Z","caller":"traceutil/trace.go:171","msg":"trace[1167521255] transaction","detail":"{read_only:false; response_revision:1497; number_of_response:1; }","duration":"130.474294ms","start":"2026-09-23T10:26:13.166523Z","end":"2026-09-23T10:26:13.296998Z","steps":["trace[1167521255] 'process raft request' (duration: 125.678405ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:26:29.583878Z","caller":"traceutil/trace.go:171","msg":"trace[830736037] transaction","detail":"{read_only:false; response_revision:1792; number_of_response:1; }","duration":"102.538032ms","start":"2026-09-23T10:26:29.481317Z","end":"2026-09-23T10:26:29.583855Z","steps":["trace[830736037] 'process raft request' (duration: 102.414174ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:26:38.131083Z","caller":"traceutil/trace.go:171","msg":"trace[1058328046] transaction","detail":"{read_only:false; response_revision:1912; number_of_response:1; }","duration":"112.183484ms","start":"2026-09-23T10:26:38.018849Z","end":"2026-09-23T10:26:38.131033Z","steps":["trace[1058328046] 'process raft request' (duration: 111.757855ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:26:38.242662Z","caller":"traceutil/trace.go:171","msg":"trace[1075485990] transaction","detail":"{read_only:false; response_revision:1913; number_of_response:1; }","duration":"212.104042ms","start":"2026-09-23T10:26:38.030471Z","end":"2026-09-23T10:26:38.242575Z","steps":["trace[1075485990] 'process raft request' (duration: 172.456935ms)","trace[1075485990] 'compare' (duration: 39.275182ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T10:26:38.643477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.828131ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-23T10:26:38.643584Z","caller":"traceutil/trace.go:171","msg":"trace[1697634648] transaction","detail":"{read_only:false; response_revision:1916; number_of_response:1; }","duration":"392.536706ms","start":"2026-09-23T10:26:38.251027Z","end":"2026-09-23T10:26:38.643564Z","steps":["trace[1697634648] 'process raft request' (duration: 251.536473ms)","trace[1697634648] 'compare' (duration: 140.426624ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T10:26:38.643689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:26:38.251005Z","time spent":"392.607382ms","remote":"127.0.0.1:40846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1530,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T10:26:38.645716Z","caller":"traceutil/trace.go:171","msg":"trace[828642050] transaction","detail":"{read_only:false; response_revision:1917; number_of_response:1; }","duration":"394.543095ms","start":"2026-09-23T10:26:38.251157Z","end":"2026-09-23T10:26:38.6457Z","steps":["trace[828642050] 'process raft request' (duration: 394.288738ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:26:38.645814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:26:38.251127Z","time spent":"394.637162ms","remote":"127.0.0.1:40842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T10:26:38.646204Z","caller":"traceutil/trace.go:171","msg":"trace[412896284] transaction","detail":"{read_only:false; response_revision:1918; number_of_response:1; }","duration":"395.031759ms","start":"2026-09-23T10:26:38.251158Z","end":"2026-09-23T10:26:38.64619Z","steps":["trace[412896284] 'process raft request' (duration: 394.412466ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:26:38.646254Z","caller":"traceutil/trace.go:171","msg":"trace[1034431874] transaction","detail":"{read_only:false; response_revision:1921; number_of_response:1; }","duration":"389.573032ms","start":"2026-09-23T10:26:38.256653Z","end":"2026-09-23T10:26:38.646226Z","steps":["trace[1034431874] 'process raft request' (duration: 389.506638ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:26:38.646306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:26:38.251127Z","time spent":"395.119515ms","remote":"127.0.0.1:40846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1472,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T10:26:38.646442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:26:38.256615Z","time spent":"389.722383ms","remote":"127.0.0.1:40738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3728,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T10:26:38.646448Z","caller":"traceutil/trace.go:171","msg":"trace[1584818220] transaction","detail":"{read_only:false; response_revision:1920; number_of_response:1; }","duration":"394.03578ms","start":"2026-09-23T10:26:38.252359Z","end":"2026-09-23T10:26:38.646395Z","steps":["trace[1584818220] 'process raft request' (duration: 393.690566ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:26:38.646551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:26:38.252353Z","time spent":"394.139427ms","remote":"127.0.0.1:40710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":894,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T10:26:38.646781Z","caller":"traceutil/trace.go:171","msg":"trace[512503046] transaction","detail":"{read_only:false; response_revision:1919; number_of_response:1; }","duration":"394.60855ms","start":"2026-09-23T10:26:38.252158Z","end":"2026-09-23T10:26:38.646767Z","steps":["trace[512503046] 'process raft request' (duration: 393.464782ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:26:38.646854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:26:38.252148Z","time spent":"394.668134ms","remote":"127.0.0.1:40710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":978,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T10:26:44.397468Z","caller":"traceutil/trace.go:171","msg":"trace[1957350991] transaction","detail":"{read_only:false; response_revision:1974; number_of_response:1; }","duration":"132.254384ms","start":"2026-09-23T10:26:44.265174Z","end":"2026-09-23T10:26:44.397428Z","steps":["trace[1957350991] 'process raft request' (duration: 131.973465ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:26:44.39856Z","caller":"traceutil/trace.go:171","msg":"trace[1935803589] linearizableReadLoop","detail":"{readStateIndex:2050; appliedIndex:2048; }","duration":"105.131749ms","start":"2026-09-23T10:26:44.293385Z","end":"2026-09-23T10:26:44.398517Z","steps":["trace[1935803589] 'read index received' (duration: 103.754043ms)","trace[1935803589] 'applied index is now lower than readState.Index' (duration: 1.376255ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T10:26:44.39867Z","caller":"traceutil/trace.go:171","msg":"trace[965335403] transaction","detail":"{read_only:false; response_revision:1975; number_of_response:1; }","duration":"128.399966ms","start":"2026-09-23T10:26:44.270219Z","end":"2026-09-23T10:26:44.398619Z","steps":["trace[965335403] 'process raft request' (duration: 128.075303ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:26:44.399336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.912554ms","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-09-23T10:26:44.399409Z","caller":"traceutil/trace.go:171","msg":"trace[660785234] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1975; }","duration":"106.050963ms","start":"2026-09-23T10:26:44.293335Z","end":"2026-09-23T10:26:44.399386Z","steps":["trace[660785234] 'agreement among raft nodes before linearized reading' (duration: 105.383146ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:26:50.806014Z","caller":"traceutil/trace.go:171","msg":"trace[1840058985] transaction","detail":"{read_only:false; response_revision:2022; number_of_response:1; }","duration":"391.704347ms","start":"2026-09-23T10:26:50.414276Z","end":"2026-09-23T10:26:50.80598Z","steps":["trace[1840058985] 'process raft request' (duration: 319.925964ms)","trace[1840058985] 'compare' (duration: 70.552707ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T10:26:50.806194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:26:50.414256Z","time spent":"391.829036ms","remote":"127.0.0.1:40842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T10:26:50.832812Z","caller":"traceutil/trace.go:171","msg":"trace[1821669529] linearizableReadLoop","detail":"{readStateIndex:2100; appliedIndex:2098; }","duration":"394.279248ms","start":"2026-09-23T10:26:50.438511Z","end":"2026-09-23T10:26:50.832791Z","steps":["trace[1821669529] 'read index received' (duration: 295.663308ms)","trace[1821669529] 'applied index is now lower than readState.Index' (duration: 98.61487ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T10:26:50.832908Z","caller":"traceutil/trace.go:171","msg":"trace[100833621] transaction","detail":"{read_only:false; response_revision:2023; number_of_response:1; }","duration":"417.020818ms","start":"2026-09-23T10:26:50.415867Z","end":"2026-09-23T10:26:50.832888Z","steps":["trace[100833621] 'process raft request' (duration: 416.807584ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:26:50.83303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:26:50.415857Z","time spent":"417.102804ms","remote":"127.0.0.1:40842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T10:26:50.833235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.716437ms","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-09-23T10:26:50.833377Z","caller":"traceutil/trace.go:171","msg":"trace[1433782722] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2023; }","duration":"394.855968ms","start":"2026-09-23T10:26:50.438497Z","end":"2026-09-23T10:26:50.833353Z","steps":["trace[1433782722] 'agreement among raft nodes before linearized reading' (duration: 394.412257ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:26:50.833544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:26:50.438411Z","time spent":"395.112846ms","remote":"127.0.0.1:39206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-09-23T10:26:51.314679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.669348ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-23T10:26:51.314939Z","caller":"traceutil/trace.go:171","msg":"trace[34494048] transaction","detail":"{read_only:false; response_revision:2025; number_of_response:1; }","duration":"417.44812ms","start":"2026-09-23T10:26:50.897462Z","end":"2026-09-23T10:26:51.314911Z","steps":["trace[34494048] 'process raft request' (duration: 77.442579ms)","trace[34494048] 'compare' (duration: 339.398839ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T10:26:51.315123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:26:50.897439Z","time spent":"417.640624ms","remote":"127.0.0.1:40842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T10:26:51.49288Z","caller":"traceutil/trace.go:171","msg":"trace[6368292] linearizableReadLoop","detail":"{readStateIndex:2103; appliedIndex:2102; }","duration":"146.902209ms","start":"2026-09-23T10:26:51.345936Z","end":"2026-09-23T10:26:51.492838Z","steps":["trace[6368292] 'read index received' (duration: 138.459118ms)","trace[6368292] 'applied index is now lower than readState.Index' (duration: 8.442071ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T10:26:51.493009Z","caller":"traceutil/trace.go:171","msg":"trace[1375269565] transaction","detail":"{read_only:false; response_revision:2026; number_of_response:1; }","duration":"151.733658ms","start":"2026-09-23T10:26:51.341265Z","end":"2026-09-23T10:26:51.492998Z","steps":["trace[1375269565] 'process raft request' (duration: 143.132525ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:26:51.493263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.320459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11980"} {"level":"info","ts":"2026-09-23T10:26:51.493305Z","caller":"traceutil/trace.go:171","msg":"trace[1610624434] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2026; }","duration":"147.443527ms","start":"2026-09-23T10:26:51.345851Z","end":"2026-09-23T10:26:51.493295Z","steps":["trace[1610624434] 'agreement among raft nodes before linearized reading' (duration: 147.276236ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:26:54.40413Z","caller":"traceutil/trace.go:171","msg":"trace[631531151] transaction","detail":"{read_only:false; response_revision:2053; number_of_response:1; }","duration":"140.135873ms","start":"2026-09-23T10:26:54.263966Z","end":"2026-09-23T10:26:54.404102Z","steps":["trace[631531151] 'process raft request' (duration: 139.917778ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:27:18.675551Z","caller":"traceutil/trace.go:171","msg":"trace[392578709] transaction","detail":"{read_only:false; response_revision:2187; number_of_response:1; }","duration":"152.114939ms","start":"2026-09-23T10:27:18.523404Z","end":"2026-09-23T10:27:18.675519Z","steps":["trace[392578709] 'process raft request' (duration: 151.85519ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:27:19.185739Z","caller":"traceutil/trace.go:171","msg":"trace[489542286] transaction","detail":"{read_only:false; response_revision:2188; number_of_response:1; }","duration":"263.66646ms","start":"2026-09-23T10:27:18.922047Z","end":"2026-09-23T10:27:19.185713Z","steps":["trace[489542286] 'process raft request' (duration: 263.513309ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:27:19.239957Z","caller":"traceutil/trace.go:171","msg":"trace[408171292] linearizableReadLoop","detail":"{readStateIndex:2272; appliedIndex:2270; }","duration":"220.685706ms","start":"2026-09-23T10:27:19.019246Z","end":"2026-09-23T10:27:19.239932Z","steps":["trace[408171292] 'read index received' (duration: 166.311852ms)","trace[408171292] 'applied index is now lower than readState.Index' (duration: 54.372284ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T10:27:19.240367Z","caller":"traceutil/trace.go:171","msg":"trace[903547098] transaction","detail":"{read_only:false; response_revision:2189; number_of_response:1; }","duration":"287.868405ms","start":"2026-09-23T10:27:18.952484Z","end":"2026-09-23T10:27:19.240352Z","steps":["trace[903547098] 'process raft request' (duration: 287.252041ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:27:19.240719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.454631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-23T10:27:19.240771Z","caller":"traceutil/trace.go:171","msg":"trace[1973147021] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2189; }","duration":"221.551498ms","start":"2026-09-23T10:27:19.019206Z","end":"2026-09-23T10:27:19.240757Z","steps":["trace[1973147021] 'agreement among raft nodes before linearized reading' (duration: 221.396037ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:27:19.241044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.817656ms","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-09-23T10:27:19.241091Z","caller":"traceutil/trace.go:171","msg":"trace[632814440] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2189; }","duration":"153.867621ms","start":"2026-09-23T10:27:19.087211Z","end":"2026-09-23T10:27:19.241079Z","steps":["trace[632814440] 'agreement among raft nodes before linearized reading' (duration: 153.741071ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:27:19.644934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.118383ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-23T10:27:19.645013Z","caller":"traceutil/trace.go:171","msg":"trace[699841661] transaction","detail":"{read_only:false; response_revision:2190; number_of_response:1; }","duration":"398.785562ms","start":"2026-09-23T10:27:19.246212Z","end":"2026-09-23T10:27:19.644997Z","steps":["trace[699841661] 'process raft request' (duration: 168.53381ms)","trace[699841661] 'compare' (duration: 229.899807ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T10:27:19.645052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:27:19.246192Z","time spent":"398.840706ms","remote":"127.0.0.1:40842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T10:27:19.648202Z","caller":"traceutil/trace.go:171","msg":"trace[1714312516] linearizableReadLoop","detail":"{readStateIndex:2274; appliedIndex:2273; }","duration":"174.353427ms","start":"2026-09-23T10:27:19.473821Z","end":"2026-09-23T10:27:19.648174Z","steps":["trace[1714312516] 'read index received' (duration: 174.149343ms)","trace[1714312516] 'applied index is now lower than readState.Index' (duration: 202.834µs)"],"step_count":2} {"level":"info","ts":"2026-09-23T10:27:19.648226Z","caller":"traceutil/trace.go:171","msg":"trace[1983490381] transaction","detail":"{read_only:false; response_revision:2191; number_of_response:1; }","duration":"332.235325ms","start":"2026-09-23T10:27:19.315979Z","end":"2026-09-23T10:27:19.648215Z","steps":["trace[1983490381] 'process raft request' (duration: 332.051822ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:27:19.648359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.53387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-09-23T10:27:19.648348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:27:19.315956Z","time spent":"332.328342ms","remote":"127.0.0.1:40722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8559,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T10:27:19.648384Z","caller":"traceutil/trace.go:171","msg":"trace[1473703002] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2191; }","duration":"174.595445ms","start":"2026-09-23T10:27:19.473782Z","end":"2026-09-23T10:27:19.648377Z","steps":["trace[1473703002] 'agreement among raft nodes before linearized reading' (duration: 174.467406ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:29:01.8468Z","caller":"traceutil/trace.go:171","msg":"trace[1212938614] transaction","detail":"{read_only:false; response_revision:2847; number_of_response:1; }","duration":"159.831291ms","start":"2026-09-23T10:29:01.686922Z","end":"2026-09-23T10:29:01.846753Z","steps":["trace[1212938614] 'process raft request' (duration: 147.00192ms)","trace[1212938614] 'compare' (duration: 12.590638ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T10:29:02.32664Z","caller":"traceutil/trace.go:171","msg":"trace[1745143669] transaction","detail":"{read_only:false; response_revision:2848; number_of_response:1; }","duration":"130.515837ms","start":"2026-09-23T10:29:02.196108Z","end":"2026-09-23T10:29:02.326623Z","steps":["trace[1745143669] 'process raft request' (duration: 130.400385ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:29:02.510512Z","caller":"traceutil/trace.go:171","msg":"trace[1167421758] linearizableReadLoop","detail":"{readStateIndex:2955; appliedIndex:2953; }","duration":"311.051671ms","start":"2026-09-23T10:29:02.199433Z","end":"2026-09-23T10:29:02.510485Z","steps":["trace[1167421758] 'read index received' (duration: 127.158522ms)","trace[1167421758] 'applied index is now lower than readState.Index' (duration: 183.892029ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T10:29:02.510801Z","caller":"traceutil/trace.go:171","msg":"trace[1607672622] transaction","detail":"{read_only:false; response_revision:2849; number_of_response:1; }","duration":"313.241938ms","start":"2026-09-23T10:29:02.197503Z","end":"2026-09-23T10:29:02.510745Z","steps":["trace[1607672622] 'process raft request' (duration: 312.816211ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:29:02.510957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.502028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-09-23T10:29:02.511012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:29:02.197481Z","time spent":"313.4324ms","remote":"127.0.0.1:40842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T10:29:02.511025Z","caller":"traceutil/trace.go:171","msg":"trace[432462696] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2849; }","duration":"311.600001ms","start":"2026-09-23T10:29:02.199406Z","end":"2026-09-23T10:29:02.511006Z","steps":["trace[432462696] 'agreement among raft nodes before linearized reading' (duration: 311.351347ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:29:02.511269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:29:02.199397Z","time spent":"311.853255ms","remote":"127.0.0.1:40842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-23T10:29:02.511294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.705558ms","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-09-23T10:29:02.511739Z","caller":"traceutil/trace.go:171","msg":"trace[809537806] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2849; }","duration":"147.169367ms","start":"2026-09-23T10:29:02.364547Z","end":"2026-09-23T10:29:02.511716Z","steps":["trace[809537806] 'agreement among raft nodes before linearized reading' (duration: 146.653178ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:29:02.511991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.232211ms","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-09-23T10:29:02.512073Z","caller":"traceutil/trace.go:171","msg":"trace[2146006147] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2849; }","duration":"159.348042ms","start":"2026-09-23T10:29:02.352713Z","end":"2026-09-23T10:29:02.512061Z","steps":["trace[2146006147] 'agreement among raft nodes before linearized reading' (duration: 158.167063ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:33:13.861258Z","caller":"traceutil/trace.go:171","msg":"trace[1338205503] linearizableReadLoop","detail":"{readStateIndex:4855; appliedIndex:4853; }","duration":"200.207994ms","start":"2026-09-23T10:33:13.661029Z","end":"2026-09-23T10:33:13.861237Z","steps":["trace[1338205503] 'read index received' (duration: 152.67533ms)","trace[1338205503] 'applied index is now lower than readState.Index' (duration: 47.531794ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T10:33:13.861396Z","caller":"traceutil/trace.go:171","msg":"trace[536337615] transaction","detail":"{read_only:false; response_revision:4674; number_of_response:1; }","duration":"221.313298ms","start":"2026-09-23T10:33:13.64007Z","end":"2026-09-23T10:33:13.861383Z","steps":["trace[536337615] 'process raft request' (duration: 221.057222ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:33:13.86146Z","caller":"traceutil/trace.go:171","msg":"trace[45236668] transaction","detail":"{read_only:false; response_revision:4673; number_of_response:1; }","duration":"223.275518ms","start":"2026-09-23T10:33:13.63815Z","end":"2026-09-23T10:33:13.861425Z","steps":["trace[45236668] 'process raft request' (duration: 175.542222ms)","trace[45236668] 'compare' (duration: 47.27464ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T10:33:13.861881Z","caller":"traceutil/trace.go:171","msg":"trace[2008631328] transaction","detail":"{read_only:false; response_revision:4675; number_of_response:1; }","duration":"200.372114ms","start":"2026-09-23T10:33:13.661478Z","end":"2026-09-23T10:33:13.86185Z","steps":["trace[2008631328] 'process raft request' (duration: 199.735657ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:33:13.862125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.929195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-tls\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-23T10:33:13.862161Z","caller":"traceutil/trace.go:171","msg":"trace[1965347328] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-tls; range_end:; response_count:0; response_revision:4677; }","duration":"140.00665ms","start":"2026-09-23T10:33:13.722145Z","end":"2026-09-23T10:33:13.862151Z","steps":["trace[1965347328] 'agreement among raft nodes before linearized reading' (duration: 139.926615ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:33:13.862221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.18768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-admission-patch-ns2x4\" ","response":"range_response_count:1 size:3378"} {"level":"info","ts":"2026-09-23T10:33:13.863102Z","caller":"traceutil/trace.go:171","msg":"trace[246899730] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-admission-patch-ns2x4; range_end:; response_count:1; response_revision:4677; }","duration":"202.08502ms","start":"2026-09-23T10:33:13.661002Z","end":"2026-09-23T10:33:13.863087Z","steps":["trace[246899730] 'agreement among raft nodes before linearized reading' (duration: 200.491111ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:33:13.862465Z","caller":"traceutil/trace.go:171","msg":"trace[929224791] transaction","detail":"{read_only:false; response_revision:4676; number_of_response:1; }","duration":"185.260446ms","start":"2026-09-23T10:33:13.677192Z","end":"2026-09-23T10:33:13.862452Z","steps":["trace[929224791] 'process raft request' (duration: 184.350336ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:33:14.212408Z","caller":"traceutil/trace.go:171","msg":"trace[1809918107] transaction","detail":"{read_only:false; response_revision:4688; number_of_response:1; }","duration":"106.879937ms","start":"2026-09-23T10:33:14.1055Z","end":"2026-09-23T10:33:14.21238Z","steps":["trace[1809918107] 'process raft request' (duration: 94.45501ms)","trace[1809918107] 'compare' (duration: 12.29391ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T10:33:28.314881Z","caller":"traceutil/trace.go:171","msg":"trace[1909447457] linearizableReadLoop","detail":"{readStateIndex:5200; appliedIndex:5198; }","duration":"271.504787ms","start":"2026-09-23T10:33:28.043349Z","end":"2026-09-23T10:33:28.314853Z","steps":["trace[1909447457] 'read index received' (duration: 2.577037ms)","trace[1909447457] 'applied index is now lower than readState.Index' (duration: 268.9269ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T10:33:28.314969Z","caller":"traceutil/trace.go:171","msg":"trace[1636094227] transaction","detail":"{read_only:false; response_revision:5008; number_of_response:1; }","duration":"300.733188ms","start":"2026-09-23T10:33:28.014183Z","end":"2026-09-23T10:33:28.314916Z","steps":["trace[1636094227] 'process raft request' (duration: 300.447311ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:33:28.315204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T10:33:28.014163Z","time spent":"300.903437ms","remote":"127.0.0.1:40738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13279,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T10:33:28.31521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.880408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-09-23T10:33:28.315262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.04ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-23T10:33:28.315284Z","caller":"traceutil/trace.go:171","msg":"trace[1639100139] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5008; }","duration":"271.983694ms","start":"2026-09-23T10:33:28.043278Z","end":"2026-09-23T10:33:28.315261Z","steps":["trace[1639100139] 'agreement among raft nodes before linearized reading' (duration: 271.784543ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:33:28.315203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.576001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-09-23T10:33:28.315332Z","caller":"traceutil/trace.go:171","msg":"trace[1734460731] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5008; }","duration":"206.133775ms","start":"2026-09-23T10:33:28.109183Z","end":"2026-09-23T10:33:28.315316Z","steps":["trace[1734460731] 'agreement among raft nodes before linearized reading' (duration: 205.936134ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:33:28.315357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.009862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-09-23T10:33:28.315282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.19735ms","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-09-23T10:33:28.315395Z","caller":"traceutil/trace.go:171","msg":"trace[2038174999] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5008; }","duration":"217.084727ms","start":"2026-09-23T10:33:28.098298Z","end":"2026-09-23T10:33:28.315382Z","steps":["trace[2038174999] 'agreement among raft nodes before linearized reading' (duration: 216.998992ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:33:28.315411Z","caller":"traceutil/trace.go:171","msg":"trace[1599173460] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5008; }","duration":"240.767211ms","start":"2026-09-23T10:33:28.074589Z","end":"2026-09-23T10:33:28.315356Z","steps":["trace[1599173460] 'agreement among raft nodes before linearized reading' (duration: 240.480395ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:33:28.315493Z","caller":"traceutil/trace.go:171","msg":"trace[99082862] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5008; }","duration":"212.37962ms","start":"2026-09-23T10:33:28.103032Z","end":"2026-09-23T10:33:28.315412Z","steps":["trace[99082862] 'agreement among raft nodes before linearized reading' (duration: 212.195359ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:33:28.746839Z","caller":"traceutil/trace.go:171","msg":"trace[1546654779] transaction","detail":"{read_only:false; response_revision:5018; number_of_response:1; }","duration":"268.930431ms","start":"2026-09-23T10:33:28.477882Z","end":"2026-09-23T10:33:28.746812Z","steps":["trace[1546654779] 'process raft request' (duration: 268.800704ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:33:28.767887Z","caller":"traceutil/trace.go:171","msg":"trace[1461029183] transaction","detail":"{read_only:false; response_revision:5019; number_of_response:1; }","duration":"137.799635ms","start":"2026-09-23T10:33:28.63006Z","end":"2026-09-23T10:33:28.767859Z","steps":["trace[1461029183] 'process raft request' (duration: 137.613714ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:33:42.662906Z","caller":"traceutil/trace.go:171","msg":"trace[1562717375] transaction","detail":"{read_only:false; response_revision:5197; number_of_response:1; }","duration":"194.061946ms","start":"2026-09-23T10:33:42.468817Z","end":"2026-09-23T10:33:42.662879Z","steps":["trace[1562717375] 'process raft request' (duration: 143.176806ms)","trace[1562717375] 'compare' (duration: 50.792184ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T10:33:42.665171Z","caller":"traceutil/trace.go:171","msg":"trace[147963657] linearizableReadLoop","detail":"{readStateIndex:5396; appliedIndex:5393; }","duration":"160.461518ms","start":"2026-09-23T10:33:42.504684Z","end":"2026-09-23T10:33:42.665145Z","steps":["trace[147963657] 'read index received' (duration: 107.322539ms)","trace[147963657] 'applied index is now lower than readState.Index' (duration: 53.136029ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T10:33:42.665234Z","caller":"traceutil/trace.go:171","msg":"trace[611822347] transaction","detail":"{read_only:false; response_revision:5198; number_of_response:1; }","duration":"195.799505ms","start":"2026-09-23T10:33:42.469387Z","end":"2026-09-23T10:33:42.665186Z","steps":["trace[611822347] 'process raft request' (duration: 195.592793ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:33:42.66529Z","caller":"traceutil/trace.go:171","msg":"trace[1653973630] transaction","detail":"{read_only:false; response_revision:5199; number_of_response:1; }","duration":"195.493128ms","start":"2026-09-23T10:33:42.469788Z","end":"2026-09-23T10:33:42.665281Z","steps":["trace[1653973630] 'process raft request' (duration: 195.318998ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:33:42.665539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.838029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"info","ts":"2026-09-23T10:33:42.66557Z","caller":"traceutil/trace.go:171","msg":"trace[1123048800] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5199; }","duration":"160.919954ms","start":"2026-09-23T10:33:42.504642Z","end":"2026-09-23T10:33:42.665562Z","steps":["trace[1123048800] 'agreement among raft nodes before linearized reading' (duration: 160.660359ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T10:33:42.66566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.729568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-76b9f8495c-r76hj\" ","response":"range_response_count:1 size:11847"} {"level":"info","ts":"2026-09-23T10:33:42.66579Z","caller":"traceutil/trace.go:171","msg":"trace[2114214590] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-grafana-76b9f8495c-r76hj; range_end:; response_count:1; response_revision:5199; }","duration":"135.94493ms","start":"2026-09-23T10:33:42.529832Z","end":"2026-09-23T10:33:42.665777Z","steps":["trace[2114214590] 'agreement among raft nodes before linearized reading' (duration: 135.666714ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:34:10.544962Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2892} {"level":"info","ts":"2026-09-23T10:34:10.642559Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2892,"took":"95.802445ms","hash":2857948319,"current-db-size-bytes":52686848,"current-db-size":"53 MB","current-db-size-in-use-bytes":27639808,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-23T10:34:10.642653Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2857948319,"revision":2892,"compact-revision":-1} {"level":"info","ts":"2026-09-23T10:37:29.998415Z","caller":"traceutil/trace.go:171","msg":"trace[1190173842] linearizableReadLoop","detail":"{readStateIndex:7083; appliedIndex:7082; }","duration":"101.920693ms","start":"2026-09-23T10:37:29.89647Z","end":"2026-09-23T10:37:29.99839Z","steps":["trace[1190173842] 'read index received' (duration: 62.934828ms)","trace[1190173842] 'applied index is now lower than readState.Index' (duration: 38.984945ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T10:37:29.998629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.138493ms","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-09-23T10:37:29.998674Z","caller":"traceutil/trace.go:171","msg":"trace[2044444250] transaction","detail":"{read_only:false; response_revision:6832; number_of_response:1; }","duration":"102.615315ms","start":"2026-09-23T10:37:29.896047Z","end":"2026-09-23T10:37:29.998663Z","steps":["trace[2044444250] 'process raft request' (duration: 63.44677ms)","trace[2044444250] 'compare' (duration: 38.751405ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T10:37:29.998679Z","caller":"traceutil/trace.go:171","msg":"trace[1748979520] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6832; }","duration":"102.231973ms","start":"2026-09-23T10:37:29.896433Z","end":"2026-09-23T10:37:29.998665Z","steps":["trace[1748979520] 'agreement among raft nodes before linearized reading' (duration: 102.049493ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T10:39:10.550189Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5376} {"level":"info","ts":"2026-09-23T10:39:10.676611Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5376,"took":"124.844025ms","hash":1888380624,"current-db-size-bytes":52686848,"current-db-size":"53 MB","current-db-size-in-use-bytes":18731008,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-23T10:39:10.676677Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1888380624,"revision":5376,"compact-revision":2892}