{"level":"warn","ts":"2026-09-10T11:02:15.311609Z","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-10T11:02:15.311712Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.191: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.191:2380","--initial-cluster=instance=https://162.253.55.191:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.191:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.191: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-10T11:02:15.311788Z","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-10T11:02:15.311822Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.191:2380"]} {"level":"info","ts":"2026-09-10T11:02:15.311851Z","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-10T11:02:15.312557Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.191:2379"]} {"level":"info","ts":"2026-09-10T11:02:15.312653Z","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.191:2380"],"listen-peer-urls":["https://162.253.55.191:2380"],"advertise-client-urls":["https://162.253.55.191:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.191:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.191: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-10T11:02:15.317291Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.381816ms"} {"level":"info","ts":"2026-09-10T11:02:15.327562Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"adb6690514c92321","cluster-id":"ee04900e3a861beb"} {"level":"info","ts":"2026-09-10T11:02:15.327633Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adb6690514c92321 switched to configuration voters=()"} {"level":"info","ts":"2026-09-10T11:02:15.327655Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adb6690514c92321 became follower at term 0"} {"level":"info","ts":"2026-09-10T11:02:15.327666Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft adb6690514c92321 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-10T11:02:15.327672Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adb6690514c92321 became follower at term 1"} {"level":"info","ts":"2026-09-10T11:02:15.327704Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adb6690514c92321 switched to configuration voters=(12517307684867351329)"} {"level":"warn","ts":"2026-09-10T11:02:15.333001Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-10T11:02:15.335352Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-10T11:02:15.33639Z","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-10T11:02:15.338819Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"adb6690514c92321","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-10T11:02:15.33954Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"adb6690514c92321","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-10T11:02:15.339686Z","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-10T11:02:15.339841Z","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-10T11:02:15.339852Z","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-10T11:02:15.340058Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adb6690514c92321 switched to configuration voters=(12517307684867351329)"} {"level":"info","ts":"2026-09-10T11:02:15.340302Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ee04900e3a861beb","local-member-id":"adb6690514c92321","added-peer-id":"adb6690514c92321","added-peer-peer-urls":["https://162.253.55.191:2380"]} {"level":"info","ts":"2026-09-10T11:02:15.341825Z","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-10T11:02:15.342077Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"adb6690514c92321","initial-advertise-peer-urls":["https://162.253.55.191:2380"],"listen-peer-urls":["https://162.253.55.191:2380"],"advertise-client-urls":["https://162.253.55.191:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.191:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-10T11:02:15.342113Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-10T11:02:15.34216Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.191:2380"} {"level":"info","ts":"2026-09-10T11:02:15.342272Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.191:2380"} {"level":"info","ts":"2026-09-10T11:02:15.628602Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adb6690514c92321 is starting a new election at term 1"} {"level":"info","ts":"2026-09-10T11:02:15.628675Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adb6690514c92321 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-10T11:02:15.628706Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adb6690514c92321 received MsgPreVoteResp from adb6690514c92321 at term 1"} {"level":"info","ts":"2026-09-10T11:02:15.628733Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adb6690514c92321 became candidate at term 2"} {"level":"info","ts":"2026-09-10T11:02:15.62874Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adb6690514c92321 received MsgVoteResp from adb6690514c92321 at term 2"} {"level":"info","ts":"2026-09-10T11:02:15.62875Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adb6690514c92321 became leader at term 2"} {"level":"info","ts":"2026-09-10T11:02:15.628759Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: adb6690514c92321 elected leader adb6690514c92321 at term 2"} {"level":"info","ts":"2026-09-10T11:02:15.978623Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"adb6690514c92321","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.191:2379]}","request-path":"/0/members/adb6690514c92321/attributes","cluster-id":"ee04900e3a861beb","publish-timeout":"7s"} {"level":"info","ts":"2026-09-10T11:02:15.97878Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-10T11:02:15.978855Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-10T11:02:15.978999Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-10T11:02:15.979021Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-10T11:02:15.979014Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-10T11:02:15.996149Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.191:2379"} {"level":"info","ts":"2026-09-10T11:02:15.997849Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ee04900e3a861beb","local-member-id":"adb6690514c92321","cluster-version":"3.5"} {"level":"info","ts":"2026-09-10T11:02:15.998033Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-10T11:02:15.998113Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-10T11:02:15.998777Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-09-10T11:02:20.802144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.023789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-09-10T11:02:20.802224Z","caller":"traceutil/trace.go:171","msg":"trace[2031884134] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:229; }","duration":"135.179564ms","start":"2026-09-10T11:02:20.667029Z","end":"2026-09-10T11:02:20.802209Z","steps":["trace[2031884134] 'range keys from in-memory index tree' (duration: 134.908875ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:02:21.721565Z","caller":"traceutil/trace.go:171","msg":"trace[884362460] transaction","detail":"{read_only:false; response_revision:253; number_of_response:1; }","duration":"156.302323ms","start":"2026-09-10T11:02:21.565214Z","end":"2026-09-10T11:02:21.721516Z","steps":["trace[884362460] 'process raft request' (duration: 96.670704ms)","trace[884362460] 'compare' (duration: 59.34569ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:02:21.721625Z","caller":"traceutil/trace.go:171","msg":"trace[1963432503] transaction","detail":"{read_only:false; response_revision:254; number_of_response:1; }","duration":"108.771044ms","start":"2026-09-10T11:02:21.612828Z","end":"2026-09-10T11:02:21.721599Z","steps":["trace[1963432503] 'process raft request' (duration: 108.675001ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:02:21.721642Z","caller":"traceutil/trace.go:171","msg":"trace[827900315] transaction","detail":"{read_only:false; number_of_response:0; response_revision:253; }","duration":"108.835596ms","start":"2026-09-10T11:02:21.612754Z","end":"2026-09-10T11:02:21.721589Z","steps":["trace[827900315] 'process raft request' (duration: 108.686611ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:02:21.721786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.833346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/attachdetach-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T11:02:21.721622Z","caller":"traceutil/trace.go:171","msg":"trace[607719178] linearizableReadLoop","detail":"{readStateIndex:259; appliedIndex:257; }","duration":"155.669021ms","start":"2026-09-10T11:02:21.565926Z","end":"2026-09-10T11:02:21.721595Z","steps":["trace[607719178] 'read index received' (duration: 52.253759ms)","trace[607719178] 'applied index is now lower than readState.Index' (duration: 103.413852ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:02:21.721865Z","caller":"traceutil/trace.go:171","msg":"trace[2132647544] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/attachdetach-controller; range_end:; response_count:0; response_revision:254; }","duration":"155.93831ms","start":"2026-09-10T11:02:21.565886Z","end":"2026-09-10T11:02:21.721824Z","steps":["trace[2132647544] 'agreement among raft nodes before linearized reading' (duration: 155.806086ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:02:21.735116Z","caller":"traceutil/trace.go:171","msg":"trace[1982925457] transaction","detail":"{read_only:false; response_revision:255; number_of_response:1; }","duration":"101.621491ms","start":"2026-09-10T11:02:21.633413Z","end":"2026-09-10T11:02:21.735035Z","steps":["trace[1982925457] 'process raft request' (duration: 101.428714ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:02:21.735185Z","caller":"traceutil/trace.go:171","msg":"trace[608059639] transaction","detail":"{read_only:false; response_revision:257; number_of_response:1; }","duration":"101.453335ms","start":"2026-09-10T11:02:21.633711Z","end":"2026-09-10T11:02:21.735164Z","steps":["trace[608059639] 'process raft request' (duration: 101.266019ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:02:21.735181Z","caller":"traceutil/trace.go:171","msg":"trace[498463907] transaction","detail":"{read_only:false; response_revision:256; number_of_response:1; }","duration":"101.681643ms","start":"2026-09-10T11:02:21.633423Z","end":"2026-09-10T11:02:21.735104Z","steps":["trace[498463907] 'process raft request' (duration: 101.512147ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:02:21.735296Z","caller":"traceutil/trace.go:171","msg":"trace[1597028109] transaction","detail":"{read_only:false; response_revision:258; number_of_response:1; }","duration":"100.969498ms","start":"2026-09-10T11:02:21.634317Z","end":"2026-09-10T11:02:21.735287Z","steps":["trace[1597028109] 'process raft request' (duration: 100.72898ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:02:21.735335Z","caller":"traceutil/trace.go:171","msg":"trace[1146703445] transaction","detail":"{read_only:false; number_of_response:0; response_revision:258; }","duration":"100.814343ms","start":"2026-09-10T11:02:21.634502Z","end":"2026-09-10T11:02:21.735317Z","steps":["trace[1146703445] 'process raft request' (duration: 100.595366ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:02:21.735565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.963027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:115"} {"level":"info","ts":"2026-09-10T11:02:21.735648Z","caller":"traceutil/trace.go:171","msg":"trace[1304324040] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:258; }","duration":"111.093632ms","start":"2026-09-10T11:02:21.624538Z","end":"2026-09-10T11:02:21.735632Z","steps":["trace[1304324040] 'agreement among raft nodes before linearized reading' (duration: 110.904636ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:02:24.109477Z","caller":"traceutil/trace.go:171","msg":"trace[365246615] transaction","detail":"{read_only:false; response_revision:296; number_of_response:1; }","duration":"183.260686ms","start":"2026-09-10T11:02:23.926183Z","end":"2026-09-10T11:02:24.109444Z","steps":["trace[365246615] 'process raft request' (duration: 183.07472ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:02:24.434256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.920259ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-10T11:02:24.434472Z","caller":"traceutil/trace.go:171","msg":"trace[399632138] linearizableReadLoop","detail":"{readStateIndex:308; appliedIndex:306; }","duration":"465.667236ms","start":"2026-09-10T11:02:23.968783Z","end":"2026-09-10T11:02:24.43445Z","steps":["trace[399632138] 'read index received' (duration: 140.781671ms)","trace[399632138] 'applied index is now lower than readState.Index' (duration: 324.884205ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:02:24.434807Z","caller":"traceutil/trace.go:171","msg":"trace[1315664208] transaction","detail":"{read_only:false; response_revision:297; number_of_response:1; }","duration":"506.34446ms","start":"2026-09-10T11:02:23.928448Z","end":"2026-09-10T11:02:24.434793Z","steps":["trace[1315664208] 'process raft request' (duration: 320.097527ms)","trace[1315664208] 'compare' (duration: 184.743854ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T11:02:24.434876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:02:23.928393Z","time spent":"506.445873ms","remote":"127.0.0.1:43720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T11:02:24.435108Z","caller":"traceutil/trace.go:171","msg":"trace[2074866680] transaction","detail":"{read_only:false; response_revision:298; number_of_response:1; }","duration":"505.372207ms","start":"2026-09-10T11:02:23.929725Z","end":"2026-09-10T11:02:24.435098Z","steps":["trace[2074866680] 'process raft request' (duration: 504.645034ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:02:24.435165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:02:23.929695Z","time spent":"505.4433ms","remote":"127.0.0.1:43720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-10T11:02:24.43534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.554584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-09-10T11:02:24.435373Z","caller":"traceutil/trace.go:171","msg":"trace[403029706] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:298; }","duration":"466.623866ms","start":"2026-09-10T11:02:23.968739Z","end":"2026-09-10T11:02:24.435363Z","steps":["trace[403029706] 'agreement among raft nodes before linearized reading' (duration: 466.558124ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:02:24.435398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:02:23.968714Z","time spent":"466.677798ms","remote":"127.0.0.1:43652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":240,"request content":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" "} {"level":"warn","ts":"2026-09-10T11:02:24.435554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.943401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-09-10T11:02:24.438726Z","caller":"traceutil/trace.go:171","msg":"trace[1322468434] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:298; }","duration":"422.143968ms","start":"2026-09-10T11:02:24.016569Z","end":"2026-09-10T11:02:24.438713Z","steps":["trace[1322468434] 'agreement among raft nodes before linearized reading' (duration: 418.954293ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:02:24.438788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:02:24.016552Z","time spent":"422.22729ms","remote":"127.0.0.1:43542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":375,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-09-10T11:02:24.436146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.147366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-09-10T11:02:24.438946Z","caller":"traceutil/trace.go:171","msg":"trace[1607615545] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:298; }","duration":"193.99672ms","start":"2026-09-10T11:02:24.244939Z","end":"2026-09-10T11:02:24.438936Z","steps":["trace[1607615545] 'agreement among raft nodes before linearized reading' (duration: 191.166176ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:02:24.783735Z","caller":"traceutil/trace.go:171","msg":"trace[337130780] transaction","detail":"{read_only:false; response_revision:299; number_of_response:1; }","duration":"341.517903ms","start":"2026-09-10T11:02:24.442172Z","end":"2026-09-10T11:02:24.78369Z","steps":["trace[337130780] 'process raft request' (duration: 341.335477ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:02:24.783939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:02:24.442147Z","time spent":"341.696999ms","remote":"127.0.0.1:43720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T11:02:24.786119Z","caller":"traceutil/trace.go:171","msg":"trace[1937031422] transaction","detail":"{read_only:false; response_revision:300; number_of_response:1; }","duration":"343.558411ms","start":"2026-09-10T11:02:24.442528Z","end":"2026-09-10T11:02:24.786087Z","steps":["trace[1937031422] 'process raft request' (duration: 343.398796ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:02:24.786297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:02:24.442518Z","time spent":"343.685075ms","remote":"127.0.0.1:43652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-10T11:02:44.312402Z","caller":"traceutil/trace.go:171","msg":"trace[935665899] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"103.415246ms","start":"2026-09-10T11:02:44.208936Z","end":"2026-09-10T11:02:44.312351Z","steps":["trace[935665899] 'process raft request' (duration: 103.185029ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:02:56.644386Z","caller":"traceutil/trace.go:171","msg":"trace[1239362749] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"103.576431ms","start":"2026-09-10T11:02:56.540787Z","end":"2026-09-10T11:02:56.644364Z","steps":["trace[1239362749] 'process raft request' (duration: 103.433947ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:02:56.644671Z","caller":"traceutil/trace.go:171","msg":"trace[2101361444] transaction","detail":"{read_only:false; number_of_response:0; response_revision:518; }","duration":"112.203999ms","start":"2026-09-10T11:02:56.53246Z","end":"2026-09-10T11:02:56.644664Z","steps":["trace[2101361444] 'process raft request' (duration: 111.476519ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:02:56.644788Z","caller":"traceutil/trace.go:171","msg":"trace[7282867] transaction","detail":"{read_only:false; number_of_response:0; response_revision:518; }","duration":"112.152157ms","start":"2026-09-10T11:02:56.532631Z","end":"2026-09-10T11:02:56.644783Z","steps":["trace[7282867] 'process raft request' (duration: 111.421317ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:02:56.644922Z","caller":"traceutil/trace.go:171","msg":"trace[1112661645] transaction","detail":"{read_only:false; number_of_response:1; response_revision:518; }","duration":"106.946669ms","start":"2026-09-10T11:02:56.537963Z","end":"2026-09-10T11:02:56.64491Z","steps":["trace[1112661645] 'process raft request' (duration: 106.177559ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:02:57.11647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.937584ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-10T11:02:57.117226Z","caller":"traceutil/trace.go:171","msg":"trace[780082582] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"464.250082ms","start":"2026-09-10T11:02:56.652939Z","end":"2026-09-10T11:02:57.117189Z","steps":["trace[780082582] 'process raft request' (duration: 307.459767ms)","trace[780082582] 'compare' (duration: 155.759398ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:02:57.117326Z","caller":"traceutil/trace.go:171","msg":"trace[1813646981] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"456.714424ms","start":"2026-09-10T11:02:56.660585Z","end":"2026-09-10T11:02:57.1173Z","steps":["trace[1813646981] 'process raft request' (duration: 456.286362ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:02:57.117408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:02:56.652928Z","time spent":"464.376435ms","remote":"127.0.0.1:43482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T11:02:57.117365Z","caller":"traceutil/trace.go:171","msg":"trace[679265377] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"464.267592ms","start":"2026-09-10T11:02:56.653049Z","end":"2026-09-10T11:02:57.117316Z","steps":["trace[679265377] 'process raft request' (duration: 463.609485ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:02:57.117535Z","caller":"traceutil/trace.go:171","msg":"trace[1369996223] linearizableReadLoop","detail":"{readStateIndex:547; appliedIndex:543; }","duration":"442.525169ms","start":"2026-09-10T11:02:56.674947Z","end":"2026-09-10T11:02:57.117472Z","steps":["trace[1369996223] 'read index received' (duration: 285.555799ms)","trace[1369996223] 'applied index is now lower than readState.Index' (duration: 156.96727ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T11:02:57.117703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.279179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha1.gateway.networking.x-k8s.io\" ","response":"range_response_count:1 size:1019"} {"level":"info","ts":"2026-09-10T11:02:57.117767Z","caller":"traceutil/trace.go:171","msg":"trace[1629867238] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1alpha1.gateway.networking.x-k8s.io; range_end:; response_count:1; response_revision:524; }","duration":"465.348801ms","start":"2026-09-10T11:02:56.652399Z","end":"2026-09-10T11:02:57.117748Z","steps":["trace[1629867238] 'agreement among raft nodes before linearized reading' (duration: 465.087734ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:02:57.117767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.362672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:995"} {"level":"warn","ts":"2026-09-10T11:02:57.117442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:02:56.660578Z","time spent":"456.819856ms","remote":"127.0.0.1:43482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-10T11:02:57.117809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:02:56.652396Z","time spent":"465.402892ms","remote":"127.0.0.1:43974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1043,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha1.gateway.networking.x-k8s.io\" "} {"level":"info","ts":"2026-09-10T11:02:57.117818Z","caller":"traceutil/trace.go:171","msg":"trace[2105381231] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:524; }","duration":"465.449274ms","start":"2026-09-10T11:02:56.652351Z","end":"2026-09-10T11:02:57.117801Z","steps":["trace[2105381231] 'agreement among raft nodes before linearized reading' (duration: 465.266289ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:02:57.117504Z","caller":"traceutil/trace.go:171","msg":"trace[1632370381] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"442.791966ms","start":"2026-09-10T11:02:56.674698Z","end":"2026-09-10T11:02:57.11749Z","steps":["trace[1632370381] 'process raft request' (duration: 442.526729ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:02:57.117854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:02:56.652331Z","time spent":"465.514965ms","remote":"127.0.0.1:43974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":1019,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/v1.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-09-10T11:02:57.118147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:02:56.674671Z","time spent":"443.213237ms","remote":"127.0.0.1:43482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7693,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-10T11:02:57.11825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:02:56.653024Z","time spent":"464.54801ms","remote":"127.0.0.1:43482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-10T11:02:57.128544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.914598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-10T11:02:57.128617Z","caller":"traceutil/trace.go:171","msg":"trace[260719297] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:528; }","duration":"396.017181ms","start":"2026-09-10T11:02:56.732578Z","end":"2026-09-10T11:02:57.128595Z","steps":["trace[260719297] 'agreement among raft nodes before linearized reading' (duration: 395.830866ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:02:57.128652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:02:56.732559Z","time spent":"396.085632ms","remote":"127.0.0.1:43720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-10T11:02:57.128871Z","caller":"traceutil/trace.go:171","msg":"trace[653399406] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"439.794086ms","start":"2026-09-10T11:02:56.68906Z","end":"2026-09-10T11:02:57.128854Z","steps":["trace[653399406] 'process raft request' (duration: 439.151759ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:02:57.129003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:02:56.689057Z","time spent":"439.852727ms","remote":"127.0.0.1:43482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T11:02:57.129213Z","caller":"traceutil/trace.go:171","msg":"trace[563840142] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"437.572917ms","start":"2026-09-10T11:02:56.691625Z","end":"2026-09-10T11:02:57.129198Z","steps":["trace[563840142] 'process raft request' (duration: 436.721604ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:02:57.129289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:02:56.691618Z","time spent":"437.625888ms","remote":"127.0.0.1:43482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31102,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T11:02:57.128811Z","caller":"traceutil/trace.go:171","msg":"trace[228471945] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"439.805076ms","start":"2026-09-10T11:02:56.688967Z","end":"2026-09-10T11:02:57.128772Z","steps":["trace[228471945] 'process raft request' (duration: 439.157538ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:02:57.129543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.522754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-09-10T11:02:57.129582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:02:56.688944Z","time spent":"440.571446ms","remote":"127.0.0.1:43482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-10T11:02:57.129599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.760456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T11:02:57.129612Z","caller":"traceutil/trace.go:171","msg":"trace[568554702] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:528; }","duration":"143.650248ms","start":"2026-09-10T11:02:56.985943Z","end":"2026-09-10T11:02:57.129593Z","steps":["trace[568554702] 'agreement among raft nodes before linearized reading' (duration: 143.36141ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:02:57.12965Z","caller":"traceutil/trace.go:171","msg":"trace[1345683792] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:528; }","duration":"144.841999ms","start":"2026-09-10T11:02:56.984792Z","end":"2026-09-10T11:02:57.129634Z","steps":["trace[1345683792] 'agreement among raft nodes before linearized reading' (duration: 144.760586ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:02:57.12993Z","caller":"traceutil/trace.go:171","msg":"trace[49709754] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"449.606885ms","start":"2026-09-10T11:02:56.68031Z","end":"2026-09-10T11:02:57.129917Z","steps":["trace[49709754] 'process raft request' (duration: 447.672964ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:02:57.12999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:02:56.680286Z","time spent":"449.673426ms","remote":"127.0.0.1:43720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-10T11:03:05.510039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.706819ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-09-10T11:03:05.510273Z","caller":"traceutil/trace.go:171","msg":"trace[1190870726] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:641; }","duration":"527.700495ms","start":"2026-09-10T11:03:04.98255Z","end":"2026-09-10T11:03:05.51025Z","steps":["trace[1190870726] 'read index received' (duration: 422.852973ms)","trace[1190870726] 'applied index is now lower than readState.Index' (duration: 104.845182ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T11:03:05.510765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.121385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-09-10T11:03:05.510765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.655148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-09-10T11:03:05.510816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.28365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T11:03:05.510883Z","caller":"traceutil/trace.go:171","msg":"trace[646066299] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:617; }","duration":"528.364172ms","start":"2026-09-10T11:03:04.982498Z","end":"2026-09-10T11:03:05.510862Z","steps":["trace[646066299] 'agreement among raft nodes before linearized reading' (duration: 527.849539ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:03:05.510751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.371151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-09-10T11:03:05.510976Z","caller":"traceutil/trace.go:171","msg":"trace[2069627842] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:617; }","duration":"347.662688ms","start":"2026-09-10T11:03:05.163298Z","end":"2026-09-10T11:03:05.510961Z","steps":["trace[2069627842] 'agreement among raft nodes before linearized reading' (duration: 347.207747ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:03:05.510884Z","caller":"traceutil/trace.go:171","msg":"trace[1730447674] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:617; }","duration":"345.289279ms","start":"2026-09-10T11:03:05.165576Z","end":"2026-09-10T11:03:05.510866Z","steps":["trace[1730447674] 'agreement among raft nodes before linearized reading' (duration: 345.017122ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:03:05.51112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:03:05.165551Z","time spent":"345.512915ms","remote":"127.0.0.1:43720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-10T11:03:05.510869Z","caller":"traceutil/trace.go:171","msg":"trace[24850601] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:617; }","duration":"339.823163ms","start":"2026-09-10T11:03:05.171027Z","end":"2026-09-10T11:03:05.51085Z","steps":["trace[24850601] 'agreement among raft nodes before linearized reading' (duration: 339.525565ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:03:05.511101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:03:05.163262Z","time spent":"347.793432ms","remote":"127.0.0.1:43720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-10T11:03:05.511188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:03:05.170987Z","time spent":"340.184722ms","remote":"127.0.0.1:43720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-10T11:03:05.51093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:03:04.98248Z","time spent":"528.441894ms","remote":"127.0.0.1:43428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-10T11:03:06.080436Z","caller":"traceutil/trace.go:171","msg":"trace[428812782] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"342.733435ms","start":"2026-09-10T11:03:05.737664Z","end":"2026-09-10T11:03:06.080398Z","steps":["trace[428812782] 'process raft request' (duration: 342.390716ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:03:06.080606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:03:05.737629Z","time spent":"342.886299ms","remote":"127.0.0.1:43632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-10T11:03:06.645013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.453086ms","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-10T11:03:06.645137Z","caller":"traceutil/trace.go:171","msg":"trace[224042905] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:621; }","duration":"128.640111ms","start":"2026-09-10T11:03:06.516477Z","end":"2026-09-10T11:03:06.645117Z","steps":["trace[224042905] 'range keys from in-memory index tree' (duration: 128.271821ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:03:06.917029Z","caller":"traceutil/trace.go:171","msg":"trace[678299299] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"267.332002ms","start":"2026-09-10T11:03:06.64966Z","end":"2026-09-10T11:03:06.916992Z","steps":["trace[678299299] 'process raft request' (duration: 267.119497ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:03:25.028636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.425489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-nnkll\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-09-10T11:03:25.028752Z","caller":"traceutil/trace.go:171","msg":"trace[778722082] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-nnkll; range_end:; response_count:1; response_revision:783; }","duration":"191.617953ms","start":"2026-09-10T11:03:24.837109Z","end":"2026-09-10T11:03:25.028727Z","steps":["trace[778722082] 'range keys from in-memory index tree' (duration: 191.05635ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:03:33.086862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.157527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T11:03:33.086979Z","caller":"traceutil/trace.go:171","msg":"trace[287852518] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:864; }","duration":"103.307861ms","start":"2026-09-10T11:03:32.983631Z","end":"2026-09-10T11:03:33.086939Z","steps":["trace[287852518] 'range keys from in-memory index tree' (duration: 103.078286ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:03:33.160116Z","caller":"traceutil/trace.go:171","msg":"trace[918206974] transaction","detail":"{read_only:false; response_revision:865; number_of_response:1; }","duration":"167.668348ms","start":"2026-09-10T11:03:32.992416Z","end":"2026-09-10T11:03:33.160085Z","steps":["trace[918206974] 'process raft request' (duration: 167.472314ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:03:33.19666Z","caller":"traceutil/trace.go:171","msg":"trace[92177505] linearizableReadLoop","detail":"{readStateIndex:898; appliedIndex:896; }","duration":"108.315959ms","start":"2026-09-10T11:03:33.088319Z","end":"2026-09-10T11:03:33.196635Z","steps":["trace[92177505] 'read index received' (duration: 71.540125ms)","trace[92177505] 'applied index is now lower than readState.Index' (duration: 36.774174ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:03:33.196836Z","caller":"traceutil/trace.go:171","msg":"trace[999066287] transaction","detail":"{read_only:false; response_revision:866; number_of_response:1; }","duration":"190.558098ms","start":"2026-09-10T11:03:33.006265Z","end":"2026-09-10T11:03:33.196823Z","steps":["trace[999066287] 'process raft request' (duration: 185.801453ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:03:33.197152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.810301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T11:03:33.197265Z","caller":"traceutil/trace.go:171","msg":"trace[2062038806] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:866; }","duration":"108.966683ms","start":"2026-09-10T11:03:33.088279Z","end":"2026-09-10T11:03:33.197246Z","steps":["trace[2062038806] 'agreement among raft nodes before linearized reading' (duration: 108.79316ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:03:35.918817Z","caller":"traceutil/trace.go:171","msg":"trace[1713375470] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"113.178164ms","start":"2026-09-10T11:03:35.805615Z","end":"2026-09-10T11:03:35.918794Z","steps":["trace[1713375470] 'process raft request' (duration: 112.97689ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:04:52.730356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.350872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-09-10T11:04:52.730385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.453675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-09-10T11:04:52.730448Z","caller":"traceutil/trace.go:171","msg":"trace[1892102606] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1884; }","duration":"442.484505ms","start":"2026-09-10T11:04:52.287947Z","end":"2026-09-10T11:04:52.730431Z","steps":["trace[1892102606] 'range keys from in-memory index tree' (duration: 442.18004ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:04:52.730487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:04:52.287931Z","time spent":"442.548706ms","remote":"127.0.0.1:43720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-09-10T11:04:52.730487Z","caller":"traceutil/trace.go:171","msg":"trace[742206099] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1884; }","duration":"442.597277ms","start":"2026-09-10T11:04:52.287861Z","end":"2026-09-10T11:04:52.730458Z","steps":["trace[742206099] 'range keys from in-memory index tree' (duration: 442.228371ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:04:52.730565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:04:52.287839Z","time spent":"442.70986ms","remote":"127.0.0.1:43720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-10T11:04:52.730832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.296698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-09-10T11:04:52.730891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"747.9821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T11:04:52.7309Z","caller":"traceutil/trace.go:171","msg":"trace[1766618306] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1884; }","duration":"425.400671ms","start":"2026-09-10T11:04:52.305484Z","end":"2026-09-10T11:04:52.730885Z","steps":["trace[1766618306] 'range keys from in-memory index tree' (duration: 425.141286ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:04:52.73092Z","caller":"traceutil/trace.go:171","msg":"trace[1540483721] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1884; }","duration":"748.047871ms","start":"2026-09-10T11:04:51.982863Z","end":"2026-09-10T11:04:52.730911Z","steps":["trace[1540483721] 'range keys from in-memory index tree' (duration: 747.898649ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:04:52.730935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:04:52.305465Z","time spent":"425.462631ms","remote":"127.0.0.1:43720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-10T11:04:52.731041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.990146ms","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-10T11:04:52.731115Z","caller":"traceutil/trace.go:171","msg":"trace[186157906] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1884; }","duration":"525.110438ms","start":"2026-09-10T11:04:52.205996Z","end":"2026-09-10T11:04:52.731107Z","steps":["trace[186157906] 'range keys from in-memory index tree' (duration: 524.759452ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:04:52.731187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:04:52.205974Z","time spent":"525.18483ms","remote":"127.0.0.1:43720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-10T11:04:52.731218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.794212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-12ce4560-cfea-4394-9776-2253df77e876\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-09-10T11:04:52.73094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:04:51.982842Z","time spent":"748.092992ms","remote":"127.0.0.1:43428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-10T11:04:52.731365Z","caller":"traceutil/trace.go:171","msg":"trace[93549576] transaction","detail":"{read_only:false; response_revision:1885; number_of_response:1; }","duration":"288.643144ms","start":"2026-09-10T11:04:52.442706Z","end":"2026-09-10T11:04:52.731349Z","steps":["trace[93549576] 'process raft request' (duration: 260.565494ms)","trace[93549576] 'compare' (duration: 26.931239ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:04:52.731305Z","caller":"traceutil/trace.go:171","msg":"trace[1581012686] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-12ce4560-cfea-4394-9776-2253df77e876; range_end:; response_count:1; response_revision:1884; }","duration":"469.955214ms","start":"2026-09-10T11:04:52.261327Z","end":"2026-09-10T11:04:52.731283Z","steps":["trace[1581012686] 'range keys from in-memory index tree' (duration: 469.592408ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:04:52.731488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:04:52.261301Z","time spent":"470.174978ms","remote":"127.0.0.1:43632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3651,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-12ce4560-cfea-4394-9776-2253df77e876\" "} {"level":"info","ts":"2026-09-10T11:04:52.885879Z","caller":"traceutil/trace.go:171","msg":"trace[2118947364] linearizableReadLoop","detail":"{readStateIndex:1961; appliedIndex:1959; }","duration":"295.72411ms","start":"2026-09-10T11:04:52.590118Z","end":"2026-09-10T11:04:52.885842Z","steps":["trace[2118947364] 'read index received' (duration: 113.168937ms)","trace[2118947364] 'applied index is now lower than readState.Index' (duration: 182.553453ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:04:52.886088Z","caller":"traceutil/trace.go:171","msg":"trace[1624411249] transaction","detail":"{read_only:false; response_revision:1886; number_of_response:1; }","duration":"353.180314ms","start":"2026-09-10T11:04:52.532879Z","end":"2026-09-10T11:04:52.886059Z","steps":["trace[1624411249] 'process raft request' (duration: 352.775057ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:04:52.886224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.046405ms","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-10T11:04:52.886273Z","caller":"traceutil/trace.go:171","msg":"trace[2028298944] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1886; }","duration":"296.192438ms","start":"2026-09-10T11:04:52.590062Z","end":"2026-09-10T11:04:52.886254Z","steps":["trace[2028298944] 'agreement among raft nodes before linearized reading' (duration: 295.920784ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:04:52.886383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:04:52.532848Z","time spent":"353.374076ms","remote":"127.0.0.1:43720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-10T11:04:53.344868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.314092ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-10T11:04:53.345666Z","caller":"traceutil/trace.go:171","msg":"trace[906726111] transaction","detail":"{read_only:false; response_revision:1889; number_of_response:1; }","duration":"607.985195ms","start":"2026-09-10T11:04:52.737665Z","end":"2026-09-10T11:04:53.34565Z","steps":["trace[906726111] 'process raft request' (duration: 607.889323ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:04:53.345805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:04:52.737655Z","time spent":"608.114997ms","remote":"127.0.0.1:43720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T11:04:53.346039Z","caller":"traceutil/trace.go:171","msg":"trace[772036209] transaction","detail":"{read_only:false; response_revision:1890; number_of_response:1; }","duration":"608.279812ms","start":"2026-09-10T11:04:52.737746Z","end":"2026-09-10T11:04:53.346026Z","steps":["trace[772036209] 'process raft request' (duration: 607.838344ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:04:53.346061Z","caller":"traceutil/trace.go:171","msg":"trace[283792159] transaction","detail":"{read_only:false; response_revision:1887; number_of_response:1; }","duration":"609.661005ms","start":"2026-09-10T11:04:52.736009Z","end":"2026-09-10T11:04:53.34567Z","steps":["trace[283792159] 'process raft request' (duration: 266.416988ms)","trace[283792159] 'compare' (duration: 342.078747ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:04:53.346285Z","caller":"traceutil/trace.go:171","msg":"trace[717714834] transaction","detail":"{read_only:false; response_revision:1891; number_of_response:1; }","duration":"603.386404ms","start":"2026-09-10T11:04:52.742868Z","end":"2026-09-10T11:04:53.346254Z","steps":["trace[717714834] 'process raft request' (duration: 602.959656ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:04:53.34634Z","caller":"traceutil/trace.go:171","msg":"trace[834525098] linearizableReadLoop","detail":"{readStateIndex:1967; appliedIndex:1961; }","duration":"460.371245ms","start":"2026-09-10T11:04:52.885955Z","end":"2026-09-10T11:04:53.346326Z","steps":["trace[834525098] 'read index received' (duration: 116.330153ms)","trace[834525098] 'applied index is now lower than readState.Index' (duration: 344.039902ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T11:04:53.346442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:04:52.735982Z","time spent":"610.349678ms","remote":"127.0.0.1:43720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-10T11:04:53.346477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:04:52.742846Z","time spent":"603.524866ms","remote":"127.0.0.1:43632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-10T11:04:53.346454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.138945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-10T11:04:53.346541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.93427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-09-10T11:04:53.346171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:04:52.737737Z","time spent":"608.372493ms","remote":"127.0.0.1:43720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-10T11:04:53.346613Z","caller":"traceutil/trace.go:171","msg":"trace[1707708449] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1892; }","duration":"624.036922ms","start":"2026-09-10T11:04:52.722559Z","end":"2026-09-10T11:04:53.346596Z","steps":["trace[1707708449] 'agreement among raft nodes before linearized reading' (duration: 623.879069ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:04:53.34665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:04:52.722542Z","time spent":"624.100593ms","remote":"127.0.0.1:43720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-09-10T11:04:53.346056Z","caller":"traceutil/trace.go:171","msg":"trace[1680119091] transaction","detail":"{read_only:false; response_revision:1888; number_of_response:1; }","duration":"608.576006ms","start":"2026-09-10T11:04:52.737126Z","end":"2026-09-10T11:04:53.345702Z","steps":["trace[1680119091] 'process raft request' (duration: 608.333071ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:04:53.346688Z","caller":"traceutil/trace.go:171","msg":"trace[2144138324] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1892; }","duration":"614.303189ms","start":"2026-09-10T11:04:52.732278Z","end":"2026-09-10T11:04:53.346581Z","steps":["trace[2144138324] 'agreement among raft nodes before linearized reading' (duration: 614.133976ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:04:53.346753Z","caller":"traceutil/trace.go:171","msg":"trace[518740764] transaction","detail":"{read_only:false; response_revision:1892; number_of_response:1; }","duration":"497.367054ms","start":"2026-09-10T11:04:52.849373Z","end":"2026-09-10T11:04:53.34674Z","steps":["trace[518740764] 'process raft request' (duration: 496.739123ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:04:53.346732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:04:52.737112Z","time spent":"609.571103ms","remote":"127.0.0.1:43720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-10T11:04:53.34677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:04:52.732247Z","time spent":"614.514052ms","remote":"127.0.0.1:43444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-10T11:04:53.346815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:04:52.849345Z","time spent":"497.433595ms","remote":"127.0.0.1:43720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-10T11:04:53.346943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.112171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-12ce4560-cfea-4394-9776-2253df77e876\" ","response":"range_response_count:1 size:3747"} {"level":"warn","ts":"2026-09-10T11:04:53.34695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.804123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-09-10T11:04:53.347221Z","caller":"traceutil/trace.go:171","msg":"trace[28701852] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:1892; }","duration":"649.038238ms","start":"2026-09-10T11:04:52.69809Z","end":"2026-09-10T11:04:53.347129Z","steps":["trace[28701852] 'agreement among raft nodes before linearized reading' (duration: 648.383026ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:04:53.347295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:04:52.698068Z","time spent":"649.21279ms","remote":"127.0.0.1:43720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":583,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-09-10T11:04:53.347018Z","caller":"traceutil/trace.go:171","msg":"trace[1091837611] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-12ce4560-cfea-4394-9776-2253df77e876; range_end:; response_count:1; response_revision:1892; }","duration":"662.194863ms","start":"2026-09-10T11:04:52.684774Z","end":"2026-09-10T11:04:53.346969Z","steps":["trace[1091837611] 'agreement among raft nodes before linearized reading' (duration: 662.094611ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:04:53.347412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-10T11:04:52.684757Z","time spent":"662.64401ms","remote":"127.0.0.1:43632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3771,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-12ce4560-cfea-4394-9776-2253df77e876\" "} {"level":"info","ts":"2026-09-10T11:05:01.016403Z","caller":"traceutil/trace.go:171","msg":"trace[2020910926] transaction","detail":"{read_only:false; response_revision:1960; number_of_response:1; }","duration":"257.55756ms","start":"2026-09-10T11:05:00.758822Z","end":"2026-09-10T11:05:01.01638Z","steps":["trace[2020910926] 'process raft request' (duration: 257.413038ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:05:01.062824Z","caller":"traceutil/trace.go:171","msg":"trace[435665967] linearizableReadLoop","detail":"{readStateIndex:2039; appliedIndex:2037; }","duration":"113.216385ms","start":"2026-09-10T11:05:00.949569Z","end":"2026-09-10T11:05:01.062786Z","steps":["trace[435665967] 'read index received' (duration: 66.681895ms)","trace[435665967] 'applied index is now lower than readState.Index' (duration: 46.53303ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:05:01.062971Z","caller":"traceutil/trace.go:171","msg":"trace[701808200] transaction","detail":"{read_only:false; response_revision:1961; number_of_response:1; }","duration":"136.240561ms","start":"2026-09-10T11:05:00.926683Z","end":"2026-09-10T11:05:01.062923Z","steps":["trace[701808200] 'process raft request' (duration: 135.963576ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:05:01.063176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.583471ms","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-10T11:05:01.063235Z","caller":"traceutil/trace.go:171","msg":"trace[1021781006] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1961; }","duration":"113.701193ms","start":"2026-09-10T11:05:00.949518Z","end":"2026-09-10T11:05:01.063219Z","steps":["trace[1021781006] 'agreement among raft nodes before linearized reading' (duration: 113.406208ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:05:27.125382Z","caller":"traceutil/trace.go:171","msg":"trace[2144543270] transaction","detail":"{read_only:false; response_revision:2124; number_of_response:1; }","duration":"117.291189ms","start":"2026-09-10T11:05:27.008064Z","end":"2026-09-10T11:05:27.125356Z","steps":["trace[2144543270] 'process raft request' (duration: 117.109755ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:05:27.467628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.867086ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-10T11:05:27.467831Z","caller":"traceutil/trace.go:171","msg":"trace[1912053188] linearizableReadLoop","detail":"{readStateIndex:2209; appliedIndex:2208; }","duration":"209.61239ms","start":"2026-09-10T11:05:27.25814Z","end":"2026-09-10T11:05:27.467752Z","steps":["trace[1912053188] 'read index received' (duration: 90.385637ms)","trace[1912053188] 'applied index is now lower than readState.Index' (duration: 119.206602ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:05:27.467873Z","caller":"traceutil/trace.go:171","msg":"trace[1304456635] transaction","detail":"{read_only:false; response_revision:2126; number_of_response:1; }","duration":"291.998761ms","start":"2026-09-10T11:05:27.17584Z","end":"2026-09-10T11:05:27.467839Z","steps":["trace[1304456635] 'process raft request' (duration: 172.800899ms)","trace[1304456635] 'compare' (duration: 118.715004ms)"],"step_count":2} {"level":"warn","ts":"2026-09-10T11:05:27.468794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.591086ms","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-10T11:05:27.468892Z","caller":"traceutil/trace.go:171","msg":"trace[2088367905] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2126; }","duration":"210.764269ms","start":"2026-09-10T11:05:27.258106Z","end":"2026-09-10T11:05:27.468871Z","steps":["trace[2088367905] 'agreement among raft nodes before linearized reading' (duration: 209.764923ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:05:27.756388Z","caller":"traceutil/trace.go:171","msg":"trace[1938198347] transaction","detail":"{read_only:false; response_revision:2128; number_of_response:1; }","duration":"108.491518ms","start":"2026-09-10T11:05:27.647622Z","end":"2026-09-10T11:05:27.756114Z","steps":["trace[1938198347] 'process raft request' (duration: 108.360685ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:05:27.764216Z","caller":"traceutil/trace.go:171","msg":"trace[1958487664] transaction","detail":"{read_only:false; response_revision:2130; number_of_response:1; }","duration":"116.121039ms","start":"2026-09-10T11:05:27.64808Z","end":"2026-09-10T11:05:27.764201Z","steps":["trace[1958487664] 'process raft request' (duration: 116.033098ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:05:27.764209Z","caller":"traceutil/trace.go:171","msg":"trace[1006259138] transaction","detail":"{read_only:false; response_revision:2129; number_of_response:1; }","duration":"116.240721ms","start":"2026-09-10T11:05:27.647947Z","end":"2026-09-10T11:05:27.764188Z","steps":["trace[1006259138] 'process raft request' (duration: 116.019627ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:05:27.764486Z","caller":"traceutil/trace.go:171","msg":"trace[1566264271] transaction","detail":"{read_only:false; response_revision:2131; number_of_response:1; }","duration":"115.575129ms","start":"2026-09-10T11:05:27.648898Z","end":"2026-09-10T11:05:27.764473Z","steps":["trace[1566264271] 'process raft request' (duration: 115.264414ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:05:27.764677Z","caller":"traceutil/trace.go:171","msg":"trace[1550730589] transaction","detail":"{read_only:false; response_revision:2132; number_of_response:1; }","duration":"115.654731ms","start":"2026-09-10T11:05:27.648978Z","end":"2026-09-10T11:05:27.764633Z","steps":["trace[1550730589] 'process raft request' (duration: 115.442187ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:05:29.410062Z","caller":"traceutil/trace.go:171","msg":"trace[1940281818] transaction","detail":"{read_only:false; response_revision:2134; number_of_response:1; }","duration":"266.16138ms","start":"2026-09-10T11:05:29.143872Z","end":"2026-09-10T11:05:29.410033Z","steps":["trace[1940281818] 'process raft request' (duration: 265.971407ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:07:42.996688Z","caller":"traceutil/trace.go:171","msg":"trace[1669802117] transaction","detail":"{read_only:false; response_revision:2950; number_of_response:1; }","duration":"254.457939ms","start":"2026-09-10T11:07:42.742198Z","end":"2026-09-10T11:07:42.996656Z","steps":["trace[1669802117] 'process raft request' (duration: 254.250522ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:07:42.997498Z","caller":"traceutil/trace.go:171","msg":"trace[1932881614] linearizableReadLoop","detail":"{readStateIndex:3064; appliedIndex:3062; }","duration":"154.943746ms","start":"2026-09-10T11:07:42.84251Z","end":"2026-09-10T11:07:42.997454Z","steps":["trace[1932881614] 'read index received' (duration: 154.014485ms)","trace[1932881614] 'applied index is now lower than readState.Index' (duration: 928.211µs)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:07:42.998054Z","caller":"traceutil/trace.go:171","msg":"trace[396158388] transaction","detail":"{read_only:false; response_revision:2951; number_of_response:1; }","duration":"255.237213ms","start":"2026-09-10T11:07:42.742543Z","end":"2026-09-10T11:07:42.997781Z","steps":["trace[396158388] 'process raft request' (duration: 254.745798ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:07:42.998171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.649609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-10T11:07:42.998347Z","caller":"traceutil/trace.go:171","msg":"trace[1306932964] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2951; }","duration":"155.800464ms","start":"2026-09-10T11:07:42.842425Z","end":"2026-09-10T11:07:42.998226Z","steps":["trace[1306932964] 'agreement among raft nodes before linearized reading' (duration: 155.37242ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:12:16.165138Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2798} {"level":"info","ts":"2026-09-10T11:12:16.250208Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2798,"took":"83.866859ms","hash":1346610761,"current-db-size-bytes":44810240,"current-db-size":"45 MB","current-db-size-in-use-bytes":19812352,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-09-10T11:12:16.250918Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1346610761,"revision":2798,"compact-revision":-1} {"level":"info","ts":"2026-09-10T11:12:37.918366Z","caller":"traceutil/trace.go:171","msg":"trace[1070096494] transaction","detail":"{read_only:false; response_revision:5207; number_of_response:1; }","duration":"195.30124ms","start":"2026-09-10T11:12:37.723034Z","end":"2026-09-10T11:12:37.918336Z","steps":["trace[1070096494] 'process raft request' (duration: 195.161237ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:12:37.954958Z","caller":"traceutil/trace.go:171","msg":"trace[535884914] linearizableReadLoop","detail":"{readStateIndex:5410; appliedIndex:5408; }","duration":"215.522726ms","start":"2026-09-10T11:12:37.739407Z","end":"2026-09-10T11:12:37.95493Z","steps":["trace[535884914] 'read index received' (duration: 178.841648ms)","trace[535884914] 'applied index is now lower than readState.Index' (duration: 36.679958ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:12:37.955059Z","caller":"traceutil/trace.go:171","msg":"trace[1175027038] transaction","detail":"{read_only:false; response_revision:5208; number_of_response:1; }","duration":"220.567619ms","start":"2026-09-10T11:12:37.734438Z","end":"2026-09-10T11:12:37.955006Z","steps":["trace[1175027038] 'process raft request' (duration: 220.318154ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:12:37.955318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.889115ms","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-10T11:12:37.955371Z","caller":"traceutil/trace.go:171","msg":"trace[1680321148] 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:5208; }","duration":"215.961336ms","start":"2026-09-10T11:12:37.739394Z","end":"2026-09-10T11:12:37.955356Z","steps":["trace[1680321148] 'agreement among raft nodes before linearized reading' (duration: 215.632849ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:12:44.29501Z","caller":"traceutil/trace.go:171","msg":"trace[2110244617] transaction","detail":"{read_only:false; response_revision:5330; number_of_response:1; }","duration":"236.41711ms","start":"2026-09-10T11:12:44.058566Z","end":"2026-09-10T11:12:44.294984Z","steps":["trace[2110244617] 'process raft request' (duration: 144.12377ms)","trace[2110244617] 'compare' (duration: 92.141207ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:12:44.537892Z","caller":"traceutil/trace.go:171","msg":"trace[1829004710] transaction","detail":"{read_only:false; response_revision:5331; number_of_response:1; }","duration":"198.06732ms","start":"2026-09-10T11:12:44.339798Z","end":"2026-09-10T11:12:44.537866Z","steps":["trace[1829004710] 'process raft request' (duration: 197.604131ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:16:34.40336Z","caller":"traceutil/trace.go:171","msg":"trace[940298989] transaction","detail":"{read_only:false; response_revision:7051; number_of_response:1; }","duration":"124.142543ms","start":"2026-09-10T11:16:34.279182Z","end":"2026-09-10T11:16:34.403324Z","steps":["trace[940298989] 'process raft request' (duration: 63.890533ms)","trace[940298989] 'compare' (duration: 60.066062ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:16:34.484438Z","caller":"traceutil/trace.go:171","msg":"trace[2117830371] transaction","detail":"{read_only:false; response_revision:7052; number_of_response:1; }","duration":"187.034277ms","start":"2026-09-10T11:16:34.297341Z","end":"2026-09-10T11:16:34.484375Z","steps":["trace[2117830371] 'process raft request' (duration: 186.793198ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:16:55.952807Z","caller":"traceutil/trace.go:171","msg":"trace[1127418805] linearizableReadLoop","detail":"{readStateIndex:7479; appliedIndex:7478; }","duration":"212.391734ms","start":"2026-09-10T11:16:55.74039Z","end":"2026-09-10T11:16:55.952782Z","steps":["trace[1127418805] 'read index received' (duration: 212.244219ms)","trace[1127418805] 'applied index is now lower than readState.Index' (duration: 146.545µs)"],"step_count":2} {"level":"warn","ts":"2026-09-10T11:16:55.953144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.729677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/ingress-nginx/\" range_end:\"/registry/secrets/ingress-nginx0\" ","response":"range_response_count:2 size:107251"} {"level":"info","ts":"2026-09-10T11:16:55.953175Z","caller":"traceutil/trace.go:171","msg":"trace[1588482354] range","detail":"{range_begin:/registry/secrets/ingress-nginx/; range_end:/registry/secrets/ingress-nginx0; response_count:2; response_revision:7210; }","duration":"212.80184ms","start":"2026-09-10T11:16:55.740362Z","end":"2026-09-10T11:16:55.953163Z","steps":["trace[1588482354] 'agreement among raft nodes before linearized reading' (duration: 212.500048ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:16:55.953387Z","caller":"traceutil/trace.go:171","msg":"trace[241911341] transaction","detail":"{read_only:false; response_revision:7210; number_of_response:1; }","duration":"264.67287ms","start":"2026-09-10T11:16:55.688708Z","end":"2026-09-10T11:16:55.953381Z","steps":["trace[241911341] 'process raft request' (duration: 263.936242ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:16:56.139329Z","caller":"traceutil/trace.go:171","msg":"trace[245473208] linearizableReadLoop","detail":"{readStateIndex:7481; appliedIndex:7479; }","duration":"114.581402ms","start":"2026-09-10T11:16:56.024719Z","end":"2026-09-10T11:16:56.1393Z","steps":["trace[245473208] 'read index received' (duration: 48.22047ms)","trace[245473208] 'applied index is now lower than readState.Index' (duration: 66.359592ms)"],"step_count":2} {"level":"info","ts":"2026-09-10T11:16:56.139653Z","caller":"traceutil/trace.go:171","msg":"trace[2073017589] transaction","detail":"{read_only:false; response_revision:7212; number_of_response:1; }","duration":"144.162208ms","start":"2026-09-10T11:16:55.995477Z","end":"2026-09-10T11:16:56.139639Z","steps":["trace[2073017589] 'process raft request' (duration: 143.67726ms)"],"step_count":1} {"level":"warn","ts":"2026-09-10T11:16:56.139985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.245767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-10T11:16:56.140028Z","caller":"traceutil/trace.go:171","msg":"trace[823228102] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7212; }","duration":"115.32553ms","start":"2026-09-10T11:16:56.024692Z","end":"2026-09-10T11:16:56.140018Z","steps":["trace[823228102] 'agreement among raft nodes before linearized reading' (duration: 115.181355ms)"],"step_count":1} {"level":"info","ts":"2026-09-10T11:17:16.173237Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":4557} {"level":"info","ts":"2026-09-10T11:17:16.23311Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":4557,"took":"58.045908ms","hash":4047572972,"current-db-size-bytes":44810240,"current-db-size":"45 MB","current-db-size-in-use-bytes":33267712,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-09-10T11:17:16.23317Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4047572972,"revision":4557,"compact-revision":2798} {"level":"warn","ts":"2026-09-10T11:19:35.280281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.178497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-10T11:19:35.280402Z","caller":"traceutil/trace.go:171","msg":"trace[225755434] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:8041; }","duration":"100.322491ms","start":"2026-09-10T11:19:35.180028Z","end":"2026-09-10T11:19:35.28035Z","steps":["trace[225755434] 'agreement among raft nodes before linearized reading' (duration: 35.929106ms)","trace[225755434] 'count revisions from in-memory index tree' (duration: 64.251271ms)"],"step_count":2}