{"level":"warn","ts":"2026-09-17T20:38:08.484783Z","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-17T20:38:08.484876Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.25: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.25:2380","--initial-cluster=instance=https://162.253.55.25:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.25:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.25: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-17T20:38:08.484934Z","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-17T20:38:08.484942Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.25:2380"]} {"level":"info","ts":"2026-09-17T20:38:08.484965Z","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-17T20:38:08.485576Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.25:2379"]} {"level":"info","ts":"2026-09-17T20:38:08.485647Z","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.25:2380"],"listen-peer-urls":["https://162.253.55.25:2380"],"advertise-client-urls":["https://162.253.55.25:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.25:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.25: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-17T20:38:08.49104Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.180452ms"} {"level":"info","ts":"2026-09-17T20:38:08.499973Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"afeb1bf8b692f9e8","cluster-id":"6bfcc13baae99738"} {"level":"info","ts":"2026-09-17T20:38:08.500047Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"afeb1bf8b692f9e8 switched to configuration voters=()"} {"level":"info","ts":"2026-09-17T20:38:08.500064Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"afeb1bf8b692f9e8 became follower at term 0"} {"level":"info","ts":"2026-09-17T20:38:08.500085Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft afeb1bf8b692f9e8 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-17T20:38:08.500104Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"afeb1bf8b692f9e8 became follower at term 1"} {"level":"info","ts":"2026-09-17T20:38:08.500139Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"afeb1bf8b692f9e8 switched to configuration voters=(12676256331193317864)"} {"level":"warn","ts":"2026-09-17T20:38:08.506143Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-17T20:38:08.507414Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-17T20:38:08.508649Z","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-17T20:38:08.510728Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"afeb1bf8b692f9e8","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-17T20:38:08.51422Z","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-17T20:38:08.516584Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"afeb1bf8b692f9e8","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-17T20:38:08.517276Z","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-17T20:38:08.517338Z","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-17T20:38:08.517349Z","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-17T20:38:08.517313Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"afeb1bf8b692f9e8 switched to configuration voters=(12676256331193317864)"} {"level":"info","ts":"2026-09-17T20:38:08.517552Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6bfcc13baae99738","local-member-id":"afeb1bf8b692f9e8","added-peer-id":"afeb1bf8b692f9e8","added-peer-peer-urls":["https://162.253.55.25:2380"]} {"level":"info","ts":"2026-09-17T20:38:08.517566Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.25:2380"} {"level":"info","ts":"2026-09-17T20:38:08.517649Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.25:2380"} {"level":"info","ts":"2026-09-17T20:38:08.517853Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"afeb1bf8b692f9e8","initial-advertise-peer-urls":["https://162.253.55.25:2380"],"listen-peer-urls":["https://162.253.55.25:2380"],"advertise-client-urls":["https://162.253.55.25:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.25:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-17T20:38:08.517907Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-17T20:38:09.101427Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"afeb1bf8b692f9e8 is starting a new election at term 1"} {"level":"info","ts":"2026-09-17T20:38:09.101492Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"afeb1bf8b692f9e8 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-17T20:38:09.101539Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"afeb1bf8b692f9e8 received MsgPreVoteResp from afeb1bf8b692f9e8 at term 1"} {"level":"info","ts":"2026-09-17T20:38:09.10156Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"afeb1bf8b692f9e8 became candidate at term 2"} {"level":"info","ts":"2026-09-17T20:38:09.101571Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"afeb1bf8b692f9e8 received MsgVoteResp from afeb1bf8b692f9e8 at term 2"} {"level":"info","ts":"2026-09-17T20:38:09.101601Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"afeb1bf8b692f9e8 became leader at term 2"} {"level":"info","ts":"2026-09-17T20:38:09.10162Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: afeb1bf8b692f9e8 elected leader afeb1bf8b692f9e8 at term 2"} {"level":"info","ts":"2026-09-17T20:38:09.115159Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-17T20:38:09.115538Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"afeb1bf8b692f9e8","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.25:2379]}","request-path":"/0/members/afeb1bf8b692f9e8/attributes","cluster-id":"6bfcc13baae99738","publish-timeout":"7s"} {"level":"info","ts":"2026-09-17T20:38:09.115575Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-17T20:38:09.115816Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-17T20:38:09.116045Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-17T20:38:09.116128Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-17T20:38:09.116278Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6bfcc13baae99738","local-member-id":"afeb1bf8b692f9e8","cluster-version":"3.5"} {"level":"info","ts":"2026-09-17T20:38:09.116385Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-17T20:38:09.116414Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-17T20:38:09.118894Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.25:2379"} {"level":"info","ts":"2026-09-17T20:38:09.119736Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-17T20:38:33.644771Z","caller":"traceutil/trace.go:171","msg":"trace[622733359] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"180.83788ms","start":"2026-09-17T20:38:33.463909Z","end":"2026-09-17T20:38:33.644747Z","steps":["trace[622733359] 'process raft request' (duration: 180.703586ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:38:47.209668Z","caller":"traceutil/trace.go:171","msg":"trace[887034544] transaction","detail":"{read_only:false; number_of_response:1; response_revision:509; }","duration":"129.929302ms","start":"2026-09-17T20:38:47.079656Z","end":"2026-09-17T20:38:47.209585Z","steps":["trace[887034544] 'process raft request' (duration: 129.885631ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:38:47.209844Z","caller":"traceutil/trace.go:171","msg":"trace[760806712] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"130.289382ms","start":"2026-09-17T20:38:47.079528Z","end":"2026-09-17T20:38:47.209817Z","steps":["trace[760806712] 'process raft request' (duration: 129.489969ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:38:47.210005Z","caller":"traceutil/trace.go:171","msg":"trace[1937262091] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"130.369325ms","start":"2026-09-17T20:38:47.079627Z","end":"2026-09-17T20:38:47.209997Z","steps":["trace[1937262091] 'process raft request' (duration: 129.881911ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:38:47.210096Z","caller":"traceutil/trace.go:171","msg":"trace[632227545] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"130.496748ms","start":"2026-09-17T20:38:47.079592Z","end":"2026-09-17T20:38:47.210089Z","steps":["trace[632227545] 'process raft request' (duration: 129.761667ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:38:47.226105Z","caller":"traceutil/trace.go:171","msg":"trace[2014407819] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"135.371836ms","start":"2026-09-17T20:38:47.090699Z","end":"2026-09-17T20:38:47.226071Z","steps":["trace[2014407819] 'process raft request' (duration: 127.509402ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:38:47.22634Z","caller":"traceutil/trace.go:171","msg":"trace[158844648] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"132.021381ms","start":"2026-09-17T20:38:47.094292Z","end":"2026-09-17T20:38:47.226314Z","steps":["trace[158844648] 'process raft request' (duration: 123.995092ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:38:47.226851Z","caller":"traceutil/trace.go:171","msg":"trace[1450435752] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"131.387042ms","start":"2026-09-17T20:38:47.095449Z","end":"2026-09-17T20:38:47.226836Z","steps":["trace[1450435752] 'process raft request' (duration: 122.88475ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:38:47.228562Z","caller":"traceutil/trace.go:171","msg":"trace[172835792] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"143.610621ms","start":"2026-09-17T20:38:47.08335Z","end":"2026-09-17T20:38:47.226961Z","steps":["trace[172835792] 'process raft request' (duration: 134.723647ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:38:47.228708Z","caller":"traceutil/trace.go:171","msg":"trace[1911575442] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"129.748516ms","start":"2026-09-17T20:38:47.097246Z","end":"2026-09-17T20:38:47.226995Z","steps":["trace[1911575442] 'process raft request' (duration: 121.429749ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:38:47.492006Z","caller":"traceutil/trace.go:171","msg":"trace[154516616] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"158.127564ms","start":"2026-09-17T20:38:47.333849Z","end":"2026-09-17T20:38:47.491977Z","steps":["trace[154516616] 'process raft request' (duration: 157.899268ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:38:47.492013Z","caller":"traceutil/trace.go:171","msg":"trace[20253365] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"195.005805ms","start":"2026-09-17T20:38:47.29698Z","end":"2026-09-17T20:38:47.491986Z","steps":["trace[20253365] 'process raft request' (duration: 194.523531ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:38:47.492305Z","caller":"traceutil/trace.go:171","msg":"trace[1881235308] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"193.934854ms","start":"2026-09-17T20:38:47.298247Z","end":"2026-09-17T20:38:47.492182Z","steps":["trace[1881235308] 'process raft request' (duration: 193.340567ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:38:47.492422Z","caller":"traceutil/trace.go:171","msg":"trace[977542268] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"197.519236ms","start":"2026-09-17T20:38:47.294087Z","end":"2026-09-17T20:38:47.491607Z","steps":["trace[977542268] 'process raft request' (duration: 146.170443ms)","trace[977542268] 'compare' (duration: 50.929801ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T20:38:47.492621Z","caller":"traceutil/trace.go:171","msg":"trace[794453553] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"189.879508ms","start":"2026-09-17T20:38:47.302689Z","end":"2026-09-17T20:38:47.492568Z","steps":["trace[794453553] 'process raft request' (duration: 188.964012ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:38:48.186455Z","caller":"traceutil/trace.go:171","msg":"trace[41140509] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"128.896937ms","start":"2026-09-17T20:38:48.057529Z","end":"2026-09-17T20:38:48.186426Z","steps":["trace[41140509] 'process raft request' (duration: 53.268303ms)","trace[41140509] 'compare' (duration: 75.409718ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T20:38:48.362942Z","caller":"traceutil/trace.go:171","msg":"trace[1044290128] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"111.901108ms","start":"2026-09-17T20:38:48.251017Z","end":"2026-09-17T20:38:48.362919Z","steps":["trace[1044290128] 'process raft request' (duration: 82.497727ms)","trace[1044290128] 'compare' (duration: 29.136793ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T20:38:53.866781Z","caller":"traceutil/trace.go:171","msg":"trace[327300836] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"186.64116ms","start":"2026-09-17T20:38:53.680113Z","end":"2026-09-17T20:38:53.866754Z","steps":["trace[327300836] 'process raft request' (duration: 186.437705ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:38:54.612314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.397365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T20:38:54.612589Z","caller":"traceutil/trace.go:171","msg":"trace[1127226123] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:599; }","duration":"448.725434ms","start":"2026-09-17T20:38:54.163842Z","end":"2026-09-17T20:38:54.612567Z","steps":["trace[1127226123] 'range keys from in-memory index tree' (duration: 448.243061ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:38:54.61266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T20:38:54.163789Z","time spent":"448.856867ms","remote":"127.0.0.1:35272","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-17T20:38:54.613237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.141067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-sgf4l\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-09-17T20:38:54.613513Z","caller":"traceutil/trace.go:171","msg":"trace[464229200] range","detail":"{range_begin:/registry/pods/kube-system/cilium-sgf4l; range_end:; response_count:1; response_revision:599; }","duration":"348.336313ms","start":"2026-09-17T20:38:54.265024Z","end":"2026-09-17T20:38:54.61336Z","steps":["trace[464229200] 'range keys from in-memory index tree' (duration: 347.603482ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:38:54.613639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T20:38:54.265006Z","time spent":"348.586809ms","remote":"127.0.0.1:35464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-sgf4l\" "} {"level":"warn","ts":"2026-09-17T20:38:54.613668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.995703ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-17T20:38:54.613769Z","caller":"traceutil/trace.go:171","msg":"trace[703358149] linearizableReadLoop","detail":"{readStateIndex:621; appliedIndex:620; }","duration":"215.513305ms","start":"2026-09-17T20:38:54.398241Z","end":"2026-09-17T20:38:54.613754Z","steps":["trace[703358149] 'read index received' (duration: 45.003412ms)","trace[703358149] 'applied index is now lower than readState.Index' (duration: 170.508823ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T20:38:54.61384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.595808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-17T20:38:54.613869Z","caller":"traceutil/trace.go:171","msg":"trace[353305802] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:600; }","duration":"215.651419ms","start":"2026-09-17T20:38:54.398209Z","end":"2026-09-17T20:38:54.613861Z","steps":["trace[353305802] 'agreement among raft nodes before linearized reading' (duration: 215.591408ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:38:54.613994Z","caller":"traceutil/trace.go:171","msg":"trace[2020869137] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"229.14133ms","start":"2026-09-17T20:38:54.384786Z","end":"2026-09-17T20:38:54.613928Z","steps":["trace[2020869137] 'process raft request' (duration: 59.327165ms)","trace[2020869137] 'compare' (duration: 168.600692ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T20:38:55.09621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.878523ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-17T20:38:55.096679Z","caller":"traceutil/trace.go:171","msg":"trace[1116637497] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:621; }","duration":"337.106852ms","start":"2026-09-17T20:38:54.75955Z","end":"2026-09-17T20:38:55.096657Z","steps":["trace[1116637497] 'read index received' (duration: 41.669683ms)","trace[1116637497] 'applied index is now lower than readState.Index' (duration: 295.436009ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T20:38:55.096696Z","caller":"traceutil/trace.go:171","msg":"trace[2041269233] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"461.635037ms","start":"2026-09-17T20:38:54.635044Z","end":"2026-09-17T20:38:55.096679Z","steps":["trace[2041269233] 'process raft request' (duration: 461.408191ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:38:55.097027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.47502ms","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-17T20:38:55.097028Z","caller":"traceutil/trace.go:171","msg":"trace[1226799361] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"407.532262ms","start":"2026-09-17T20:38:54.68946Z","end":"2026-09-17T20:38:55.096992Z","steps":["trace[1226799361] 'process raft request' (duration: 407.151832ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:38:55.097087Z","caller":"traceutil/trace.go:171","msg":"trace[1657309080] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:603; }","duration":"337.570363ms","start":"2026-09-17T20:38:54.7595Z","end":"2026-09-17T20:38:55.09707Z","steps":["trace[1657309080] 'agreement among raft nodes before linearized reading' (duration: 337.383029ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:38:55.096688Z","caller":"traceutil/trace.go:171","msg":"trace[57373688] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"477.922743ms","start":"2026-09-17T20:38:54.618734Z","end":"2026-09-17T20:38:55.096657Z","steps":["trace[57373688] 'process raft request' (duration: 182.476234ms)","trace[57373688] 'compare' (duration: 294.73524ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T20:38:55.097133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T20:38:54.759487Z","time spent":"337.633184ms","remote":"127.0.0.1:35548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-17T20:38:55.097191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T20:38:54.689425Z","time spent":"407.689546ms","remote":"127.0.0.1:35520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-17T20:38:55.097302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T20:38:54.61871Z","time spent":"478.433246ms","remote":"127.0.0.1:35548","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-09-17T20:38:55.097866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T20:38:54.634991Z","time spent":"462.030177ms","remote":"127.0.0.1:35464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-17T20:38:55.10005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.884111ms","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-17T20:38:55.100123Z","caller":"traceutil/trace.go:171","msg":"trace[862556905] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:604; }","duration":"119.002124ms","start":"2026-09-17T20:38:54.981104Z","end":"2026-09-17T20:38:55.100106Z","steps":["trace[862556905] 'agreement among raft nodes before linearized reading' (duration: 118.581694ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:38:55.100315Z","caller":"traceutil/trace.go:171","msg":"trace[137966875] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"102.281498ms","start":"2026-09-17T20:38:54.998018Z","end":"2026-09-17T20:38:55.100299Z","steps":["trace[137966875] 'process raft request' (duration: 101.533779ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:39:11.351647Z","caller":"traceutil/trace.go:171","msg":"trace[262755539] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"104.905815ms","start":"2026-09-17T20:39:11.246718Z","end":"2026-09-17T20:39:11.351624Z","steps":["trace[262755539] 'process raft request' (duration: 104.848594ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:39:11.351673Z","caller":"traceutil/trace.go:171","msg":"trace[1797947325] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"105.54799ms","start":"2026-09-17T20:39:11.2461Z","end":"2026-09-17T20:39:11.351648Z","steps":["trace[1797947325] 'process raft request' (duration: 100.473013ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:40:06.889103Z","caller":"traceutil/trace.go:171","msg":"trace[1626722735] transaction","detail":"{read_only:false; response_revision:1441; number_of_response:1; }","duration":"146.131991ms","start":"2026-09-17T20:40:06.742921Z","end":"2026-09-17T20:40:06.889053Z","steps":["trace[1626722735] 'process raft request' (duration: 146.001759ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:40:33.123508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.287721ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-17T20:40:33.123822Z","caller":"traceutil/trace.go:171","msg":"trace[54217897] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"379.720261ms","start":"2026-09-17T20:40:32.744078Z","end":"2026-09-17T20:40:33.123798Z","steps":["trace[54217897] 'process raft request' (duration: 379.585439ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:40:33.123858Z","caller":"traceutil/trace.go:171","msg":"trace[1354648722] linearizableReadLoop","detail":"{readStateIndex:1932; appliedIndex:1931; }","duration":"484.605085ms","start":"2026-09-17T20:40:32.639217Z","end":"2026-09-17T20:40:33.123823Z","steps":["trace[1354648722] 'read index received' (duration: 319.882847ms)","trace[1354648722] 'applied index is now lower than readState.Index' (duration: 164.719028ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T20:40:33.123932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T20:40:32.74406Z","time spent":"379.820153ms","remote":"127.0.0.1:35548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-17T20:40:33.123951Z","caller":"traceutil/trace.go:171","msg":"trace[2091424573] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"499.1983ms","start":"2026-09-17T20:40:32.624669Z","end":"2026-09-17T20:40:33.123867Z","steps":["trace[2091424573] 'process raft request' (duration: 334.415561ms)","trace[2091424573] 'compare' (duration: 164.17427ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T20:40:33.124127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.903139ms","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-17T20:40:33.124177Z","caller":"traceutil/trace.go:171","msg":"trace[1685418812] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1861; }","duration":"484.99641ms","start":"2026-09-17T20:40:32.639162Z","end":"2026-09-17T20:40:33.124159Z","steps":["trace[1685418812] 'agreement among raft nodes before linearized reading' (duration: 484.806377ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:40:33.124193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.219309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18d63652a4275591\" ","response":"range_response_count:1 size:982"} {"level":"warn","ts":"2026-09-17T20:40:33.124222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T20:40:32.639151Z","time spent":"485.062072ms","remote":"127.0.0.1:52590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-09-17T20:40:33.12426Z","caller":"traceutil/trace.go:171","msg":"trace[631558721] range","detail":"{range_begin:/registry/events/openstack/datadir-percona-xtradb-pxc-0.18d63652a4275591; range_end:; response_count:1; response_revision:1861; }","duration":"450.334631ms","start":"2026-09-17T20:40:32.673905Z","end":"2026-09-17T20:40:33.12424Z","steps":["trace[631558721] 'agreement among raft nodes before linearized reading' (duration: 450.147518ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:40:33.12431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T20:40:32.673878Z","time spent":"450.416692ms","remote":"127.0.0.1:35366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":1005,"request content":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18d63652a4275591\" "} {"level":"warn","ts":"2026-09-17T20:40:33.12432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T20:40:32.624648Z","time spent":"499.457294ms","remote":"127.0.0.1:35314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-17T20:40:33.124435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.293637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-09-17T20:40:33.124485Z","caller":"traceutil/trace.go:171","msg":"trace[1892510519] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1861; }","duration":"110.388829ms","start":"2026-09-17T20:40:33.014082Z","end":"2026-09-17T20:40:33.124471Z","steps":["trace[1892510519] 'agreement among raft nodes before linearized reading' (duration: 110.259547ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:40:33.124584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.076758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-01f74b28-3c8e-4c23-89ef-e20dbf6fdc40\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-09-17T20:40:33.124731Z","caller":"traceutil/trace.go:171","msg":"trace[136783704] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-01f74b28-3c8e-4c23-89ef-e20dbf6fdc40; range_end:; response_count:1; response_revision:1861; }","duration":"321.219951ms","start":"2026-09-17T20:40:32.803492Z","end":"2026-09-17T20:40:33.124712Z","steps":["trace[136783704] 'agreement among raft nodes before linearized reading' (duration: 320.993247ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:40:33.124781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T20:40:32.803421Z","time spent":"321.343862ms","remote":"127.0.0.1:35464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-01f74b28-3c8e-4c23-89ef-e20dbf6fdc40\" "} {"level":"info","ts":"2026-09-17T20:40:33.296635Z","caller":"traceutil/trace.go:171","msg":"trace[1233110639] linearizableReadLoop","detail":"{readStateIndex:1934; appliedIndex:1933; }","duration":"168.47797ms","start":"2026-09-17T20:40:33.128091Z","end":"2026-09-17T20:40:33.296569Z","steps":["trace[1233110639] 'read index received' (duration: 168.282917ms)","trace[1233110639] 'applied index is now lower than readState.Index' (duration: 193.483µs)"],"step_count":2} {"level":"info","ts":"2026-09-17T20:40:33.296773Z","caller":"traceutil/trace.go:171","msg":"trace[447996772] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"168.817085ms","start":"2026-09-17T20:40:33.127929Z","end":"2026-09-17T20:40:33.296746Z","steps":["trace[447996772] 'process raft request' (duration: 168.448319ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:40:33.297086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.949657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-09-17T20:40:33.297139Z","caller":"traceutil/trace.go:171","msg":"trace[1506536321] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1862; }","duration":"169.071378ms","start":"2026-09-17T20:40:33.128055Z","end":"2026-09-17T20:40:33.297126Z","steps":["trace[1506536321] 'agreement among raft nodes before linearized reading' (duration: 168.919406ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:40:33.538561Z","caller":"traceutil/trace.go:171","msg":"trace[1041984864] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"405.597427ms","start":"2026-09-17T20:40:33.132934Z","end":"2026-09-17T20:40:33.538532Z","steps":["trace[1041984864] 'process raft request' (duration: 390.901802ms)","trace[1041984864] 'compare' (duration: 14.3564ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T20:40:33.538742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T20:40:33.132906Z","time spent":"405.752719ms","remote":"127.0.0.1:35548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-17T20:40:33.538766Z","caller":"traceutil/trace.go:171","msg":"trace[1719643974] linearizableReadLoop","detail":"{readStateIndex:1936; appliedIndex:1934; }","duration":"242.032939ms","start":"2026-09-17T20:40:33.296707Z","end":"2026-09-17T20:40:33.53874Z","steps":["trace[1719643974] 'read index received' (duration: 227.152511ms)","trace[1719643974] 'applied index is now lower than readState.Index' (duration: 14.878778ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T20:40:33.539352Z","caller":"traceutil/trace.go:171","msg":"trace[1507041028] transaction","detail":"{read_only:false; response_revision:1864; number_of_response:1; }","duration":"400.882719ms","start":"2026-09-17T20:40:33.138408Z","end":"2026-09-17T20:40:33.53929Z","steps":["trace[1507041028] 'process raft request' (duration: 400.020226ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:40:33.539358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.007733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T20:40:33.539675Z","caller":"traceutil/trace.go:171","msg":"trace[1200097576] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1864; }","duration":"363.344008ms","start":"2026-09-17T20:40:33.176302Z","end":"2026-09-17T20:40:33.539646Z","steps":["trace[1200097576] 'agreement among raft nodes before linearized reading' (duration: 362.989473ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:40:33.53975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T20:40:33.176276Z","time spent":"363.45996ms","remote":"127.0.0.1:35268","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-17T20:40:33.539877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T20:40:33.138385Z","time spent":"401.124432ms","remote":"127.0.0.1:35464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3728,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-17T20:40:33.545389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.172036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-17T20:40:33.545432Z","caller":"traceutil/trace.go:171","msg":"trace[407460025] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1865; }","duration":"170.228037ms","start":"2026-09-17T20:40:33.375195Z","end":"2026-09-17T20:40:33.545423Z","steps":["trace[407460025] 'agreement among raft nodes before linearized reading' (duration: 170.128565ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:40:33.545373Z","caller":"traceutil/trace.go:171","msg":"trace[971143467] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"217.929177ms","start":"2026-09-17T20:40:33.327416Z","end":"2026-09-17T20:40:33.545346Z","steps":["trace[971143467] 'process raft request' (duration: 217.799525ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:40:33.545815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.56438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-09-17T20:40:33.545867Z","caller":"traceutil/trace.go:171","msg":"trace[1140474188] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:1865; }","duration":"245.618951ms","start":"2026-09-17T20:40:33.300234Z","end":"2026-09-17T20:40:33.545853Z","steps":["trace[1140474188] 'agreement among raft nodes before linearized reading' (duration: 245.216265ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:40:46.505718Z","caller":"traceutil/trace.go:171","msg":"trace[671110130] linearizableReadLoop","detail":"{readStateIndex:2049; appliedIndex:2048; }","duration":"194.647429ms","start":"2026-09-17T20:40:46.311053Z","end":"2026-09-17T20:40:46.5057Z","steps":["trace[671110130] 'read index received' (duration: 142.573386ms)","trace[671110130] 'applied index is now lower than readState.Index' (duration: 52.073383ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T20:40:46.505898Z","caller":"traceutil/trace.go:171","msg":"trace[1968716677] transaction","detail":"{read_only:false; response_revision:1975; number_of_response:1; }","duration":"217.665212ms","start":"2026-09-17T20:40:46.28822Z","end":"2026-09-17T20:40:46.505885Z","steps":["trace[1968716677] 'process raft request' (duration: 165.510119ms)","trace[1968716677] 'compare' (duration: 51.826159ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T20:40:46.50639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.335268ms","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-17T20:40:46.506479Z","caller":"traceutil/trace.go:171","msg":"trace[1494959644] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1975; }","duration":"195.468501ms","start":"2026-09-17T20:40:46.310995Z","end":"2026-09-17T20:40:46.506464Z","steps":["trace[1494959644] 'agreement among raft nodes before linearized reading' (duration: 195.013164ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:41:01.893322Z","caller":"traceutil/trace.go:171","msg":"trace[1803408094] transaction","detail":"{read_only:false; response_revision:2075; number_of_response:1; }","duration":"202.477928ms","start":"2026-09-17T20:41:01.690806Z","end":"2026-09-17T20:41:01.893284Z","steps":["trace[1803408094] 'process raft request' (duration: 202.276605ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:41:02.23247Z","caller":"traceutil/trace.go:171","msg":"trace[2015562449] transaction","detail":"{read_only:false; response_revision:2076; number_of_response:1; }","duration":"222.357888ms","start":"2026-09-17T20:41:02.010077Z","end":"2026-09-17T20:41:02.232435Z","steps":["trace[2015562449] 'process raft request' (duration: 179.43622ms)","trace[2015562449] 'compare' (duration: 42.743476ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T20:41:02.347995Z","caller":"traceutil/trace.go:171","msg":"trace[1533585002] linearizableReadLoop","detail":"{readStateIndex:2154; appliedIndex:2152; }","duration":"316.249916ms","start":"2026-09-17T20:41:02.031718Z","end":"2026-09-17T20:41:02.347968Z","steps":["trace[1533585002] 'read index received' (duration: 157.809599ms)","trace[1533585002] 'applied index is now lower than readState.Index' (duration: 158.439257ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T20:41:02.348141Z","caller":"traceutil/trace.go:171","msg":"trace[586807352] transaction","detail":"{read_only:false; response_revision:2077; number_of_response:1; }","duration":"323.674765ms","start":"2026-09-17T20:41:02.02443Z","end":"2026-09-17T20:41:02.348105Z","steps":["trace[586807352] 'process raft request' (duration: 311.825506ms)","trace[586807352] 'compare' (duration: 11.609266ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T20:41:02.348356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T20:41:02.024416Z","time spent":"323.783627ms","remote":"127.0.0.1:35548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-17T20:41:02.348395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.719652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-09-17T20:41:02.348433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.26445ms","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-09-17T20:41:02.348455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.730962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-09-17T20:41:02.34848Z","caller":"traceutil/trace.go:171","msg":"trace[116076018] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2077; }","duration":"276.843523ms","start":"2026-09-17T20:41:02.071613Z","end":"2026-09-17T20:41:02.348457Z","steps":["trace[116076018] 'agreement among raft nodes before linearized reading' (duration: 276.62716ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:41:02.348489Z","caller":"traceutil/trace.go:171","msg":"trace[559219954] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2077; }","duration":"277.360161ms","start":"2026-09-17T20:41:02.071113Z","end":"2026-09-17T20:41:02.348473Z","steps":["trace[559219954] 'agreement among raft nodes before linearized reading' (duration: 277.156788ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:41:02.34852Z","caller":"traceutil/trace.go:171","msg":"trace[955507342] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2077; }","duration":"316.827533ms","start":"2026-09-17T20:41:02.03167Z","end":"2026-09-17T20:41:02.348497Z","steps":["trace[955507342] 'agreement among raft nodes before linearized reading' (duration: 316.451408ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:41:02.348565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T20:41:02.031625Z","time spent":"316.928225ms","remote":"127.0.0.1:35432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":1876,"request content":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-09-17T20:41:02.348576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.049995ms","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-17T20:41:02.348689Z","caller":"traceutil/trace.go:171","msg":"trace[738036558] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2077; }","duration":"291.246938ms","start":"2026-09-17T20:41:02.057415Z","end":"2026-09-17T20:41:02.348662Z","steps":["trace[738036558] 'agreement among raft nodes before linearized reading' (duration: 290.832652ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:41:02.348711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.216108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T20:41:02.348827Z","caller":"traceutil/trace.go:171","msg":"trace[1907912827] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2077; }","duration":"182.365379ms","start":"2026-09-17T20:41:02.166447Z","end":"2026-09-17T20:41:02.348813Z","steps":["trace[1907912827] 'agreement among raft nodes before linearized reading' (duration: 182.223827ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:41:08.474683Z","caller":"traceutil/trace.go:171","msg":"trace[187361870] transaction","detail":"{read_only:false; response_revision:2111; number_of_response:1; }","duration":"105.16037ms","start":"2026-09-17T20:41:08.369486Z","end":"2026-09-17T20:41:08.474646Z","steps":["trace[187361870] 'process raft request' (duration: 23.941109ms)","trace[187361870] 'compare' (duration: 80.749135ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T20:41:08.898433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.130914ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-17T20:41:08.898503Z","caller":"traceutil/trace.go:171","msg":"trace[1043414859] linearizableReadLoop","detail":"{readStateIndex:2194; appliedIndex:2193; }","duration":"256.487132ms","start":"2026-09-17T20:41:08.642003Z","end":"2026-09-17T20:41:08.89849Z","steps":["trace[1043414859] 'read index received' (duration: 74.216487ms)","trace[1043414859] 'applied index is now lower than readState.Index' (duration: 182.269865ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T20:41:08.898647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.849322ms","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-17T20:41:08.898669Z","caller":"traceutil/trace.go:171","msg":"trace[1335999100] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2115; }","duration":"212.905853ms","start":"2026-09-17T20:41:08.685756Z","end":"2026-09-17T20:41:08.898661Z","steps":["trace[1335999100] 'agreement among raft nodes before linearized reading' (duration: 212.811811ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:41:08.898739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.691335ms","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-17T20:41:08.89881Z","caller":"traceutil/trace.go:171","msg":"trace[2066114923] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2115; }","duration":"256.844027ms","start":"2026-09-17T20:41:08.641949Z","end":"2026-09-17T20:41:08.898793Z","steps":["trace[2066114923] 'agreement among raft nodes before linearized reading' (duration: 256.601074ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:41:08.899363Z","caller":"traceutil/trace.go:171","msg":"trace[2001997280] transaction","detail":"{read_only:false; response_revision:2115; number_of_response:1; }","duration":"279.177523ms","start":"2026-09-17T20:41:08.619323Z","end":"2026-09-17T20:41:08.898501Z","steps":["trace[2001997280] 'process raft request' (duration: 96.919468ms)","trace[2001997280] 'compare' (duration: 181.972141ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T20:42:29.031747Z","caller":"traceutil/trace.go:171","msg":"trace[243983133] linearizableReadLoop","detail":"{readStateIndex:2738; appliedIndex:2737; }","duration":"129.495891ms","start":"2026-09-17T20:42:28.902232Z","end":"2026-09-17T20:42:29.031728Z","steps":["trace[243983133] 'read index received' (duration: 129.318564ms)","trace[243983133] 'applied index is now lower than readState.Index' (duration: 176.817µs)"],"step_count":2} {"level":"info","ts":"2026-09-17T20:42:29.031839Z","caller":"traceutil/trace.go:171","msg":"trace[331135221] transaction","detail":"{read_only:false; response_revision:2640; number_of_response:1; }","duration":"278.511688ms","start":"2026-09-17T20:42:28.753321Z","end":"2026-09-17T20:42:29.031833Z","steps":["trace[331135221] 'process raft request' (duration: 278.295938ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:42:29.031998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.754011ms","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-17T20:42:29.032016Z","caller":"traceutil/trace.go:171","msg":"trace[825383638] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2640; }","duration":"129.834994ms","start":"2026-09-17T20:42:28.902177Z","end":"2026-09-17T20:42:29.032012Z","steps":["trace[825383638] 'agreement among raft nodes before linearized reading' (duration: 129.751471ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:42:29.032171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.898784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-17T20:42:29.032229Z","caller":"traceutil/trace.go:171","msg":"trace[1108931593] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2640; }","duration":"121.002338ms","start":"2026-09-17T20:42:28.911213Z","end":"2026-09-17T20:42:29.032215Z","steps":["trace[1108931593] 'agreement among raft nodes before linearized reading' (duration: 120.861612ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:42:29.032275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.50748ms","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-17T20:42:29.032295Z","caller":"traceutil/trace.go:171","msg":"trace[339565477] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2640; }","duration":"117.568223ms","start":"2026-09-17T20:42:28.914721Z","end":"2026-09-17T20:42:29.03229Z","steps":["trace[339565477] 'agreement among raft nodes before linearized reading' (duration: 117.516951ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:42:59.941258Z","caller":"traceutil/trace.go:171","msg":"trace[1644259544] transaction","detail":"{read_only:false; response_revision:2813; number_of_response:1; }","duration":"235.253476ms","start":"2026-09-17T20:42:59.705969Z","end":"2026-09-17T20:42:59.941223Z","steps":["trace[1644259544] 'process raft request' (duration: 235.118781ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:43:05.526924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.65032ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-17T20:43:05.527019Z","caller":"traceutil/trace.go:171","msg":"trace[56753496] transaction","detail":"{read_only:false; response_revision:2840; number_of_response:1; }","duration":"297.990545ms","start":"2026-09-17T20:43:05.229009Z","end":"2026-09-17T20:43:05.527Z","steps":["trace[56753496] 'process raft request' (duration: 143.18808ms)","trace[56753496] 'compare' (duration: 154.472034ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T20:43:05.527092Z","caller":"traceutil/trace.go:171","msg":"trace[828707958] linearizableReadLoop","detail":"{readStateIndex:2945; appliedIndex:2944; }","duration":"270.254906ms","start":"2026-09-17T20:43:05.256814Z","end":"2026-09-17T20:43:05.527069Z","steps":["trace[828707958] 'read index received' (duration: 115.587795ms)","trace[828707958] 'applied index is now lower than readState.Index' (duration: 154.665171ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T20:43:05.527343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.510845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-17T20:43:05.527508Z","caller":"traceutil/trace.go:171","msg":"trace[1686453687] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2840; }","duration":"270.68821ms","start":"2026-09-17T20:43:05.256804Z","end":"2026-09-17T20:43:05.527492Z","steps":["trace[1686453687] 'agreement among raft nodes before linearized reading' (duration: 270.399981ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:43:05.550846Z","caller":"traceutil/trace.go:171","msg":"trace[1701799705] transaction","detail":"{read_only:false; response_revision:2841; number_of_response:1; }","duration":"229.981288ms","start":"2026-09-17T20:43:05.320837Z","end":"2026-09-17T20:43:05.550818Z","steps":["trace[1701799705] 'process raft request' (duration: 229.868204ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:43:05.551153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.203099ms","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-17T20:43:05.551232Z","caller":"traceutil/trace.go:171","msg":"trace[2130540737] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2841; }","duration":"292.311553ms","start":"2026-09-17T20:43:05.258902Z","end":"2026-09-17T20:43:05.551214Z","steps":["trace[2130540737] 'agreement among raft nodes before linearized reading' (duration: 291.898498ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:47:37.327396Z","caller":"traceutil/trace.go:171","msg":"trace[282764559] linearizableReadLoop","detail":"{readStateIndex:5129; appliedIndex:5125; }","duration":"162.864148ms","start":"2026-09-17T20:47:37.16451Z","end":"2026-09-17T20:47:37.327374Z","steps":["trace[282764559] 'read index received' (duration: 36.727904ms)","trace[282764559] 'applied index is now lower than readState.Index' (duration: 126.135344ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T20:47:37.327717Z","caller":"traceutil/trace.go:171","msg":"trace[1536782863] transaction","detail":"{read_only:false; response_revision:4940; number_of_response:1; }","duration":"175.786928ms","start":"2026-09-17T20:47:37.151916Z","end":"2026-09-17T20:47:37.327702Z","steps":["trace[1536782863] 'process raft request' (duration: 104.311977ms)","trace[1536782863] 'compare' (duration: 70.581236ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T20:47:37.327891Z","caller":"traceutil/trace.go:171","msg":"trace[535668113] transaction","detail":"{read_only:false; response_revision:4941; number_of_response:1; }","duration":"175.916621ms","start":"2026-09-17T20:47:37.151964Z","end":"2026-09-17T20:47:37.32788Z","steps":["trace[535668113] 'process raft request' (duration: 175.31502ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:47:37.328014Z","caller":"traceutil/trace.go:171","msg":"trace[447159229] transaction","detail":"{read_only:false; response_revision:4942; number_of_response:1; }","duration":"176.003612ms","start":"2026-09-17T20:47:37.152Z","end":"2026-09-17T20:47:37.328004Z","steps":["trace[447159229] 'process raft request' (duration: 175.33157ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:47:37.328208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.697223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T20:47:37.328404Z","caller":"traceutil/trace.go:171","msg":"trace[1706791824] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4942; }","duration":"163.932617ms","start":"2026-09-17T20:47:37.164461Z","end":"2026-09-17T20:47:37.328394Z","steps":["trace[1706791824] 'agreement among raft nodes before linearized reading' (duration: 163.679182ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:47:38.421371Z","caller":"traceutil/trace.go:171","msg":"trace[1636402186] transaction","detail":"{read_only:false; response_revision:4956; number_of_response:1; }","duration":"249.017927ms","start":"2026-09-17T20:47:38.172317Z","end":"2026-09-17T20:47:38.421335Z","steps":["trace[1636402186] 'process raft request' (duration: 248.606739ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:47:38.426684Z","caller":"traceutil/trace.go:171","msg":"trace[710315992] linearizableReadLoop","detail":"{readStateIndex:5145; appliedIndex:5143; }","duration":"217.70364ms","start":"2026-09-17T20:47:38.20893Z","end":"2026-09-17T20:47:38.426634Z","steps":["trace[710315992] 'read index received' (duration: 211.971258ms)","trace[710315992] 'applied index is now lower than readState.Index' (duration: 5.731082ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T20:47:38.426881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.914714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-17T20:47:38.426944Z","caller":"traceutil/trace.go:171","msg":"trace[1040174775] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4957; }","duration":"218.026166ms","start":"2026-09-17T20:47:38.208899Z","end":"2026-09-17T20:47:38.426925Z","steps":["trace[1040174775] 'agreement among raft nodes before linearized reading' (duration: 217.870524ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:47:38.426954Z","caller":"traceutil/trace.go:171","msg":"trace[824405093] transaction","detail":"{read_only:false; response_revision:4957; number_of_response:1; }","duration":"226.263663ms","start":"2026-09-17T20:47:38.20063Z","end":"2026-09-17T20:47:38.426894Z","steps":["trace[824405093] 'process raft request' (duration: 225.866096ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:47:44.296525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.319956ms","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-09-17T20:47:44.296621Z","caller":"traceutil/trace.go:171","msg":"trace[415860191] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5053; }","duration":"193.458938ms","start":"2026-09-17T20:47:44.10314Z","end":"2026-09-17T20:47:44.296598Z","steps":["trace[415860191] 'range keys from in-memory index tree' (duration: 193.075091ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:47:44.296658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.907636ms","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-17T20:47:44.296842Z","caller":"traceutil/trace.go:171","msg":"trace[220117280] 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:5053; }","duration":"258.059129ms","start":"2026-09-17T20:47:44.038714Z","end":"2026-09-17T20:47:44.296773Z","steps":["trace[220117280] 'range keys from in-memory index tree' (duration: 257.679812ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:47:44.296863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.595149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T20:47:44.296974Z","caller":"traceutil/trace.go:171","msg":"trace[1210982734] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5053; }","duration":"133.708421ms","start":"2026-09-17T20:47:44.16325Z","end":"2026-09-17T20:47:44.296958Z","steps":["trace[1210982734] 'range keys from in-memory index tree' (duration: 133.464228ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:47:44.296855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.3323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12973"} {"level":"warn","ts":"2026-09-17T20:47:44.297129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.435191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-5649d45556-fxrgh.18d636b7541907e4\" ","response":"range_response_count:1 size:898"} {"level":"info","ts":"2026-09-17T20:47:44.297121Z","caller":"traceutil/trace.go:171","msg":"trace[1050841673] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5053; }","duration":"346.583184ms","start":"2026-09-17T20:47:43.95048Z","end":"2026-09-17T20:47:44.297063Z","steps":["trace[1050841673] 'range keys from in-memory index tree' (duration: 346.207198ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:47:44.297233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T20:47:43.950461Z","time spent":"346.738247ms","remote":"127.0.0.1:35464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":77,"response count":1,"response size":12996,"request content":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"info","ts":"2026-09-17T20:47:44.297361Z","caller":"traceutil/trace.go:171","msg":"trace[1926200138] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-5649d45556-fxrgh.18d636b7541907e4; range_end:; response_count:1; response_revision:5053; }","duration":"346.517913ms","start":"2026-09-17T20:47:43.950664Z","end":"2026-09-17T20:47:44.297182Z","steps":["trace[1926200138] 'range keys from in-memory index tree' (duration: 345.551566ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:47:44.297453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T20:47:43.950659Z","time spent":"346.774918ms","remote":"127.0.0.1:35366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":93,"response count":1,"response size":921,"request content":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-5649d45556-fxrgh.18d636b7541907e4\" "} {"level":"warn","ts":"2026-09-17T20:47:44.299513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.053226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2207411"} {"level":"info","ts":"2026-09-17T20:47:44.299663Z","caller":"traceutil/trace.go:171","msg":"trace[833037103] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:5053; }","duration":"268.252849ms","start":"2026-09-17T20:47:44.031395Z","end":"2026-09-17T20:47:44.299648Z","steps":["trace[833037103] 'range keys from in-memory index tree' (duration: 264.856818ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:47:44.678467Z","caller":"traceutil/trace.go:171","msg":"trace[474924859] linearizableReadLoop","detail":"{readStateIndex:5252; appliedIndex:5251; }","duration":"101.507574ms","start":"2026-09-17T20:47:44.576936Z","end":"2026-09-17T20:47:44.678443Z","steps":["trace[474924859] 'read index received' (duration: 39.06169ms)","trace[474924859] 'applied index is now lower than readState.Index' (duration: 62.444824ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T20:47:44.678904Z","caller":"traceutil/trace.go:171","msg":"trace[1719753987] transaction","detail":"{read_only:false; response_revision:5062; number_of_response:1; }","duration":"166.158134ms","start":"2026-09-17T20:47:44.512717Z","end":"2026-09-17T20:47:44.678875Z","steps":["trace[1719753987] 'process raft request' (duration: 103.274314ms)","trace[1719753987] 'compare' (duration: 62.346922ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T20:47:44.679166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.209445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-17T20:47:44.679208Z","caller":"traceutil/trace.go:171","msg":"trace[478138536] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:5062; }","duration":"102.301907ms","start":"2026-09-17T20:47:44.576895Z","end":"2026-09-17T20:47:44.679197Z","steps":["trace[478138536] 'agreement among raft nodes before linearized reading' (duration: 102.220486ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:47:46.889319Z","caller":"traceutil/trace.go:171","msg":"trace[969401464] linearizableReadLoop","detail":"{readStateIndex:5280; appliedIndex:5279; }","duration":"149.513207ms","start":"2026-09-17T20:47:46.739782Z","end":"2026-09-17T20:47:46.889295Z","steps":["trace[969401464] 'read index received' (duration: 149.482826ms)","trace[969401464] 'applied index is now lower than readState.Index' (duration: 29.211µs)"],"step_count":2} {"level":"warn","ts":"2026-09-17T20:47:46.88954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.727541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e521e509-c838-49c1-9b97-f91aaee10da3\" ","response":"range_response_count:1 size:3935"} {"level":"info","ts":"2026-09-17T20:47:46.8896Z","caller":"traceutil/trace.go:171","msg":"trace[547495842] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e521e509-c838-49c1-9b97-f91aaee10da3; range_end:; response_count:1; response_revision:5090; }","duration":"149.829722ms","start":"2026-09-17T20:47:46.739754Z","end":"2026-09-17T20:47:46.889584Z","steps":["trace[547495842] 'agreement among raft nodes before linearized reading' (duration: 149.634378ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:47:46.890462Z","caller":"traceutil/trace.go:171","msg":"trace[1000769489] transaction","detail":"{read_only:false; response_revision:5090; number_of_response:1; }","duration":"155.826548ms","start":"2026-09-17T20:47:46.734597Z","end":"2026-09-17T20:47:46.890424Z","steps":["trace[1000769489] 'process raft request' (duration: 154.549575ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:47:58.870008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.939162ms","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-09-17T20:47:58.870108Z","caller":"traceutil/trace.go:171","msg":"trace[1568863254] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:5252; }","duration":"575.135655ms","start":"2026-09-17T20:47:58.294945Z","end":"2026-09-17T20:47:58.870081Z","steps":["trace[1568863254] 'count revisions from in-memory index tree' (duration: 574.794538ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:47:58.870181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T20:47:58.294924Z","time spent":"575.234706ms","remote":"127.0.0.1:35504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-09-17T20:47:58.870506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.101335ms","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-09-17T20:47:58.870602Z","caller":"traceutil/trace.go:171","msg":"trace[893359838] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5252; }","duration":"423.253508ms","start":"2026-09-17T20:47:58.447327Z","end":"2026-09-17T20:47:58.870581Z","steps":["trace[893359838] 'range keys from in-memory index tree' (duration: 422.876841ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:47:58.870659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T20:47:58.447305Z","time spent":"423.339639ms","remote":"127.0.0.1:35548","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-09-17T20:47:58.870736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.566455ms","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-17T20:47:58.870788Z","caller":"traceutil/trace.go:171","msg":"trace[1762082479] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5252; }","duration":"504.662527ms","start":"2026-09-17T20:47:58.366109Z","end":"2026-09-17T20:47:58.870771Z","steps":["trace[1762082479] 'range keys from in-memory index tree' (duration: 504.375402ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:47:58.87082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T20:47:58.36608Z","time spent":"504.732578ms","remote":"127.0.0.1:52366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3339,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"info","ts":"2026-09-17T20:47:58.870809Z","caller":"traceutil/trace.go:171","msg":"trace[70450119] linearizableReadLoop","detail":"{readStateIndex:5448; appliedIndex:5447; }","duration":"250.964785ms","start":"2026-09-17T20:47:58.619818Z","end":"2026-09-17T20:47:58.870783Z","steps":["trace[70450119] 'read index received' (duration: 193.149837ms)","trace[70450119] 'applied index is now lower than readState.Index' (duration: 57.813448ms)"],"step_count":2} {"level":"info","ts":"2026-09-17T20:47:58.871159Z","caller":"traceutil/trace.go:171","msg":"trace[626533261] transaction","detail":"{read_only:false; response_revision:5253; number_of_response:1; }","duration":"353.92482ms","start":"2026-09-17T20:47:58.517214Z","end":"2026-09-17T20:47:58.871139Z","steps":["trace[626533261] 'process raft request' (duration: 295.867218ms)","trace[626533261] 'compare' (duration: 57.297008ms)"],"step_count":2} {"level":"warn","ts":"2026-09-17T20:47:58.871224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.401092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-17T20:47:58.871272Z","caller":"traceutil/trace.go:171","msg":"trace[158634278] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:5253; }","duration":"251.490184ms","start":"2026-09-17T20:47:58.619767Z","end":"2026-09-17T20:47:58.871257Z","steps":["trace[158634278] 'agreement among raft nodes before linearized reading' (duration: 251.408713ms)"],"step_count":1} {"level":"warn","ts":"2026-09-17T20:47:58.871278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T20:47:58.517196Z","time spent":"354.010801ms","remote":"127.0.0.1:35548","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-09-17T20:47:58.871385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.156084ms","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-17T20:47:58.871457Z","caller":"traceutil/trace.go:171","msg":"trace[1306606180] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5253; }","duration":"229.257026ms","start":"2026-09-17T20:47:58.642161Z","end":"2026-09-17T20:47:58.871418Z","steps":["trace[1306606180] 'agreement among raft nodes before linearized reading' (duration: 229.121223ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:47:59.256653Z","caller":"traceutil/trace.go:171","msg":"trace[162198166] transaction","detail":"{read_only:false; response_revision:5261; number_of_response:1; }","duration":"105.690461ms","start":"2026-09-17T20:47:59.150937Z","end":"2026-09-17T20:47:59.256627Z","steps":["trace[162198166] 'process raft request' (duration: 105.428116ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:47:59.386461Z","caller":"traceutil/trace.go:171","msg":"trace[598637803] transaction","detail":"{read_only:false; response_revision:5266; number_of_response:1; }","duration":"107.726476ms","start":"2026-09-17T20:47:59.278711Z","end":"2026-09-17T20:47:59.386438Z","steps":["trace[598637803] 'process raft request' (duration: 107.39922ms)"],"step_count":1} {"level":"info","ts":"2026-09-17T20:48:09.152292Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2860} {"level":"info","ts":"2026-09-17T20:48:09.226806Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2860,"took":"73.5546ms","hash":3659628469,"current-db-size-bytes":52662272,"current-db-size":"53 MB","current-db-size-in-use-bytes":27561984,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-17T20:48:09.226967Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3659628469,"revision":2860,"compact-revision":-1} {"level":"info","ts":"2026-09-17T20:53:09.158529Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5324} {"level":"info","ts":"2026-09-17T20:53:09.26445Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5324,"took":"104.594037ms","hash":3728747602,"current-db-size-bytes":52662272,"current-db-size":"53 MB","current-db-size-in-use-bytes":19005440,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-17T20:53:09.264552Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3728747602,"revision":5324,"compact-revision":2860}