{"level":"warn","ts":"2026-08-11T12:49:36.316843Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-11T12:49:36.31694Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.222: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://199.204.45.222:2380","--initial-cluster=instance=https://199.204.45.222:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.222:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.222:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-08-11T12:49:36.317001Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-11T12:49:36.317009Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.222:2380"]} {"level":"info","ts":"2026-08-11T12:49:36.317026Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-11T12:49:36.317659Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.222:2379"]} {"level":"info","ts":"2026-08-11T12:49:36.317728Z","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://199.204.45.222:2380"],"listen-peer-urls":["https://199.204.45.222:2380"],"advertise-client-urls":["https://199.204.45.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.222:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-08-11T12:49:36.322341Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.398463ms"} {"level":"info","ts":"2026-08-11T12:49:36.33045Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"380f6760faea5f9e","cluster-id":"b87e8aca83a17eab"} {"level":"info","ts":"2026-08-11T12:49:36.330605Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e switched to configuration voters=()"} {"level":"info","ts":"2026-08-11T12:49:36.33072Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became follower at term 0"} {"level":"info","ts":"2026-08-11T12:49:36.330829Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 380f6760faea5f9e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-11T12:49:36.331035Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became follower at term 1"} {"level":"info","ts":"2026-08-11T12:49:36.331194Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e switched to configuration voters=(4039561056998809502)"} {"level":"warn","ts":"2026-08-11T12:49:36.339337Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-11T12:49:36.341731Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-11T12:49:36.345464Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-08-11T12:49:36.349235Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"380f6760faea5f9e","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-11T12:49:36.349381Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"380f6760faea5f9e","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-11T12:49:36.350163Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e switched to configuration voters=(4039561056998809502)"} {"level":"info","ts":"2026-08-11T12:49:36.350294Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b87e8aca83a17eab","local-member-id":"380f6760faea5f9e","added-peer-id":"380f6760faea5f9e","added-peer-peer-urls":["https://199.204.45.222:2380"]} {"level":"info","ts":"2026-08-11T12:49:36.350365Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-11T12:49:36.350405Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-11T12:49:36.350424Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-11T12:49:36.355271Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-11T12:49:36.355393Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.222:2380"} {"level":"info","ts":"2026-08-11T12:49:36.355486Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.222:2380"} {"level":"info","ts":"2026-08-11T12:49:36.355504Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"380f6760faea5f9e","initial-advertise-peer-urls":["https://199.204.45.222:2380"],"listen-peer-urls":["https://199.204.45.222:2380"],"advertise-client-urls":["https://199.204.45.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-11T12:49:36.355557Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-11T12:49:36.635017Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e is starting a new election at term 1"} {"level":"info","ts":"2026-08-11T12:49:36.63509Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became pre-candidate at term 1"} {"level":"info","ts":"2026-08-11T12:49:36.635111Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e received MsgPreVoteResp from 380f6760faea5f9e at term 1"} {"level":"info","ts":"2026-08-11T12:49:36.635127Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became candidate at term 2"} {"level":"info","ts":"2026-08-11T12:49:36.635134Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e received MsgVoteResp from 380f6760faea5f9e at term 2"} {"level":"info","ts":"2026-08-11T12:49:36.635145Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became leader at term 2"} {"level":"info","ts":"2026-08-11T12:49:36.635154Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 380f6760faea5f9e elected leader 380f6760faea5f9e at term 2"} {"level":"info","ts":"2026-08-11T12:49:36.707104Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"380f6760faea5f9e","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.222:2379]}","request-path":"/0/members/380f6760faea5f9e/attributes","cluster-id":"b87e8aca83a17eab","publish-timeout":"7s"} {"level":"info","ts":"2026-08-11T12:49:36.707136Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-11T12:49:36.707395Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-11T12:49:36.70743Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-11T12:49:36.707733Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-11T12:49:36.707796Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-11T12:49:36.707931Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b87e8aca83a17eab","local-member-id":"380f6760faea5f9e","cluster-version":"3.5"} {"level":"info","ts":"2026-08-11T12:49:36.708036Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-11T12:49:36.708105Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-11T12:49:36.710436Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.222:2379"} {"level":"info","ts":"2026-08-11T12:49:36.71058Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-08-11T12:49:42.363441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.992409ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T12:49:42.363559Z","caller":"traceutil/trace.go:171","msg":"trace[1989700875] linearizableReadLoop","detail":"{readStateIndex:276; appliedIndex:275; }","duration":"279.048233ms","start":"2026-08-11T12:49:42.084499Z","end":"2026-08-11T12:49:42.363547Z","steps":["trace[1989700875] 'read index received' (duration: 102.691193ms)","trace[1989700875] 'applied index is now lower than readState.Index' (duration: 176.35625ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:49:42.363628Z","caller":"traceutil/trace.go:171","msg":"trace[309703856] transaction","detail":"{read_only:false; response_revision:267; number_of_response:1; }","duration":"310.31855ms","start":"2026-08-11T12:49:42.053288Z","end":"2026-08-11T12:49:42.363606Z","steps":["trace[309703856] 'process raft request' (duration: 133.812904ms)","trace[309703856] 'compare' (duration: 174.907626ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T12:49:42.363772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.561576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T12:49:42.363798Z","caller":"traceutil/trace.go:171","msg":"trace[1346596952] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:267; }","duration":"207.635079ms","start":"2026-08-11T12:49:42.156155Z","end":"2026-08-11T12:49:42.36379Z","steps":["trace[1346596952] 'agreement among raft nodes before linearized reading' (duration: 207.582127ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:49:42.363769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.223699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"warn","ts":"2026-08-11T12:49:42.363807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.730626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T12:49:42.363845Z","caller":"traceutil/trace.go:171","msg":"trace[1036086638] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:267; }","duration":"279.369805ms","start":"2026-08-11T12:49:42.084457Z","end":"2026-08-11T12:49:42.363827Z","steps":["trace[1036086638] 'agreement among raft nodes before linearized reading' (duration: 279.182678ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:49:42.363877Z","caller":"traceutil/trace.go:171","msg":"trace[994459360] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:267; }","duration":"115.796428ms","start":"2026-08-11T12:49:42.248053Z","end":"2026-08-11T12:49:42.363849Z","steps":["trace[994459360] 'agreement among raft nodes before linearized reading' (duration: 115.700534ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:49:42.363965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:49:42.053266Z","time spent":"310.674953ms","remote":"127.0.0.1:41634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T12:49:42.725662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.791858ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-11T12:49:42.72576Z","caller":"traceutil/trace.go:171","msg":"trace[1237008592] transaction","detail":"{read_only:false; response_revision:268; number_of_response:1; }","duration":"358.532525ms","start":"2026-08-11T12:49:42.367209Z","end":"2026-08-11T12:49:42.725741Z","steps":["trace[1237008592] 'process raft request' (duration: 104.569671ms)","trace[1237008592] 'compare' (duration: 253.530118ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T12:49:42.725811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:49:42.367195Z","time spent":"358.596348ms","remote":"127.0.0.1:41582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":201,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-11T12:49:42.755786Z","caller":"traceutil/trace.go:171","msg":"trace[354166685] linearizableReadLoop","detail":"{readStateIndex:280; appliedIndex:276; }","duration":"305.617917ms","start":"2026-08-11T12:49:42.450145Z","end":"2026-08-11T12:49:42.755763Z","steps":["trace[354166685] 'read index received' (duration: 21.707684ms)","trace[354166685] 'applied index is now lower than readState.Index' (duration: 283.909323ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:49:42.755832Z","caller":"traceutil/trace.go:171","msg":"trace[422447044] transaction","detail":"{read_only:false; number_of_response:0; response_revision:269; }","duration":"305.765362ms","start":"2026-08-11T12:49:42.450036Z","end":"2026-08-11T12:49:42.755801Z","steps":["trace[422447044] 'process raft request' (duration: 305.629336ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:49:42.755824Z","caller":"traceutil/trace.go:171","msg":"trace[92324108] transaction","detail":"{read_only:false; number_of_response:0; response_revision:269; }","duration":"305.821094ms","start":"2026-08-11T12:49:42.449941Z","end":"2026-08-11T12:49:42.755762Z","steps":["trace[92324108] 'process raft request' (duration: 305.681938ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:49:42.755866Z","caller":"traceutil/trace.go:171","msg":"trace[10662882] transaction","detail":"{read_only:false; number_of_response:0; response_revision:269; }","duration":"305.611757ms","start":"2026-08-11T12:49:42.450226Z","end":"2026-08-11T12:49:42.755837Z","steps":["trace[10662882] 'process raft request' (duration: 305.486862ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:49:42.755841Z","caller":"traceutil/trace.go:171","msg":"trace[1935546553] transaction","detail":"{read_only:false; number_of_response:0; response_revision:269; }","duration":"305.624777ms","start":"2026-08-11T12:49:42.450186Z","end":"2026-08-11T12:49:42.75581Z","steps":["trace[1935546553] 'process raft request' (duration: 305.501442ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:49:42.75596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:49:42.45003Z","time spent":"305.863466ms","remote":"127.0.0.1:41556","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-11T12:49:42.755999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:49:42.450222Z","time spent":"305.71606ms","remote":"127.0.0.1:41556","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-11T12:49:42.756015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:49:42.449924Z","time spent":"306.010361ms","remote":"127.0.0.1:41556","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-11T12:49:42.756027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:49:42.45018Z","time spent":"305.775843ms","remote":"127.0.0.1:41556","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-11T12:49:42.75604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.867436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:3980"} {"level":"info","ts":"2026-08-11T12:49:42.756077Z","caller":"traceutil/trace.go:171","msg":"trace[1190207453] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:269; }","duration":"305.954899ms","start":"2026-08-11T12:49:42.450113Z","end":"2026-08-11T12:49:42.756068Z","steps":["trace[1190207453] 'agreement among raft nodes before linearized reading' (duration: 305.822604ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:49:42.755915Z","caller":"traceutil/trace.go:171","msg":"trace[1055950210] transaction","detail":"{read_only:false; number_of_response:0; response_revision:269; }","duration":"305.644978ms","start":"2026-08-11T12:49:42.45026Z","end":"2026-08-11T12:49:42.755905Z","steps":["trace[1055950210] 'process raft request' (duration: 305.476002ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:49:42.756112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:49:42.450107Z","time spent":"305.997441ms","remote":"127.0.0.1:41556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":4003,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"warn","ts":"2026-08-11T12:49:42.756146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:49:42.450256Z","time spent":"305.869016ms","remote":"127.0.0.1:41556","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-11T12:49:42.756179Z","caller":"traceutil/trace.go:171","msg":"trace[2138910164] transaction","detail":"{read_only:false; response_revision:269; number_of_response:1; }","duration":"388.370281ms","start":"2026-08-11T12:49:42.367768Z","end":"2026-08-11T12:49:42.756138Z","steps":["trace[2138910164] 'process raft request' (duration: 387.728216ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:49:42.756337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:49:42.367753Z","time spent":"388.516056ms","remote":"127.0.0.1:41634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-11T12:50:09.395133Z","caller":"traceutil/trace.go:171","msg":"trace[259639719] transaction","detail":"{read_only:false; response_revision:438; number_of_response:1; }","duration":"108.237571ms","start":"2026-08-11T12:50:09.286868Z","end":"2026-08-11T12:50:09.395105Z","steps":["trace[259639719] 'process raft request' (duration: 108.065566ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:50:12.615485Z","caller":"traceutil/trace.go:171","msg":"trace[412819423] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"108.951507ms","start":"2026-08-11T12:50:12.506501Z","end":"2026-08-11T12:50:12.615452Z","steps":["trace[412819423] 'process raft request' (duration: 100.671769ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:50:12.616264Z","caller":"traceutil/trace.go:171","msg":"trace[125545179] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"106.746207ms","start":"2026-08-11T12:50:12.509502Z","end":"2026-08-11T12:50:12.616249Z","steps":["trace[125545179] 'process raft request' (duration: 106.52186ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:50:12.616596Z","caller":"traceutil/trace.go:171","msg":"trace[1028355795] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"104.586478ms","start":"2026-08-11T12:50:12.511983Z","end":"2026-08-11T12:50:12.61657Z","steps":["trace[1028355795] 'process raft request' (duration: 104.195648ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:50:12.616786Z","caller":"traceutil/trace.go:171","msg":"trace[770217060] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"100.713151ms","start":"2026-08-11T12:50:12.516034Z","end":"2026-08-11T12:50:12.616748Z","steps":["trace[770217060] 'process raft request' (duration: 100.251819ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:50:18.598591Z","caller":"traceutil/trace.go:171","msg":"trace[1741848316] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"103.565202ms","start":"2026-08-11T12:50:18.494983Z","end":"2026-08-11T12:50:18.598548Z","steps":["trace[1741848316] 'process raft request' (duration: 102.532455ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:50:19.245896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.511469ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-11T12:50:19.246145Z","caller":"traceutil/trace.go:171","msg":"trace[1457413502] linearizableReadLoop","detail":"{readStateIndex:613; appliedIndex:611; }","duration":"310.724053ms","start":"2026-08-11T12:50:18.935398Z","end":"2026-08-11T12:50:19.246122Z","steps":["trace[1457413502] 'read index received' (duration: 33.4µs)","trace[1457413502] 'applied index is now lower than readState.Index' (duration: 310.689473ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:50:19.246261Z","caller":"traceutil/trace.go:171","msg":"trace[1216895056] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"532.111089ms","start":"2026-08-11T12:50:18.714138Z","end":"2026-08-11T12:50:19.246249Z","steps":["trace[1216895056] 'process raft request' (duration: 531.899814ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:50:19.246349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:50:18.714075Z","time spent":"532.220982ms","remote":"127.0.0.1:41634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T12:50:19.246505Z","caller":"traceutil/trace.go:171","msg":"trace[1920314154] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"572.492092ms","start":"2026-08-11T12:50:18.67397Z","end":"2026-08-11T12:50:19.246462Z","steps":["trace[1920314154] 'process raft request' (duration: 241.322366ms)","trace[1920314154] 'compare' (duration: 330.178901ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T12:50:19.246634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.591809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-08-11T12:50:19.246646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.232157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-11T12:50:19.246737Z","caller":"traceutil/trace.go:171","msg":"trace[211116722] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:588; }","duration":"305.731684ms","start":"2026-08-11T12:50:18.940979Z","end":"2026-08-11T12:50:19.246711Z","steps":["trace[211116722] 'agreement among raft nodes before linearized reading' (duration: 305.410485ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:50:19.246743Z","caller":"traceutil/trace.go:171","msg":"trace[249550062] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:588; }","duration":"311.390531ms","start":"2026-08-11T12:50:18.93533Z","end":"2026-08-11T12:50:19.246721Z","steps":["trace[249550062] 'agreement among raft nodes before linearized reading' (duration: 311.063002ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:50:19.246816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:50:18.940967Z","time spent":"305.838706ms","remote":"127.0.0.1:41634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-11T12:50:19.246858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:50:18.935308Z","time spent":"311.520955ms","remote":"127.0.0.1:41634","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":"warn","ts":"2026-08-11T12:50:19.246692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:50:18.673953Z","time spent":"572.657976ms","remote":"127.0.0.1:41890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-11T12:51:29.089411Z","caller":"traceutil/trace.go:171","msg":"trace[430960231] transaction","detail":"{read_only:false; response_revision:1423; number_of_response:1; }","duration":"118.937198ms","start":"2026-08-11T12:51:28.970446Z","end":"2026-08-11T12:51:29.089383Z","steps":["trace[430960231] 'process raft request' (duration: 118.789565ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:51:52.425921Z","caller":"traceutil/trace.go:171","msg":"trace[1220060176] linearizableReadLoop","detail":"{readStateIndex:1907; appliedIndex:1906; }","duration":"339.685433ms","start":"2026-08-11T12:51:52.08621Z","end":"2026-08-11T12:51:52.425896Z","steps":["trace[1220060176] 'read index received' (duration: 339.199654ms)","trace[1220060176] 'applied index is now lower than readState.Index' (duration: 484.718µs)"],"step_count":2} {"level":"warn","ts":"2026-08-11T12:51:52.42625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.443386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T12:51:52.42664Z","caller":"traceutil/trace.go:171","msg":"trace[549133455] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1833; }","duration":"269.877122ms","start":"2026-08-11T12:51:52.156752Z","end":"2026-08-11T12:51:52.426629Z","steps":["trace[549133455] 'agreement among raft nodes before linearized reading' (duration: 269.450326ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:51:52.426232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.992607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T12:51:52.426711Z","caller":"traceutil/trace.go:171","msg":"trace[1013034230] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:1833; }","duration":"340.529514ms","start":"2026-08-11T12:51:52.08617Z","end":"2026-08-11T12:51:52.4267Z","steps":["trace[1013034230] 'agreement among raft nodes before linearized reading' (duration: 339.964596ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:51:52.426747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:51:52.086147Z","time spent":"340.584666ms","remote":"127.0.0.1:44272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"info","ts":"2026-08-11T12:51:52.426335Z","caller":"traceutil/trace.go:171","msg":"trace[1350592113] transaction","detail":"{read_only:false; response_revision:1833; number_of_response:1; }","duration":"407.077085ms","start":"2026-08-11T12:51:52.019236Z","end":"2026-08-11T12:51:52.426313Z","steps":["trace[1350592113] 'process raft request' (duration: 406.537437ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:51:52.426989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:51:52.019206Z","time spent":"407.648353ms","remote":"127.0.0.1:41556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T12:51:52.426554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.356644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a3e658cc-ce8a-47b3-ac34-a31ec51fdaa5\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-08-11T12:51:52.427123Z","caller":"traceutil/trace.go:171","msg":"trace[1390589527] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a3e658cc-ce8a-47b3-ac34-a31ec51fdaa5; range_end:; response_count:1; response_revision:1833; }","duration":"205.969923ms","start":"2026-08-11T12:51:52.22114Z","end":"2026-08-11T12:51:52.42711Z","steps":["trace[1390589527] 'agreement among raft nodes before linearized reading' (duration: 205.298593ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:51:52.585312Z","caller":"traceutil/trace.go:171","msg":"trace[1785800906] transaction","detail":"{read_only:false; response_revision:1837; number_of_response:1; }","duration":"100.274836ms","start":"2026-08-11T12:51:52.48501Z","end":"2026-08-11T12:51:52.585285Z","steps":["trace[1785800906] 'process raft request' (duration: 99.624076ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:51:52.585365Z","caller":"traceutil/trace.go:171","msg":"trace[1155839442] transaction","detail":"{read_only:false; response_revision:1838; number_of_response:1; }","duration":"100.159684ms","start":"2026-08-11T12:51:52.485175Z","end":"2026-08-11T12:51:52.585334Z","steps":["trace[1155839442] 'process raft request' (duration: 99.701736ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:51:52.839852Z","caller":"traceutil/trace.go:171","msg":"trace[484206752] transaction","detail":"{read_only:false; response_revision:1839; number_of_response:1; }","duration":"203.871991ms","start":"2026-08-11T12:51:52.635923Z","end":"2026-08-11T12:51:52.839795Z","steps":["trace[484206752] 'process raft request' (duration: 129.939771ms)","trace[484206752] 'compare' (duration: 73.686987ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:52:03.517941Z","caller":"traceutil/trace.go:171","msg":"trace[301375107] transaction","detail":"{read_only:false; response_revision:1933; number_of_response:1; }","duration":"129.764761ms","start":"2026-08-11T12:52:03.388156Z","end":"2026-08-11T12:52:03.517921Z","steps":["trace[301375107] 'process raft request' (duration: 129.644509ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:52:15.182662Z","caller":"traceutil/trace.go:171","msg":"trace[1878677248] transaction","detail":"{read_only:false; response_revision:2013; number_of_response:1; }","duration":"144.793432ms","start":"2026-08-11T12:52:15.037838Z","end":"2026-08-11T12:52:15.182632Z","steps":["trace[1878677248] 'process raft request' (duration: 127.948735ms)","trace[1878677248] 'compare' (duration: 16.699665ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T12:52:21.558655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.669122ms","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-08-11T12:52:21.558828Z","caller":"traceutil/trace.go:171","msg":"trace[750716787] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2046; }","duration":"223.896355ms","start":"2026-08-11T12:52:21.334909Z","end":"2026-08-11T12:52:21.558805Z","steps":["trace[750716787] 'range keys from in-memory index tree' (duration: 223.413718ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:52:21.966449Z","caller":"traceutil/trace.go:171","msg":"trace[1344217103] transaction","detail":"{read_only:false; response_revision:2050; number_of_response:1; }","duration":"119.006815ms","start":"2026-08-11T12:52:21.847409Z","end":"2026-08-11T12:52:21.966415Z","steps":["trace[1344217103] 'process raft request' (duration: 118.753792ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:52:21.966479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.810217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-11T12:52:21.967004Z","caller":"traceutil/trace.go:171","msg":"trace[1623170189] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2050; }","duration":"115.295563ms","start":"2026-08-11T12:52:21.851635Z","end":"2026-08-11T12:52:21.966931Z","steps":["trace[1623170189] 'agreement among raft nodes before linearized reading' (duration: 114.686685ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:52:22.223102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.91889ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T12:52:22.223309Z","caller":"traceutil/trace.go:171","msg":"trace[1724964159] linearizableReadLoop","detail":"{readStateIndex:2133; appliedIndex:2131; }","duration":"160.911798ms","start":"2026-08-11T12:52:22.062359Z","end":"2026-08-11T12:52:22.22327Z","steps":["trace[1724964159] 'read index received' (duration: 54.748715ms)","trace[1724964159] 'applied index is now lower than readState.Index' (duration: 106.162163ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:52:22.223405Z","caller":"traceutil/trace.go:171","msg":"trace[939788844] transaction","detail":"{read_only:false; response_revision:2052; number_of_response:1; }","duration":"251.620621ms","start":"2026-08-11T12:52:21.971774Z","end":"2026-08-11T12:52:22.223395Z","steps":["trace[939788844] 'process raft request' (duration: 251.442618ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:52:22.223542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.180092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T12:52:22.223615Z","caller":"traceutil/trace.go:171","msg":"trace[1452826515] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:2052; }","duration":"161.293834ms","start":"2026-08-11T12:52:22.062307Z","end":"2026-08-11T12:52:22.223601Z","steps":["trace[1452826515] 'agreement among raft nodes before linearized reading' (duration: 161.178872ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:52:22.223662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.579886ms","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-08-11T12:52:22.223712Z","caller":"traceutil/trace.go:171","msg":"trace[1925496169] transaction","detail":"{read_only:false; response_revision:2051; number_of_response:1; }","duration":"306.538881ms","start":"2026-08-11T12:52:21.917106Z","end":"2026-08-11T12:52:22.223645Z","steps":["trace[1925496169] 'process raft request' (duration: 199.988591ms)","trace[1925496169] 'compare' (duration: 105.767128ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T12:52:22.22398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:52:21.917083Z","time spent":"306.756214ms","remote":"127.0.0.1:41634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T12:52:22.223745Z","caller":"traceutil/trace.go:171","msg":"trace[1408481877] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2052; }","duration":"118.737168ms","start":"2026-08-11T12:52:22.104986Z","end":"2026-08-11T12:52:22.223724Z","steps":["trace[1408481877] 'agreement among raft nodes before linearized reading' (duration: 118.506274ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:52:22.61698Z","caller":"traceutil/trace.go:171","msg":"trace[972188495] transaction","detail":"{read_only:false; response_revision:2053; number_of_response:1; }","duration":"387.796649ms","start":"2026-08-11T12:52:22.228949Z","end":"2026-08-11T12:52:22.616746Z","steps":["trace[972188495] 'process raft request' (duration: 344.874307ms)","trace[972188495] 'compare' (duration: 42.78903ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T12:52:22.617097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:52:22.22893Z","time spent":"388.107393ms","remote":"127.0.0.1:41634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T12:54:04.841576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.627257ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-11T12:54:04.841683Z","caller":"traceutil/trace.go:171","msg":"trace[1850173907] linearizableReadLoop","detail":"{readStateIndex:2814; appliedIndex:2813; }","duration":"109.638314ms","start":"2026-08-11T12:54:04.732025Z","end":"2026-08-11T12:54:04.841664Z","steps":["trace[1850173907] 'read index received' (duration: 44.991µs)","trace[1850173907] 'applied index is now lower than readState.Index' (duration: 109.591633ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T12:54:04.841964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.927157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-11T12:54:04.842097Z","caller":"traceutil/trace.go:171","msg":"trace[1184012031] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2709; }","duration":"110.101435ms","start":"2026-08-11T12:54:04.731968Z","end":"2026-08-11T12:54:04.84207Z","steps":["trace[1184012031] 'agreement among raft nodes before linearized reading' (duration: 109.851824ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:54:04.842443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.746926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-11T12:54:04.842529Z","caller":"traceutil/trace.go:171","msg":"trace[649941533] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2709; }","duration":"108.857311ms","start":"2026-08-11T12:54:04.733645Z","end":"2026-08-11T12:54:04.842502Z","steps":["trace[649941533] 'agreement among raft nodes before linearized reading' (duration: 108.630721ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:54:05.024912Z","caller":"traceutil/trace.go:171","msg":"trace[1056560506] transaction","detail":"{read_only:false; response_revision:2710; number_of_response:1; }","duration":"180.318654ms","start":"2026-08-11T12:54:04.844577Z","end":"2026-08-11T12:54:05.024896Z","steps":["trace[1056560506] 'process raft request' (duration: 180.196908ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:54:05.026832Z","caller":"traceutil/trace.go:171","msg":"trace[801447371] transaction","detail":"{read_only:false; response_revision:2712; number_of_response:1; }","duration":"180.642906ms","start":"2026-08-11T12:54:04.846171Z","end":"2026-08-11T12:54:05.026814Z","steps":["trace[801447371] 'process raft request' (duration: 180.603685ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:54:05.027267Z","caller":"traceutil/trace.go:171","msg":"trace[1614956324] transaction","detail":"{read_only:false; response_revision:2711; number_of_response:1; }","duration":"181.175869ms","start":"2026-08-11T12:54:04.846079Z","end":"2026-08-11T12:54:05.027255Z","steps":["trace[1614956324] 'process raft request' (duration: 180.586704ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:57:06.15112Z","caller":"traceutil/trace.go:171","msg":"trace[1887782818] transaction","detail":"{read_only:false; response_revision:3826; number_of_response:1; }","duration":"127.916935ms","start":"2026-08-11T12:57:06.023169Z","end":"2026-08-11T12:57:06.151086Z","steps":["trace[1887782818] 'process raft request' (duration: 48.281672ms)","trace[1887782818] 'compare' (duration: 79.49763ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:57:06.179842Z","caller":"traceutil/trace.go:171","msg":"trace[1347980623] transaction","detail":"{read_only:false; response_revision:3827; number_of_response:1; }","duration":"132.930747ms","start":"2026-08-11T12:57:06.046887Z","end":"2026-08-11T12:57:06.179818Z","steps":["trace[1347980623] 'process raft request' (duration: 132.840485ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:57:07.094923Z","caller":"traceutil/trace.go:171","msg":"trace[1997375824] transaction","detail":"{read_only:false; response_revision:3831; number_of_response:1; }","duration":"353.23889ms","start":"2026-08-11T12:57:06.74165Z","end":"2026-08-11T12:57:07.094889Z","steps":["trace[1997375824] 'process raft request' (duration: 353.030176ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:57:07.095091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:57:06.741625Z","time spent":"353.405815ms","remote":"127.0.0.1:41464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118319,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-11T12:57:07.134798Z","caller":"traceutil/trace.go:171","msg":"trace[853464269] transaction","detail":"{read_only:false; response_revision:3833; number_of_response:1; }","duration":"267.428719ms","start":"2026-08-11T12:57:06.867342Z","end":"2026-08-11T12:57:07.134771Z","steps":["trace[853464269] 'process raft request' (duration: 267.360248ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:57:07.134792Z","caller":"traceutil/trace.go:171","msg":"trace[1163375857] transaction","detail":"{read_only:false; response_revision:3832; number_of_response:1; }","duration":"388.831652ms","start":"2026-08-11T12:57:06.745926Z","end":"2026-08-11T12:57:07.134758Z","steps":["trace[1163375857] 'process raft request' (duration: 388.640228ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:57:07.13497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T12:57:06.745911Z","time spent":"388.963285ms","remote":"127.0.0.1:41634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T12:57:07.135135Z","caller":"traceutil/trace.go:171","msg":"trace[892059380] linearizableReadLoop","detail":"{readStateIndex:3981; appliedIndex:3978; }","duration":"170.827744ms","start":"2026-08-11T12:57:06.964281Z","end":"2026-08-11T12:57:07.135109Z","steps":["trace[892059380] 'read index received' (duration: 130.547238ms)","trace[892059380] 'applied index is now lower than readState.Index' (duration: 40.279216ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T12:57:07.135332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.033048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-11T12:57:07.135409Z","caller":"traceutil/trace.go:171","msg":"trace[1147035235] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3834; }","duration":"171.149071ms","start":"2026-08-11T12:57:06.96424Z","end":"2026-08-11T12:57:07.135389Z","steps":["trace[1147035235] 'agreement among raft nodes before linearized reading' (duration: 170.960707ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:57:07.135638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.2868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12210"} {"level":"info","ts":"2026-08-11T12:57:07.135833Z","caller":"traceutil/trace.go:171","msg":"trace[165393857] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3834; }","duration":"154.397202ms","start":"2026-08-11T12:57:06.981288Z","end":"2026-08-11T12:57:07.135685Z","steps":["trace[165393857] 'agreement among raft nodes before linearized reading' (duration: 154.003104ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:57:09.026947Z","caller":"traceutil/trace.go:171","msg":"trace[1891931693] transaction","detail":"{read_only:false; response_revision:3844; number_of_response:1; }","duration":"132.161744ms","start":"2026-08-11T12:57:08.894758Z","end":"2026-08-11T12:57:09.02692Z","steps":["trace[1891931693] 'process raft request' (duration: 131.990481ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:57:09.219022Z","caller":"traceutil/trace.go:171","msg":"trace[2090873879] transaction","detail":"{read_only:false; response_revision:3845; number_of_response:1; }","duration":"111.786722ms","start":"2026-08-11T12:57:09.107201Z","end":"2026-08-11T12:57:09.218988Z","steps":["trace[2090873879] 'process raft request' (duration: 25.690931ms)","trace[2090873879] 'compare' (duration: 85.935328ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:57:09.245902Z","caller":"traceutil/trace.go:171","msg":"trace[670191146] transaction","detail":"{read_only:false; response_revision:3847; number_of_response:1; }","duration":"104.958054ms","start":"2026-08-11T12:57:09.14092Z","end":"2026-08-11T12:57:09.245878Z","steps":["trace[670191146] 'process raft request' (duration: 104.511634ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:57:10.304493Z","caller":"traceutil/trace.go:171","msg":"trace[35537414] linearizableReadLoop","detail":"{readStateIndex:4002; appliedIndex:4001; }","duration":"148.463018ms","start":"2026-08-11T12:57:10.156007Z","end":"2026-08-11T12:57:10.30447Z","steps":["trace[35537414] 'read index received' (duration: 117.473992ms)","trace[35537414] 'applied index is now lower than readState.Index' (duration: 30.987816ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:57:10.304612Z","caller":"traceutil/trace.go:171","msg":"trace[615294570] transaction","detail":"{read_only:false; response_revision:3853; number_of_response:1; }","duration":"180.422375ms","start":"2026-08-11T12:57:10.124178Z","end":"2026-08-11T12:57:10.304601Z","steps":["trace[615294570] 'process raft request' (duration: 149.323036ms)","trace[615294570] 'compare' (duration: 30.718721ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T12:57:10.304758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.950623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-11T12:57:10.304813Z","caller":"traceutil/trace.go:171","msg":"trace[580585412] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3853; }","duration":"135.055005ms","start":"2026-08-11T12:57:10.169745Z","end":"2026-08-11T12:57:10.3048Z","steps":["trace[580585412] 'agreement among raft nodes before linearized reading' (duration: 134.867101ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:57:10.304907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.901007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T12:57:10.304947Z","caller":"traceutil/trace.go:171","msg":"trace[311281756] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3853; }","duration":"148.973229ms","start":"2026-08-11T12:57:10.155964Z","end":"2026-08-11T12:57:10.304937Z","steps":["trace[311281756] 'agreement among raft nodes before linearized reading' (duration: 148.913608ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:57:10.305014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.628844ms","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-08-11T12:57:10.305051Z","caller":"traceutil/trace.go:171","msg":"trace[1445041480] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3853; }","duration":"105.689245ms","start":"2026-08-11T12:57:10.199353Z","end":"2026-08-11T12:57:10.305042Z","steps":["trace[1445041480] 'agreement among raft nodes before linearized reading' (duration: 105.597593ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:57:10.891326Z","caller":"traceutil/trace.go:171","msg":"trace[515054060] transaction","detail":"{read_only:false; response_revision:3859; number_of_response:1; }","duration":"203.530198ms","start":"2026-08-11T12:57:10.687767Z","end":"2026-08-11T12:57:10.891297Z","steps":["trace[515054060] 'process raft request' (duration: 173.249449ms)","trace[515054060] 'compare' (duration: 30.145306ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:57:11.360367Z","caller":"traceutil/trace.go:171","msg":"trace[564970919] transaction","detail":"{read_only:false; response_revision:3861; number_of_response:1; }","duration":"106.17371ms","start":"2026-08-11T12:57:11.254169Z","end":"2026-08-11T12:57:11.360343Z","steps":["trace[564970919] 'process raft request' (duration: 105.771282ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:57:12.415307Z","caller":"traceutil/trace.go:171","msg":"trace[653982284] transaction","detail":"{read_only:false; response_revision:3864; number_of_response:1; }","duration":"103.304562ms","start":"2026-08-11T12:57:12.311981Z","end":"2026-08-11T12:57:12.415285Z","steps":["trace[653982284] 'process raft request' (duration: 103.148859ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:57:12.415314Z","caller":"traceutil/trace.go:171","msg":"trace[496985072] linearizableReadLoop","detail":"{readStateIndex:4013; appliedIndex:4012; }","duration":"102.082316ms","start":"2026-08-11T12:57:12.313207Z","end":"2026-08-11T12:57:12.41529Z","steps":["trace[496985072] 'read index received' (duration: 102.039895ms)","trace[496985072] 'applied index is now lower than readState.Index' (duration: 28.241µs)"],"step_count":2} {"level":"warn","ts":"2026-08-11T12:57:12.415597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.364013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T12:57:12.415672Z","caller":"traceutil/trace.go:171","msg":"trace[577003920] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:3864; }","duration":"102.466385ms","start":"2026-08-11T12:57:12.313194Z","end":"2026-08-11T12:57:12.41566Z","steps":["trace[577003920] 'agreement among raft nodes before linearized reading' (duration: 102.26352ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:57:12.437135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.062416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-11T12:57:12.437661Z","caller":"traceutil/trace.go:171","msg":"trace[586141438] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3866; }","duration":"114.620288ms","start":"2026-08-11T12:57:12.323024Z","end":"2026-08-11T12:57:12.437645Z","steps":["trace[586141438] 'agreement among raft nodes before linearized reading' (duration: 114.015255ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:57:12.437362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.17274ms","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-08-11T12:57:12.437848Z","caller":"traceutil/trace.go:171","msg":"trace[1915882882] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3866; }","duration":"121.687221ms","start":"2026-08-11T12:57:12.316148Z","end":"2026-08-11T12:57:12.437836Z","steps":["trace[1915882882] 'agreement among raft nodes before linearized reading' (duration: 121.056938ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:57:12.616259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.999444ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T12:57:12.616446Z","caller":"traceutil/trace.go:171","msg":"trace[214719921] linearizableReadLoop","detail":"{readStateIndex:4017; appliedIndex:4015; }","duration":"166.728199ms","start":"2026-08-11T12:57:12.449699Z","end":"2026-08-11T12:57:12.616427Z","steps":["trace[214719921] 'read index received' (duration: 41.483909ms)","trace[214719921] 'applied index is now lower than readState.Index' (duration: 125.24331ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:57:12.616511Z","caller":"traceutil/trace.go:171","msg":"trace[457237585] transaction","detail":"{read_only:false; response_revision:3867; number_of_response:1; }","duration":"172.124966ms","start":"2026-08-11T12:57:12.444345Z","end":"2026-08-11T12:57:12.61647Z","steps":["trace[457237585] 'process raft request' (duration: 46.621671ms)","trace[457237585] 'compare' (duration: 124.866621ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T12:57:12.616721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.021075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"info","ts":"2026-08-11T12:57:12.616755Z","caller":"traceutil/trace.go:171","msg":"trace[1285458382] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:3868; }","duration":"167.077147ms","start":"2026-08-11T12:57:12.44967Z","end":"2026-08-11T12:57:12.616747Z","steps":["trace[1285458382] 'agreement among raft nodes before linearized reading' (duration: 166.893813ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:57:12.61655Z","caller":"traceutil/trace.go:171","msg":"trace[1445390301] transaction","detail":"{read_only:false; response_revision:3868; number_of_response:1; }","duration":"171.974653ms","start":"2026-08-11T12:57:12.444567Z","end":"2026-08-11T12:57:12.616541Z","steps":["trace[1445390301] 'process raft request' (duration: 171.81778ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:57:12.617282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.072546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-11T12:57:12.61738Z","caller":"traceutil/trace.go:171","msg":"trace[1993572087] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:3868; }","duration":"155.222691ms","start":"2026-08-11T12:57:12.462133Z","end":"2026-08-11T12:57:12.617356Z","steps":["trace[1993572087] 'agreement among raft nodes before linearized reading' (duration: 154.78194ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:57:12.756646Z","caller":"traceutil/trace.go:171","msg":"trace[205079511] transaction","detail":"{read_only:false; response_revision:3869; number_of_response:1; }","duration":"131.771871ms","start":"2026-08-11T12:57:12.624839Z","end":"2026-08-11T12:57:12.756611Z","steps":["trace[205079511] 'process raft request' (duration: 94.41729ms)","trace[205079511] 'compare' (duration: 37.169377ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T12:57:55.280201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.879792ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-11T12:57:55.280398Z","caller":"traceutil/trace.go:171","msg":"trace[628704379] linearizableReadLoop","detail":"{readStateIndex:4874; appliedIndex:4872; }","duration":"123.519459ms","start":"2026-08-11T12:57:55.156863Z","end":"2026-08-11T12:57:55.280382Z","steps":["trace[628704379] 'read index received' (duration: 122.412947ms)","trace[628704379] 'applied index is now lower than readState.Index' (duration: 1.105782ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T12:57:55.280494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.632491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T12:57:55.280527Z","caller":"traceutil/trace.go:171","msg":"trace[1338377584] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4696; }","duration":"123.695782ms","start":"2026-08-11T12:57:55.156821Z","end":"2026-08-11T12:57:55.280517Z","steps":["trace[1338377584] 'agreement among raft nodes before linearized reading' (duration: 123.614541ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:57:55.280782Z","caller":"traceutil/trace.go:171","msg":"trace[1092336131] transaction","detail":"{read_only:false; response_revision:4695; number_of_response:1; }","duration":"261.823702ms","start":"2026-08-11T12:57:55.018904Z","end":"2026-08-11T12:57:55.280728Z","steps":["trace[1092336131] 'process raft request' (duration: 88.347828ms)","trace[1092336131] 'compare' (duration: 172.726459ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:57:55.280793Z","caller":"traceutil/trace.go:171","msg":"trace[1937368878] transaction","detail":"{read_only:false; response_revision:4696; number_of_response:1; }","duration":"142.398365ms","start":"2026-08-11T12:57:55.138361Z","end":"2026-08-11T12:57:55.280759Z","steps":["trace[1937368878] 'process raft request' (duration: 141.953866ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:57:55.790325Z","caller":"traceutil/trace.go:171","msg":"trace[1794285372] transaction","detail":"{read_only:false; response_revision:4702; number_of_response:1; }","duration":"131.783894ms","start":"2026-08-11T12:57:55.658515Z","end":"2026-08-11T12:57:55.790299Z","steps":["trace[1794285372] 'process raft request' (duration: 130.944018ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:57:55.973662Z","caller":"traceutil/trace.go:171","msg":"trace[257020451] transaction","detail":"{read_only:false; response_revision:4703; number_of_response:1; }","duration":"125.877006ms","start":"2026-08-11T12:57:55.847762Z","end":"2026-08-11T12:57:55.973639Z","steps":["trace[257020451] 'process raft request' (duration: 125.750114ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:57:56.11299Z","caller":"traceutil/trace.go:171","msg":"trace[1374850916] linearizableReadLoop","detail":"{readStateIndex:4882; appliedIndex:4881; }","duration":"263.998841ms","start":"2026-08-11T12:57:55.848958Z","end":"2026-08-11T12:57:56.112957Z","steps":["trace[1374850916] 'read index received' (duration: 124.666381ms)","trace[1374850916] 'applied index is now lower than readState.Index' (duration: 139.32873ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T12:57:56.113011Z","caller":"traceutil/trace.go:171","msg":"trace[1931523597] transaction","detail":"{read_only:false; response_revision:4704; number_of_response:1; }","duration":"264.152794ms","start":"2026-08-11T12:57:55.848826Z","end":"2026-08-11T12:57:56.112978Z","steps":["trace[1931523597] 'process raft request' (duration: 262.723217ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T12:57:56.11334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.338707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-68a6aea2-ab97-48f6-931d-a7a301e81e17\" ","response":"range_response_count:1 size:1435"} {"level":"info","ts":"2026-08-11T12:57:56.113412Z","caller":"traceutil/trace.go:171","msg":"trace[1363172032] range","detail":"{range_begin:/registry/persistentvolumes/pvc-68a6aea2-ab97-48f6-931d-a7a301e81e17; range_end:; response_count:1; response_revision:4704; }","duration":"264.47506ms","start":"2026-08-11T12:57:55.84892Z","end":"2026-08-11T12:57:56.113395Z","steps":["trace[1363172032] 'agreement among raft nodes before linearized reading' (duration: 264.117184ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T12:59:36.743209Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2879} {"level":"info","ts":"2026-08-11T12:59:36.853905Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2879,"took":"109.245526ms","hash":1711496397,"current-db-size-bytes":53473280,"current-db-size":"54 MB","current-db-size-in-use-bytes":29802496,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-11T12:59:36.853985Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1711496397,"revision":2879,"compact-revision":-1} {"level":"info","ts":"2026-08-11T13:01:47.747301Z","caller":"traceutil/trace.go:171","msg":"trace[2130917178] transaction","detail":"{read_only:false; response_revision:6470; number_of_response:1; }","duration":"100.024645ms","start":"2026-08-11T13:01:47.647241Z","end":"2026-08-11T13:01:47.747266Z","steps":["trace[2130917178] 'process raft request' (duration: 84.657786ms)","trace[2130917178] 'compare' (duration: 15.023173ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T13:01:47.768818Z","caller":"traceutil/trace.go:171","msg":"trace[2107938718] transaction","detail":"{read_only:false; response_revision:6471; number_of_response:1; }","duration":"104.626439ms","start":"2026-08-11T13:01:47.66415Z","end":"2026-08-11T13:01:47.768777Z","steps":["trace[2107938718] 'process raft request' (duration: 104.304094ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:01:53.272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.195129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12210"} {"level":"info","ts":"2026-08-11T13:01:53.272092Z","caller":"traceutil/trace.go:171","msg":"trace[1959229423] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:6498; }","duration":"356.357781ms","start":"2026-08-11T13:01:52.915716Z","end":"2026-08-11T13:01:53.272073Z","steps":["trace[1959229423] 'range keys from in-memory index tree' (duration: 355.964785ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:01:53.272143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T13:01:52.915676Z","time spent":"356.457124ms","remote":"127.0.0.1:41556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12233,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-08-11T13:01:53.272666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.928717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T13:01:53.272747Z","caller":"traceutil/trace.go:171","msg":"trace[183840560] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6498; }","duration":"116.07541ms","start":"2026-08-11T13:01:53.156654Z","end":"2026-08-11T13:01:53.272729Z","steps":["trace[183840560] 'range keys from in-memory index tree' (duration: 115.854626ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:01:53.273049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.830669ms","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-08-11T13:01:53.273151Z","caller":"traceutil/trace.go:171","msg":"trace[93375830] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6498; }","duration":"235.991712ms","start":"2026-08-11T13:01:53.037135Z","end":"2026-08-11T13:01:53.273127Z","steps":["trace[93375830] 'range keys from in-memory index tree' (duration: 235.424293ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:01:54.871518Z","caller":"traceutil/trace.go:171","msg":"trace[1303563633] transaction","detail":"{read_only:false; response_revision:6508; number_of_response:1; }","duration":"223.984097ms","start":"2026-08-11T13:01:54.647502Z","end":"2026-08-11T13:01:54.871486Z","steps":["trace[1303563633] 'process raft request' (duration: 223.787794ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:01:55.340818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.945637ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-11T13:01:55.341212Z","caller":"traceutil/trace.go:171","msg":"trace[178889850] transaction","detail":"{read_only:false; response_revision:6509; number_of_response:1; }","duration":"574.276136ms","start":"2026-08-11T13:01:54.766903Z","end":"2026-08-11T13:01:55.341179Z","steps":["trace[178889850] 'process raft request' (duration: 574.052813ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:01:55.341409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.087927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T13:01:55.341238Z","caller":"traceutil/trace.go:171","msg":"trace[1716046483] linearizableReadLoop","detail":"{readStateIndex:6746; appliedIndex:6744; }","duration":"195.898394ms","start":"2026-08-11T13:01:55.145315Z","end":"2026-08-11T13:01:55.341214Z","steps":["trace[1716046483] 'read index received' (duration: 86.262µs)","trace[1716046483] 'applied index is now lower than readState.Index' (duration: 195.809292ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T13:01:55.34146Z","caller":"traceutil/trace.go:171","msg":"trace[1944054454] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:6510; }","duration":"196.204389ms","start":"2026-08-11T13:01:55.145243Z","end":"2026-08-11T13:01:55.341447Z","steps":["trace[1944054454] 'agreement among raft nodes before linearized reading' (duration: 196.039946ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:01:55.341461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T13:01:54.766879Z","time spent":"574.418729ms","remote":"127.0.0.1:41634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T13:01:55.34164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.674072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T13:01:55.341848Z","caller":"traceutil/trace.go:171","msg":"trace[1627267668] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6510; }","duration":"184.906957ms","start":"2026-08-11T13:01:55.15691Z","end":"2026-08-11T13:01:55.341817Z","steps":["trace[1627267668] 'agreement among raft nodes before linearized reading' (duration: 184.653702ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:02:07.678099Z","caller":"traceutil/trace.go:171","msg":"trace[2047500162] linearizableReadLoop","detail":"{readStateIndex:6865; appliedIndex:6864; }","duration":"251.277839ms","start":"2026-08-11T13:02:07.426797Z","end":"2026-08-11T13:02:07.678075Z","steps":["trace[2047500162] 'read index received' (duration: 251.067956ms)","trace[2047500162] 'applied index is now lower than readState.Index' (duration: 205.693µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T13:02:07.678476Z","caller":"traceutil/trace.go:171","msg":"trace[1836104371] transaction","detail":"{read_only:false; response_revision:6623; number_of_response:1; }","duration":"290.704452ms","start":"2026-08-11T13:02:07.387746Z","end":"2026-08-11T13:02:07.67845Z","steps":["trace[1836104371] 'process raft request' (duration: 290.145233ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T13:02:07.678677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.860518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-11T13:02:07.678968Z","caller":"traceutil/trace.go:171","msg":"trace[1813549035] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6623; }","duration":"252.200965ms","start":"2026-08-11T13:02:07.42674Z","end":"2026-08-11T13:02:07.678941Z","steps":["trace[1813549035] 'agreement among raft nodes before linearized reading' (duration: 251.765917ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T13:04:36.75059Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5602} {"level":"info","ts":"2026-08-11T13:04:36.875745Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5602,"took":"124.03694ms","hash":4223500224,"current-db-size-bytes":53473280,"current-db-size":"54 MB","current-db-size-in-use-bytes":18554880,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-08-11T13:04:36.875848Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4223500224,"revision":5602,"compact-revision":2879}