{"level":"warn","ts":"2026-07-16T06:51:17.512252Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-16T06:51:17.512353Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.231: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.19.213.231:2380","--initial-cluster=instance=https://199.19.213.231:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.231:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.231: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-07-16T06:51:17.512431Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-16T06:51:17.512444Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.231:2380"]} {"level":"info","ts":"2026-07-16T06:51:17.512466Z","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-07-16T06:51:17.513169Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.231:2379"]} {"level":"info","ts":"2026-07-16T06:51:17.513257Z","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.19.213.231:2380"],"listen-peer-urls":["https://199.19.213.231:2380"],"advertise-client-urls":["https://199.19.213.231:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.231:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.231: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-07-16T06:51:17.516417Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.954544ms"} {"level":"info","ts":"2026-07-16T06:51:17.521904Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"8bb149ac537fafcb","cluster-id":"6a60f8f1d4e49112"} {"level":"info","ts":"2026-07-16T06:51:17.521995Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8bb149ac537fafcb switched to configuration voters=()"} {"level":"info","ts":"2026-07-16T06:51:17.522014Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8bb149ac537fafcb became follower at term 0"} {"level":"info","ts":"2026-07-16T06:51:17.522025Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8bb149ac537fafcb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-16T06:51:17.522037Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8bb149ac537fafcb became follower at term 1"} {"level":"info","ts":"2026-07-16T06:51:17.522076Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8bb149ac537fafcb switched to configuration voters=(10065907646633848779)"} {"level":"warn","ts":"2026-07-16T06:51:17.529103Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-16T06:51:17.530659Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-16T06:51:17.531687Z","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-07-16T06:51:17.533693Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"8bb149ac537fafcb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-16T06:51:17.534543Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"8bb149ac537fafcb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-16T06:51:17.53469Z","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-07-16T06:51:17.534762Z","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-07-16T06:51:17.534783Z","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-07-16T06:51:17.536341Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8bb149ac537fafcb switched to configuration voters=(10065907646633848779)"} {"level":"info","ts":"2026-07-16T06:51:17.536507Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6a60f8f1d4e49112","local-member-id":"8bb149ac537fafcb","added-peer-id":"8bb149ac537fafcb","added-peer-peer-urls":["https://199.19.213.231:2380"]} {"level":"info","ts":"2026-07-16T06:51:17.538377Z","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-07-16T06:51:17.538483Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.231:2380"} {"level":"info","ts":"2026-07-16T06:51:17.53855Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.231:2380"} {"level":"info","ts":"2026-07-16T06:51:17.538569Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"8bb149ac537fafcb","initial-advertise-peer-urls":["https://199.19.213.231:2380"],"listen-peer-urls":["https://199.19.213.231:2380"],"advertise-client-urls":["https://199.19.213.231:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.231:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-16T06:51:17.538608Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-16T06:51:18.022476Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8bb149ac537fafcb is starting a new election at term 1"} {"level":"info","ts":"2026-07-16T06:51:18.022532Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8bb149ac537fafcb became pre-candidate at term 1"} {"level":"info","ts":"2026-07-16T06:51:18.022555Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8bb149ac537fafcb received MsgPreVoteResp from 8bb149ac537fafcb at term 1"} {"level":"info","ts":"2026-07-16T06:51:18.022574Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8bb149ac537fafcb became candidate at term 2"} {"level":"info","ts":"2026-07-16T06:51:18.022585Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8bb149ac537fafcb received MsgVoteResp from 8bb149ac537fafcb at term 2"} {"level":"info","ts":"2026-07-16T06:51:18.022601Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8bb149ac537fafcb became leader at term 2"} {"level":"info","ts":"2026-07-16T06:51:18.022615Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8bb149ac537fafcb elected leader 8bb149ac537fafcb at term 2"} {"level":"info","ts":"2026-07-16T06:51:18.024106Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T06:51:18.024383Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"8bb149ac537fafcb","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.231:2379]}","request-path":"/0/members/8bb149ac537fafcb/attributes","cluster-id":"6a60f8f1d4e49112","publish-timeout":"7s"} {"level":"info","ts":"2026-07-16T06:51:18.024887Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6a60f8f1d4e49112","local-member-id":"8bb149ac537fafcb","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T06:51:18.024952Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T06:51:18.024968Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T06:51:18.024983Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-16T06:51:18.025164Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-16T06:51:18.025246Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-16T06:51:18.025671Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-16T06:51:18.028909Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.231:2379"} {"level":"info","ts":"2026-07-16T06:51:18.029052Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-16T06:51:47.021824Z","caller":"traceutil/trace.go:171","msg":"trace[1978646768] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"177.03403ms","start":"2026-07-16T06:51:46.844763Z","end":"2026-07-16T06:51:47.021797Z","steps":["trace[1978646768] 'process raft request' (duration: 176.866925ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:51:54.668616Z","caller":"traceutil/trace.go:171","msg":"trace[2075082475] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"114.266045ms","start":"2026-07-16T06:51:54.554323Z","end":"2026-07-16T06:51:54.668589Z","steps":["trace[2075082475] 'process raft request' (duration: 114.10379ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:51:54.67279Z","caller":"traceutil/trace.go:171","msg":"trace[341031575] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"108.175108ms","start":"2026-07-16T06:51:54.564583Z","end":"2026-07-16T06:51:54.672758Z","steps":["trace[341031575] 'process raft request' (duration: 107.994933ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:51:54.672921Z","caller":"traceutil/trace.go:171","msg":"trace[240234020] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"108.279962ms","start":"2026-07-16T06:51:54.564633Z","end":"2026-07-16T06:51:54.672913Z","steps":["trace[240234020] 'process raft request' (duration: 108.035445ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:51:54.673053Z","caller":"traceutil/trace.go:171","msg":"trace[661761922] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"105.707367ms","start":"2026-07-16T06:51:54.567338Z","end":"2026-07-16T06:51:54.673046Z","steps":["trace[661761922] 'process raft request' (duration: 105.372088ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:51:54.673513Z","caller":"traceutil/trace.go:171","msg":"trace[400821099] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"102.539556ms","start":"2026-07-16T06:51:54.57094Z","end":"2026-07-16T06:51:54.673479Z","steps":["trace[400821099] 'process raft request' (duration: 101.881597ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:51:54.673687Z","caller":"traceutil/trace.go:171","msg":"trace[1330962215] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"101.744103ms","start":"2026-07-16T06:51:54.571932Z","end":"2026-07-16T06:51:54.673676Z","steps":["trace[1330962215] 'process raft request' (duration: 101.480505ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:51:54.673697Z","caller":"traceutil/trace.go:171","msg":"trace[1611799153] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"102.0144ms","start":"2026-07-16T06:51:54.571656Z","end":"2026-07-16T06:51:54.673671Z","steps":["trace[1611799153] 'process raft request' (duration: 101.686381ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:52:00.751885Z","caller":"traceutil/trace.go:171","msg":"trace[835507697] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"161.46904ms","start":"2026-07-16T06:52:00.590388Z","end":"2026-07-16T06:52:00.751858Z","steps":["trace[835507697] 'process raft request' (duration: 160.923314ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:52:01.304972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.357653ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T06:52:01.305114Z","caller":"traceutil/trace.go:171","msg":"trace[326112037] linearizableReadLoop","detail":"{readStateIndex:655; appliedIndex:654; }","duration":"469.88872ms","start":"2026-07-16T06:52:00.835202Z","end":"2026-07-16T06:52:01.305091Z","steps":["trace[326112037] 'read index received' (duration: 174.818926ms)","trace[326112037] 'applied index is now lower than readState.Index' (duration: 295.068604ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:52:01.305395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.285418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.231\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-16T06:52:01.305427Z","caller":"traceutil/trace.go:171","msg":"trace[302727436] range","detail":"{range_begin:/registry/masterleases/199.19.213.231; range_end:; response_count:1; response_revision:632; }","duration":"260.35757ms","start":"2026-07-16T06:52:01.04506Z","end":"2026-07-16T06:52:01.305417Z","steps":["trace[302727436] 'agreement among raft nodes before linearized reading' (duration: 260.284878ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:52:01.305474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.237001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:52:01.305585Z","caller":"traceutil/trace.go:171","msg":"trace[397744854] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:632; }","duration":"470.362364ms","start":"2026-07-16T06:52:00.83519Z","end":"2026-07-16T06:52:01.305552Z","steps":["trace[397744854] 'agreement among raft nodes before linearized reading' (duration: 470.171929ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:52:01.305673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.904077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-16T06:52:01.305649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:52:00.835106Z","time spent":"470.528659ms","remote":"127.0.0.1:33274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"info","ts":"2026-07-16T06:52:01.305718Z","caller":"traceutil/trace.go:171","msg":"trace[313369641] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:632; }","duration":"176.988849ms","start":"2026-07-16T06:52:01.12872Z","end":"2026-07-16T06:52:01.305709Z","steps":["trace[313369641] 'agreement among raft nodes before linearized reading' (duration: 176.867935ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:52:01.305711Z","caller":"traceutil/trace.go:171","msg":"trace[100064037] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"494.403969ms","start":"2026-07-16T06:52:00.811249Z","end":"2026-07-16T06:52:01.305653Z","steps":["trace[100064037] 'process raft request' (duration: 198.851061ms)","trace[100064037] 'compare' (duration: 294.111466ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:52:01.306064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:52:00.811228Z","time spent":"494.734649ms","remote":"127.0.0.1:33226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T06:52:02.235973Z","caller":"traceutil/trace.go:171","msg":"trace[810583274] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:658; }","duration":"240.785894ms","start":"2026-07-16T06:52:01.995163Z","end":"2026-07-16T06:52:02.235949Z","steps":["trace[810583274] 'read index received' (duration: 240.091264ms)","trace[810583274] 'applied index is now lower than readState.Index' (duration: 693.58µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:52:02.236047Z","caller":"traceutil/trace.go:171","msg":"trace[2028936537] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"539.075571ms","start":"2026-07-16T06:52:01.696953Z","end":"2026-07-16T06:52:02.236028Z","steps":["trace[2028936537] 'process raft request' (duration: 538.496264ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:52:02.236164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:52:01.696926Z","time spent":"539.167104ms","remote":"127.0.0.1:33144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T06:52:02.236257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.994996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-16T06:52:02.236294Z","caller":"traceutil/trace.go:171","msg":"trace[1896174742] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:635; }","duration":"217.048437ms","start":"2026-07-16T06:52:02.019235Z","end":"2026-07-16T06:52:02.236283Z","steps":["trace[1896174742] 'agreement among raft nodes before linearized reading' (duration: 216.970085ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:52:02.236179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.020851ms","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-07-16T06:52:02.236379Z","caller":"traceutil/trace.go:171","msg":"trace[1971193304] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:635; }","duration":"241.265158ms","start":"2026-07-16T06:52:01.995099Z","end":"2026-07-16T06:52:02.236364Z","steps":["trace[1971193304] 'agreement among raft nodes before linearized reading' (duration: 240.97905ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:52:17.493218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.069719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:52:17.493299Z","caller":"traceutil/trace.go:171","msg":"trace[1295477832] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:792; }","duration":"206.194593ms","start":"2026-07-16T06:52:17.287087Z","end":"2026-07-16T06:52:17.493281Z","steps":["trace[1295477832] 'range keys from in-memory index tree' (duration: 205.997128ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:52:18.085651Z","caller":"traceutil/trace.go:171","msg":"trace[778924130] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"563.856328ms","start":"2026-07-16T06:52:17.52176Z","end":"2026-07-16T06:52:18.085616Z","steps":["trace[778924130] 'process raft request' (duration: 562.509249ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:52:18.085792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:52:17.521735Z","time spent":"563.986562ms","remote":"127.0.0.1:33282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T06:52:18.08713Z","caller":"traceutil/trace.go:171","msg":"trace[1245173834] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"183.829497ms","start":"2026-07-16T06:52:17.903284Z","end":"2026-07-16T06:52:18.087114Z","steps":["trace[1245173834] 'process raft request' (duration: 183.731394ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:52:53.448744Z","caller":"traceutil/trace.go:171","msg":"trace[755500114] linearizableReadLoop","detail":"{readStateIndex:1053; appliedIndex:1052; }","duration":"159.4142ms","start":"2026-07-16T06:52:53.289302Z","end":"2026-07-16T06:52:53.448717Z","steps":["trace[755500114] 'read index received' (duration: 159.149853ms)","trace[755500114] 'applied index is now lower than readState.Index' (duration: 262.947µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:52:53.448822Z","caller":"traceutil/trace.go:171","msg":"trace[1192533505] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"407.773362ms","start":"2026-07-16T06:52:53.041024Z","end":"2026-07-16T06:52:53.448798Z","steps":["trace[1192533505] 'process raft request' (duration: 407.524864ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:52:53.44888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.585245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:52:53.448912Z","caller":"traceutil/trace.go:171","msg":"trace[476633259] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1016; }","duration":"159.674498ms","start":"2026-07-16T06:52:53.289228Z","end":"2026-07-16T06:52:53.448903Z","steps":["trace[476633259] 'agreement among raft nodes before linearized reading' (duration: 159.593535ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:52:53.448943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:52:53.040994Z","time spent":"407.879505ms","remote":"127.0.0.1:33282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T06:52:53.913668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.167721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-16T06:52:53.913758Z","caller":"traceutil/trace.go:171","msg":"trace[1676012615] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1016; }","duration":"234.316306ms","start":"2026-07-16T06:52:53.679421Z","end":"2026-07-16T06:52:53.913737Z","steps":["trace[1676012615] 'range keys from in-memory index tree' (duration: 233.974876ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:53:14.709972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.436571ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T06:53:14.710051Z","caller":"traceutil/trace.go:171","msg":"trace[1793882867] linearizableReadLoop","detail":"{readStateIndex:1126; appliedIndex:1125; }","duration":"150.10179ms","start":"2026-07-16T06:53:14.559937Z","end":"2026-07-16T06:53:14.710038Z","steps":["trace[1793882867] 'read index received' (duration: 515.005µs)","trace[1793882867] 'applied index is now lower than readState.Index' (duration: 149.585515ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:53:14.710111Z","caller":"traceutil/trace.go:171","msg":"trace[1166656914] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"252.227043ms","start":"2026-07-16T06:53:14.457876Z","end":"2026-07-16T06:53:14.710104Z","steps":["trace[1166656914] 'process raft request' (duration: 102.565635ms)","trace[1166656914] 'compare' (duration: 149.181854ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:53:14.710322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.383858ms","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-07-16T06:53:14.710344Z","caller":"traceutil/trace.go:171","msg":"trace[567359023] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1085; }","duration":"150.44078ms","start":"2026-07-16T06:53:14.559896Z","end":"2026-07-16T06:53:14.710337Z","steps":["trace[567359023] 'agreement among raft nodes before linearized reading' (duration: 150.344747ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:53:14.710411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.377851ms","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-07-16T06:53:14.710461Z","caller":"traceutil/trace.go:171","msg":"trace[1318284853] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1085; }","duration":"129.471864ms","start":"2026-07-16T06:53:14.580976Z","end":"2026-07-16T06:53:14.710447Z","steps":["trace[1318284853] 'agreement among raft nodes before linearized reading' (duration: 129.32844ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:53:14.710442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.455733ms","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-07-16T06:53:14.710653Z","caller":"traceutil/trace.go:171","msg":"trace[1971037314] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1085; }","duration":"120.71445ms","start":"2026-07-16T06:53:14.589914Z","end":"2026-07-16T06:53:14.710629Z","steps":["trace[1971037314] 'agreement among raft nodes before linearized reading' (duration: 120.408251ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:53:15.014987Z","caller":"traceutil/trace.go:171","msg":"trace[1837387177] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"301.20375ms","start":"2026-07-16T06:53:14.713741Z","end":"2026-07-16T06:53:15.014945Z","steps":["trace[1837387177] 'process raft request' (duration: 301.087307ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:53:15.015207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:53:14.713724Z","time spent":"301.406846ms","remote":"127.0.0.1:33282","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-07-16T06:53:15.033206Z","caller":"traceutil/trace.go:171","msg":"trace[42657850] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"319.052526ms","start":"2026-07-16T06:53:14.714134Z","end":"2026-07-16T06:53:15.033186Z","steps":["trace[42657850] 'process raft request' (duration: 318.917012ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:53:15.033351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:53:14.714126Z","time spent":"319.143468ms","remote":"127.0.0.1:33282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T06:53:15.033373Z","caller":"traceutil/trace.go:171","msg":"trace[548976070] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"318.82623ms","start":"2026-07-16T06:53:14.714522Z","end":"2026-07-16T06:53:15.033349Z","steps":["trace[548976070] 'process raft request' (duration: 318.616304ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:53:15.033489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:53:14.714498Z","time spent":"318.926533ms","remote":"127.0.0.1:33282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T06:53:16.240482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.643913ms","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-07-16T06:53:16.240564Z","caller":"traceutil/trace.go:171","msg":"trace[1203456397] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1089; }","duration":"208.786677ms","start":"2026-07-16T06:53:16.031764Z","end":"2026-07-16T06:53:16.240551Z","steps":["trace[1203456397] 'range keys from in-memory index tree' (duration: 208.221781ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:53:16.592229Z","caller":"traceutil/trace.go:171","msg":"trace[1544746240] linearizableReadLoop","detail":"{readStateIndex:1132; appliedIndex:1130; }","duration":"304.742622ms","start":"2026-07-16T06:53:16.287469Z","end":"2026-07-16T06:53:16.592212Z","steps":["trace[1544746240] 'read index received' (duration: 255.701544ms)","trace[1544746240] 'applied index is now lower than readState.Index' (duration: 49.040058ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:53:16.59233Z","caller":"traceutil/trace.go:171","msg":"trace[1587096898] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"347.606751ms","start":"2026-07-16T06:53:16.244716Z","end":"2026-07-16T06:53:16.592322Z","steps":["trace[1587096898] 'process raft request' (duration: 347.401996ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:53:16.592438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:53:16.244699Z","time spent":"347.652053ms","remote":"127.0.0.1:33282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T06:53:16.592515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.03629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:53:16.59259Z","caller":"traceutil/trace.go:171","msg":"trace[555444908] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1090; }","duration":"305.144544ms","start":"2026-07-16T06:53:16.287427Z","end":"2026-07-16T06:53:16.592571Z","steps":["trace[555444908] 'agreement among raft nodes before linearized reading' (duration: 305.03346ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:53:16.592628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:53:16.287415Z","time spent":"305.205915ms","remote":"127.0.0.1:33038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-16T06:53:16.592723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.61053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-16T06:53:16.592816Z","caller":"traceutil/trace.go:171","msg":"trace[1237313029] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1090; }","duration":"238.768515ms","start":"2026-07-16T06:53:16.354009Z","end":"2026-07-16T06:53:16.592778Z","steps":["trace[1237313029] 'agreement among raft nodes before linearized reading' (duration: 238.500467ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:53:16.822614Z","caller":"traceutil/trace.go:171","msg":"trace[1610034692] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"225.017647ms","start":"2026-07-16T06:53:16.597566Z","end":"2026-07-16T06:53:16.822584Z","steps":["trace[1610034692] 'process raft request' (duration: 125.18755ms)","trace[1610034692] 'compare' (duration: 99.279791ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:53:17.182294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.847899ms","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-07-16T06:53:17.182378Z","caller":"traceutil/trace.go:171","msg":"trace[20739191] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1091; }","duration":"144.974213ms","start":"2026-07-16T06:53:17.037384Z","end":"2026-07-16T06:53:17.182358Z","steps":["trace[20739191] 'range keys from in-memory index tree' (duration: 144.632883ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:53:17.182533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.676224ms","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-07-16T06:53:17.182643Z","caller":"traceutil/trace.go:171","msg":"trace[310159247] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1091; }","duration":"144.837118ms","start":"2026-07-16T06:53:17.037783Z","end":"2026-07-16T06:53:17.182621Z","steps":["trace[310159247] 'range keys from in-memory index tree' (duration: 144.457187ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:53:17.182761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.200108ms","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-07-16T06:53:17.182813Z","caller":"traceutil/trace.go:171","msg":"trace[1174534248] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1091; }","duration":"164.322391ms","start":"2026-07-16T06:53:17.018476Z","end":"2026-07-16T06:53:17.182798Z","steps":["trace[1174534248] 'range keys from in-memory index tree' (duration: 163.798036ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:53:18.156549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.810333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:53:18.156631Z","caller":"traceutil/trace.go:171","msg":"trace[892453801] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1095; }","duration":"343.935466ms","start":"2026-07-16T06:53:17.812678Z","end":"2026-07-16T06:53:18.156613Z","steps":["trace[892453801] 'count revisions from in-memory index tree' (duration: 343.74564ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:53:18.156673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:53:17.812662Z","time spent":"344.000878ms","remote":"127.0.0.1:33192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-07-16T06:53:33.854352Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12667393653210389577,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-16T06:53:33.872329Z","caller":"traceutil/trace.go:171","msg":"trace[555350123] transaction","detail":"{read_only:false; response_revision:1196; number_of_response:1; }","duration":"520.49712ms","start":"2026-07-16T06:53:33.351804Z","end":"2026-07-16T06:53:33.872301Z","steps":["trace[555350123] 'process raft request' (duration: 520.358756ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:53:33.87244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:53:33.351792Z","time spent":"520.577362ms","remote":"127.0.0.1:33282","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-07-16T06:53:33.873425Z","caller":"traceutil/trace.go:171","msg":"trace[2077069053] linearizableReadLoop","detail":"{readStateIndex:1245; appliedIndex:1243; }","duration":"519.769039ms","start":"2026-07-16T06:53:33.353638Z","end":"2026-07-16T06:53:33.873407Z","steps":["trace[2077069053] 'read index received' (duration: 518.659507ms)","trace[2077069053] 'applied index is now lower than readState.Index' (duration: 1.108982ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:53:33.873639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.986395ms","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-07-16T06:53:33.873625Z","caller":"traceutil/trace.go:171","msg":"trace[367253789] transaction","detail":"{read_only:false; response_revision:1197; number_of_response:1; }","duration":"521.488719ms","start":"2026-07-16T06:53:33.352089Z","end":"2026-07-16T06:53:33.873578Z","steps":["trace[367253789] 'process raft request' (duration: 521.18317ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:53:33.873675Z","caller":"traceutil/trace.go:171","msg":"trace[1924697106] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1198; }","duration":"520.077358ms","start":"2026-07-16T06:53:33.353591Z","end":"2026-07-16T06:53:33.873668Z","steps":["trace[1924697106] 'agreement among raft nodes before linearized reading' (duration: 519.904673ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:53:33.873625Z","caller":"traceutil/trace.go:171","msg":"trace[521547110] transaction","detail":"{read_only:false; response_revision:1198; number_of_response:1; }","duration":"150.251995ms","start":"2026-07-16T06:53:33.723343Z","end":"2026-07-16T06:53:33.873595Z","steps":["trace[521547110] 'process raft request' (duration: 150.018048ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:53:33.873697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:53:33.353582Z","time spent":"520.110279ms","remote":"127.0.0.1:33282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-16T06:53:33.873781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:53:33.352071Z","time spent":"521.625973ms","remote":"127.0.0.1:33282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T06:53:49.723849Z","caller":"traceutil/trace.go:171","msg":"trace[2108624607] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"428.320986ms","start":"2026-07-16T06:53:49.295502Z","end":"2026-07-16T06:53:49.723823Z","steps":["trace[2108624607] 'process raft request' (duration: 428.179752ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:53:49.72399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:53:49.295482Z","time spent":"428.43683ms","remote":"127.0.0.1:33212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":915,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T06:53:49.876425Z","caller":"traceutil/trace.go:171","msg":"trace[988821052] linearizableReadLoop","detail":"{readStateIndex:1317; appliedIndex:1314; }","duration":"401.079769ms","start":"2026-07-16T06:53:49.475316Z","end":"2026-07-16T06:53:49.876395Z","steps":["trace[988821052] 'read index received' (duration: 248.389083ms)","trace[988821052] 'applied index is now lower than readState.Index' (duration: 152.689676ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:53:49.876476Z","caller":"traceutil/trace.go:171","msg":"trace[1728638840] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"579.56302ms","start":"2026-07-16T06:53:49.296873Z","end":"2026-07-16T06:53:49.876436Z","steps":["trace[1728638840] 'process raft request' (duration: 579.315993ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:53:49.876568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.220873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-16T06:53:49.876633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:53:49.296857Z","time spent":"579.692373ms","remote":"127.0.0.1:33284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T06:53:49.876641Z","caller":"traceutil/trace.go:171","msg":"trace[507944665] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1267; }","duration":"401.328556ms","start":"2026-07-16T06:53:49.475272Z","end":"2026-07-16T06:53:49.876601Z","steps":["trace[507944665] 'agreement among raft nodes before linearized reading' (duration: 401.225413ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:53:49.876721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:53:49.475253Z","time spent":"401.456089ms","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"info","ts":"2026-07-16T06:53:49.876776Z","caller":"traceutil/trace.go:171","msg":"trace[134640071] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"575.558234ms","start":"2026-07-16T06:53:49.301195Z","end":"2026-07-16T06:53:49.876753Z","steps":["trace[134640071] 'process raft request' (duration: 575.153823ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:53:49.876893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:53:49.301172Z","time spent":"575.652306ms","remote":"127.0.0.1:33528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9375,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T06:53:50.834979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.891225ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T06:53:50.835173Z","caller":"traceutil/trace.go:171","msg":"trace[1495527709] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"403.990653ms","start":"2026-07-16T06:53:50.431169Z","end":"2026-07-16T06:53:50.835159Z","steps":["trace[1495527709] 'process raft request' (duration: 50.862011ms)","trace[1495527709] 'compare' (duration: 352.774902ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:53:50.835225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:53:50.431146Z","time spent":"404.056834ms","remote":"127.0.0.1:33282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T06:53:51.192462Z","caller":"traceutil/trace.go:171","msg":"trace[1625320595] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"352.306808ms","start":"2026-07-16T06:53:50.840121Z","end":"2026-07-16T06:53:51.192428Z","steps":["trace[1625320595] 'process raft request' (duration: 256.719123ms)","trace[1625320595] 'compare' (duration: 95.300956ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:53:51.192486Z","caller":"traceutil/trace.go:171","msg":"trace[2033561901] linearizableReadLoop","detail":"{readStateIndex:1329; appliedIndex:1328; }","duration":"238.192489ms","start":"2026-07-16T06:53:50.954268Z","end":"2026-07-16T06:53:51.192461Z","steps":["trace[2033561901] 'read index received' (duration: 142.553803ms)","trace[2033561901] 'applied index is now lower than readState.Index' (duration: 95.637266ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:53:51.192667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:53:50.840104Z","time spent":"352.445612ms","remote":"127.0.0.1:33282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T06:53:51.192678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.391814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:53:51.192832Z","caller":"traceutil/trace.go:171","msg":"trace[1027039389] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1279; }","duration":"238.578149ms","start":"2026-07-16T06:53:50.954221Z","end":"2026-07-16T06:53:51.192799Z","steps":["trace[1027039389] 'agreement among raft nodes before linearized reading' (duration: 238.351953ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:53:51.192952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.071568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.231\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-16T06:53:51.192988Z","caller":"traceutil/trace.go:171","msg":"trace[1057086077] range","detail":"{range_begin:/registry/masterleases/199.19.213.231; range_end:; response_count:1; response_revision:1279; }","duration":"143.106729ms","start":"2026-07-16T06:53:51.049872Z","end":"2026-07-16T06:53:51.192979Z","steps":["trace[1057086077] 'agreement among raft nodes before linearized reading' (duration: 142.994105ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:53:51.193268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.625461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-16T06:53:51.193302Z","caller":"traceutil/trace.go:171","msg":"trace[1975460749] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1279; }","duration":"210.692893ms","start":"2026-07-16T06:53:50.982602Z","end":"2026-07-16T06:53:51.193295Z","steps":["trace[1975460749] 'agreement among raft nodes before linearized reading' (duration: 210.225299ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:53:52.25594Z","caller":"traceutil/trace.go:171","msg":"trace[1635006475] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"198.355896ms","start":"2026-07-16T06:53:52.057561Z","end":"2026-07-16T06:53:52.255917Z","steps":["trace[1635006475] 'process raft request' (duration: 198.304554ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:53:52.256247Z","caller":"traceutil/trace.go:171","msg":"trace[1993552758] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"198.689256ms","start":"2026-07-16T06:53:52.057507Z","end":"2026-07-16T06:53:52.256196Z","steps":["trace[1993552758] 'process raft request' (duration: 173.332113ms)","trace[1993552758] 'compare' (duration: 24.686933ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:54:02.123285Z","caller":"traceutil/trace.go:171","msg":"trace[1232295718] transaction","detail":"{read_only:false; response_revision:1356; number_of_response:1; }","duration":"261.838452ms","start":"2026-07-16T06:54:01.86142Z","end":"2026-07-16T06:54:02.123259Z","steps":["trace[1232295718] 'process raft request' (duration: 261.569055ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:54:02.808818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.006829ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:54:02.808948Z","caller":"traceutil/trace.go:171","msg":"trace[1570762185] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1356; }","duration":"407.150393ms","start":"2026-07-16T06:54:02.401779Z","end":"2026-07-16T06:54:02.808929Z","steps":["trace[1570762185] 'range keys from in-memory index tree' (duration: 406.989779ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:54:02.809179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.466549ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T06:54:02.809247Z","caller":"traceutil/trace.go:171","msg":"trace[817959836] linearizableReadLoop","detail":"{readStateIndex:1410; appliedIndex:1409; }","duration":"654.022432ms","start":"2026-07-16T06:54:02.155217Z","end":"2026-07-16T06:54:02.809239Z","steps":["trace[817959836] 'read index received' (duration: 261.352337ms)","trace[817959836] 'applied index is now lower than readState.Index' (duration: 392.669085ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:54:02.809481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.837304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-16T06:54:02.809524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.864338ms","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-07-16T06:54:02.809491Z","caller":"traceutil/trace.go:171","msg":"trace[608358089] transaction","detail":"{read_only:false; response_revision:1357; number_of_response:1; }","duration":"725.965023ms","start":"2026-07-16T06:54:02.083478Z","end":"2026-07-16T06:54:02.809443Z","steps":["trace[608358089] 'process raft request' (duration: 333.175605ms)","trace[608358089] 'compare' (duration: 392.266873ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:54:02.809548Z","caller":"traceutil/trace.go:171","msg":"trace[462041558] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1357; }","duration":"515.94325ms","start":"2026-07-16T06:54:02.293599Z","end":"2026-07-16T06:54:02.809542Z","steps":["trace[462041558] 'agreement among raft nodes before linearized reading' (duration: 515.841777ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:54:02.809539Z","caller":"traceutil/trace.go:171","msg":"trace[608268636] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1357; }","duration":"518.953817ms","start":"2026-07-16T06:54:02.290571Z","end":"2026-07-16T06:54:02.809525Z","steps":["trace[608268636] 'agreement among raft nodes before linearized reading' (duration: 518.835603ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:54:02.809578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:54:02.29356Z","time spent":"516.012852ms","remote":"127.0.0.1:33282","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-07-16T06:54:02.809587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:54:02.290546Z","time spent":"519.035969ms","remote":"127.0.0.1:33038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-16T06:54:02.809581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.350042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-16T06:54:02.809615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.299535ms","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-07-16T06:54:02.809657Z","caller":"traceutil/trace.go:171","msg":"trace[1185152035] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1357; }","duration":"654.473706ms","start":"2026-07-16T06:54:02.155166Z","end":"2026-07-16T06:54:02.80964Z","steps":["trace[1185152035] 'agreement among raft nodes before linearized reading' (duration: 654.228339ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:54:02.809693Z","caller":"traceutil/trace.go:171","msg":"trace[597934637] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1357; }","duration":"511.425339ms","start":"2026-07-16T06:54:02.298245Z","end":"2026-07-16T06:54:02.809671Z","steps":["trace[597934637] 'agreement among raft nodes before linearized reading' (duration: 511.179352ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:54:02.809693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:54:02.083452Z","time spent":"726.125978ms","remote":"127.0.0.1:33282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T06:54:02.809701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:54:02.155149Z","time spent":"654.543848ms","remote":"127.0.0.1:33282","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-07-16T06:54:02.809741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:54:02.298226Z","time spent":"511.505922ms","remote":"127.0.0.1:33282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-16T06:54:03.153172Z","caller":"traceutil/trace.go:171","msg":"trace[1335062594] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"338.243662ms","start":"2026-07-16T06:54:02.814899Z","end":"2026-07-16T06:54:03.153143Z","steps":["trace[1335062594] 'process raft request' (duration: 338.17526ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:54:03.153348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:54:02.814886Z","time spent":"338.372495ms","remote":"127.0.0.1:33282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T06:54:03.153645Z","caller":"traceutil/trace.go:171","msg":"trace[692259731] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"339.739455ms","start":"2026-07-16T06:54:02.813833Z","end":"2026-07-16T06:54:03.153573Z","steps":["trace[692259731] 'process raft request' (duration: 298.431151ms)","trace[692259731] 'compare' (duration: 40.634515ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:54:03.154072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:54:02.813816Z","time spent":"339.90951ms","remote":"127.0.0.1:33282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T06:54:03.159162Z","caller":"traceutil/trace.go:171","msg":"trace[1063466858] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"116.922771ms","start":"2026-07-16T06:54:03.042222Z","end":"2026-07-16T06:54:03.159145Z","steps":["trace[1063466858] 'process raft request' (duration: 116.832299ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:55:48.929396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.551262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-07-16T06:55:48.929486Z","caller":"traceutil/trace.go:171","msg":"trace[1012821486] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1844; }","duration":"110.686856ms","start":"2026-07-16T06:55:48.818779Z","end":"2026-07-16T06:55:48.929466Z","steps":["trace[1012821486] 'range keys from in-memory index tree' (duration: 110.317395ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:55:50.156516Z","caller":"traceutil/trace.go:171","msg":"trace[1832797795] transaction","detail":"{read_only:false; response_revision:1852; number_of_response:1; }","duration":"124.25305ms","start":"2026-07-16T06:55:50.03223Z","end":"2026-07-16T06:55:50.156483Z","steps":["trace[1832797795] 'process raft request' (duration: 48.958413ms)","trace[1832797795] 'compare' (duration: 75.125092ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:55:51.344188Z","caller":"traceutil/trace.go:171","msg":"trace[902481100] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"101.6437ms","start":"2026-07-16T06:55:51.242499Z","end":"2026-07-16T06:55:51.344143Z","steps":["trace[902481100] 'process raft request' (duration: 25.240823ms)","trace[902481100] 'compare' (duration: 76.228382ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:55:51.636821Z","caller":"traceutil/trace.go:171","msg":"trace[2089079523] transaction","detail":"{read_only:false; response_revision:1858; number_of_response:1; }","duration":"160.005738ms","start":"2026-07-16T06:55:51.476791Z","end":"2026-07-16T06:55:51.636797Z","steps":["trace[2089079523] 'process raft request' (duration: 159.876265ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:55:59.673568Z","caller":"traceutil/trace.go:171","msg":"trace[260505137] transaction","detail":"{read_only:false; response_revision:1892; number_of_response:1; }","duration":"241.48772ms","start":"2026-07-16T06:55:59.432059Z","end":"2026-07-16T06:55:59.673547Z","steps":["trace[260505137] 'process raft request' (duration: 241.355106ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:56:00.053824Z","caller":"traceutil/trace.go:171","msg":"trace[1108889951] transaction","detail":"{read_only:false; response_revision:1894; number_of_response:1; }","duration":"165.296988ms","start":"2026-07-16T06:55:59.888485Z","end":"2026-07-16T06:56:00.053782Z","steps":["trace[1108889951] 'process raft request' (duration: 95.655561ms)","trace[1108889951] 'compare' (duration: 69.492572ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:56:00.306605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.977164ms","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-07-16T06:56:00.306774Z","caller":"traceutil/trace.go:171","msg":"trace[555588988] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1895; }","duration":"128.18821ms","start":"2026-07-16T06:56:00.178561Z","end":"2026-07-16T06:56:00.30675Z","steps":["trace[555588988] 'range keys from in-memory index tree' (duration: 127.780548ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:56:00.589189Z","caller":"traceutil/trace.go:171","msg":"trace[1570953339] transaction","detail":"{read_only:false; response_revision:1898; number_of_response:1; }","duration":"107.108644ms","start":"2026-07-16T06:56:00.48205Z","end":"2026-07-16T06:56:00.589159Z","steps":["trace[1570953339] 'process raft request' (duration: 106.95669ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:56:01.309712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.102154ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T06:56:01.30982Z","caller":"traceutil/trace.go:171","msg":"trace[1439122119] linearizableReadLoop","detail":"{readStateIndex:1976; appliedIndex:1975; }","duration":"223.493452ms","start":"2026-07-16T06:56:01.086311Z","end":"2026-07-16T06:56:01.309804Z","steps":["trace[1439122119] 'read index received' (duration: 30.092289ms)","trace[1439122119] 'applied index is now lower than readState.Index' (duration: 193.399993ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:56:01.309936Z","caller":"traceutil/trace.go:171","msg":"trace[1599619548] transaction","detail":"{read_only:false; response_revision:1899; number_of_response:1; }","duration":"229.367289ms","start":"2026-07-16T06:56:01.080556Z","end":"2026-07-16T06:56:01.309923Z","steps":["trace[1599619548] 'process raft request' (duration: 35.967026ms)","trace[1599619548] 'compare' (duration: 192.980271ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:56:01.310325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.967624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:525"} {"level":"warn","ts":"2026-07-16T06:56:01.310379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.979522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"warn","ts":"2026-07-16T06:56:01.310445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.10287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-07-16T06:56:01.310472Z","caller":"traceutil/trace.go:171","msg":"trace[1986758498] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1899; }","duration":"107.105214ms","start":"2026-07-16T06:56:01.203348Z","end":"2026-07-16T06:56:01.310453Z","steps":["trace[1986758498] 'agreement among raft nodes before linearized reading' (duration: 106.793666ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:56:01.310531Z","caller":"traceutil/trace.go:171","msg":"trace[1086444091] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1899; }","duration":"224.235393ms","start":"2026-07-16T06:56:01.086277Z","end":"2026-07-16T06:56:01.310512Z","steps":["trace[1086444091] 'agreement among raft nodes before linearized reading' (duration: 223.862853ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:56:01.310385Z","caller":"traceutil/trace.go:171","msg":"trace[2145069490] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1899; }","duration":"168.065467ms","start":"2026-07-16T06:56:01.142304Z","end":"2026-07-16T06:56:01.31037Z","steps":["trace[2145069490] 'agreement among raft nodes before linearized reading' (duration: 167.80713ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:56:02.394342Z","caller":"traceutil/trace.go:171","msg":"trace[789559291] linearizableReadLoop","detail":"{readStateIndex:1982; appliedIndex:1981; }","duration":"165.632597ms","start":"2026-07-16T06:56:02.228683Z","end":"2026-07-16T06:56:02.394315Z","steps":["trace[789559291] 'read index received' (duration: 165.354989ms)","trace[789559291] 'applied index is now lower than readState.Index' (duration: 276.328µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:56:02.394434Z","caller":"traceutil/trace.go:171","msg":"trace[608937990] transaction","detail":"{read_only:false; response_revision:1905; number_of_response:1; }","duration":"276.291352ms","start":"2026-07-16T06:56:02.118101Z","end":"2026-07-16T06:56:02.394393Z","steps":["trace[608937990] 'process raft request' (duration: 276.014744ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:56:02.394661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.3692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:56:02.394781Z","caller":"traceutil/trace.go:171","msg":"trace[67273582] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1905; }","duration":"108.590337ms","start":"2026-07-16T06:56:02.286171Z","end":"2026-07-16T06:56:02.394762Z","steps":["trace[67273582] 'agreement among raft nodes before linearized reading' (duration: 108.397981ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:56:02.394801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.045318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-16T06:56:02.394931Z","caller":"traceutil/trace.go:171","msg":"trace[1289338268] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1905; }","duration":"166.316616ms","start":"2026-07-16T06:56:02.228597Z","end":"2026-07-16T06:56:02.394913Z","steps":["trace[1289338268] 'agreement among raft nodes before linearized reading' (duration: 165.882524ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:56:02.517635Z","caller":"traceutil/trace.go:171","msg":"trace[621030148] transaction","detail":"{read_only:false; response_revision:1907; number_of_response:1; }","duration":"118.178597ms","start":"2026-07-16T06:56:02.399429Z","end":"2026-07-16T06:56:02.517607Z","steps":["trace[621030148] 'process raft request' (duration: 117.992772ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:56:06.786464Z","caller":"traceutil/trace.go:171","msg":"trace[1532118713] transaction","detail":"{read_only:false; response_revision:1926; number_of_response:1; }","duration":"149.191933ms","start":"2026-07-16T06:56:06.637247Z","end":"2026-07-16T06:56:06.786439Z","steps":["trace[1532118713] 'process raft request' (duration: 148.944136ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:56:13.606866Z","caller":"traceutil/trace.go:171","msg":"trace[1248443812] transaction","detail":"{read_only:false; response_revision:1955; number_of_response:1; }","duration":"133.335545ms","start":"2026-07-16T06:56:13.473507Z","end":"2026-07-16T06:56:13.606842Z","steps":["trace[1248443812] 'process raft request' (duration: 133.180491ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:58:32.156573Z","caller":"traceutil/trace.go:171","msg":"trace[1563191827] transaction","detail":"{read_only:false; response_revision:2618; number_of_response:1; }","duration":"394.631359ms","start":"2026-07-16T06:58:31.761913Z","end":"2026-07-16T06:58:32.156545Z","steps":["trace[1563191827] 'process raft request' (duration: 333.76162ms)","trace[1563191827] 'compare' (duration: 60.710595ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:58:32.156724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:58:31.761902Z","time spent":"394.752963ms","remote":"127.0.0.1:33282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T06:58:32.156876Z","caller":"traceutil/trace.go:171","msg":"trace[1669608277] transaction","detail":"{read_only:false; response_revision:2619; number_of_response:1; }","duration":"394.806355ms","start":"2026-07-16T06:58:31.762044Z","end":"2026-07-16T06:58:32.15685Z","steps":["trace[1669608277] 'process raft request' (duration: 394.451825ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:58:32.156952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:58:31.762022Z","time spent":"394.898057ms","remote":"127.0.0.1:33282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":39,"request content":"compare: success:> failure: >"}