{"level":"warn","ts":"2026-07-03T19:00:15.351004Z","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-03T19:00:15.351134Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.169: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.169:2380","--initial-cluster=instance=https://199.204.45.169:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.169:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.169: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-03T19:00:15.351331Z","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-03T19:00:15.351348Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-07-03T19:00:15.351386Z","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-03T19:00:15.352612Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"]} {"level":"info","ts":"2026-07-03T19:00:15.352716Z","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.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.169: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-03T19:00:15.367093Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"14.071269ms"} {"level":"info","ts":"2026-07-03T19:00:15.386461Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1c917701ce165bac","cluster-id":"bfb5247022b87cb1"} {"level":"info","ts":"2026-07-03T19:00:15.386565Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=()"} {"level":"info","ts":"2026-07-03T19:00:15.386598Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 0"} {"level":"info","ts":"2026-07-03T19:00:15.38661Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1c917701ce165bac [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-03T19:00:15.386622Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 1"} {"level":"info","ts":"2026-07-03T19:00:15.386666Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"warn","ts":"2026-07-03T19:00:15.423883Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-03T19:00:15.428958Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-03T19:00:15.440617Z","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-03T19:00:15.446369Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1c917701ce165bac","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-03T19:00:15.446579Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1c917701ce165bac","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-03T19:00:15.451435Z","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-03T19:00:15.451536Z","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-03T19:00:15.451549Z","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-03T19:00:15.460989Z","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-03T19:00:15.461255Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1c917701ce165bac","initial-advertise-peer-urls":["https://199.204.45.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-03T19:00:15.461281Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-03T19:00:15.461361Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-07-03T19:00:15.461372Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-07-03T19:00:15.4765Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"info","ts":"2026-07-03T19:00:15.476672Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","added-peer-id":"1c917701ce165bac","added-peer-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-07-03T19:00:15.987646Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac is starting a new election at term 1"} {"level":"info","ts":"2026-07-03T19:00:15.987752Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became pre-candidate at term 1"} {"level":"info","ts":"2026-07-03T19:00:15.987786Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgPreVoteResp from 1c917701ce165bac at term 1"} {"level":"info","ts":"2026-07-03T19:00:15.98781Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became candidate at term 2"} {"level":"info","ts":"2026-07-03T19:00:15.987827Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgVoteResp from 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-07-03T19:00:15.98785Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became leader at term 2"} {"level":"info","ts":"2026-07-03T19:00:15.987869Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1c917701ce165bac elected leader 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-07-03T19:00:15.997092Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-03T19:00:15.999106Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-03T19:00:15.999201Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-03T19:00:16.000281Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-03T19:00:16.000406Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-03T19:00:16.001672Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.169:2379"} {"level":"info","ts":"2026-07-03T19:00:16.002318Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-03T19:00:16.003375Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1c917701ce165bac","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.169:2379]}","request-path":"/0/members/1c917701ce165bac/attributes","cluster-id":"bfb5247022b87cb1","publish-timeout":"7s"} {"level":"info","ts":"2026-07-03T19:00:16.01065Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","cluster-version":"3.5"} {"level":"info","ts":"2026-07-03T19:00:16.010851Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-03T19:00:16.010905Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-03T19:00:24.03257Z","caller":"traceutil/trace.go:171","msg":"trace[789750268] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"101.363631ms","start":"2026-07-03T19:00:23.931176Z","end":"2026-07-03T19:00:24.032539Z","steps":["trace[789750268] 'process raft request' (duration: 101.173543ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:24.39158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.279175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T19:00:24.391684Z","caller":"traceutil/trace.go:171","msg":"trace[99571135] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:0; response_revision:330; }","duration":"261.470964ms","start":"2026-07-03T19:00:24.130191Z","end":"2026-07-03T19:00:24.391662Z","steps":["trace[99571135] 'range keys from in-memory index tree' (duration: 261.126209ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:00:24.637974Z","caller":"traceutil/trace.go:171","msg":"trace[598420559] linearizableReadLoop","detail":"{readStateIndex:344; appliedIndex:342; }","duration":"164.706129ms","start":"2026-07-03T19:00:24.473242Z","end":"2026-07-03T19:00:24.637948Z","steps":["trace[598420559] 'read index received' (duration: 92.655919ms)","trace[598420559] 'applied index is now lower than readState.Index' (duration: 72.04893ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:00:24.638139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.856505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-07-03T19:00:24.638134Z","caller":"traceutil/trace.go:171","msg":"trace[722785567] transaction","detail":"{read_only:false; response_revision:332; number_of_response:1; }","duration":"167.506779ms","start":"2026-07-03T19:00:24.470571Z","end":"2026-07-03T19:00:24.638078Z","steps":["trace[722785567] 'process raft request' (duration: 95.346955ms)","trace[722785567] 'compare' (duration: 71.805869ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:00:24.638168Z","caller":"traceutil/trace.go:171","msg":"trace[204597572] transaction","detail":"{read_only:false; response_revision:333; number_of_response:1; }","duration":"167.418905ms","start":"2026-07-03T19:00:24.470679Z","end":"2026-07-03T19:00:24.638098Z","steps":["trace[204597572] 'process raft request' (duration: 167.210816ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:00:24.638186Z","caller":"traceutil/trace.go:171","msg":"trace[795782159] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:333; }","duration":"164.971019ms","start":"2026-07-03T19:00:24.473201Z","end":"2026-07-03T19:00:24.638172Z","steps":["trace[795782159] 'agreement among raft nodes before linearized reading' (duration: 164.850634ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:26.073558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.477334ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-03T19:00:26.073774Z","caller":"traceutil/trace.go:171","msg":"trace[1087000929] transaction","detail":"{read_only:false; response_revision:339; number_of_response:1; }","duration":"727.038479ms","start":"2026-07-03T19:00:25.346719Z","end":"2026-07-03T19:00:26.073757Z","steps":["trace[1087000929] 'process raft request' (duration: 454.591652ms)","trace[1087000929] 'compare' (duration: 271.253755ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:00:26.073965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:25.346695Z","time spent":"727.223727ms","remote":"127.0.0.1:33716","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-03T19:00:26.073718Z","caller":"traceutil/trace.go:171","msg":"trace[811167903] linearizableReadLoop","detail":"{readStateIndex:350; appliedIndex:349; }","duration":"693.823093ms","start":"2026-07-03T19:00:25.379876Z","end":"2026-07-03T19:00:26.073699Z","steps":["trace[811167903] 'read index received' (duration: 421.417819ms)","trace[811167903] 'applied index is now lower than readState.Index' (duration: 272.403814ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:00:26.074412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"694.515974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/attachdetach-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T19:00:26.074532Z","caller":"traceutil/trace.go:171","msg":"trace[1676246131] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/attachdetach-controller; range_end:; response_count:0; response_revision:339; }","duration":"694.65915ms","start":"2026-07-03T19:00:25.37982Z","end":"2026-07-03T19:00:26.074479Z","steps":["trace[1676246131] 'agreement among raft nodes before linearized reading' (duration: 694.19884ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:26.074436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.866346ms","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-03T19:00:26.074674Z","caller":"traceutil/trace.go:171","msg":"trace[2061828087] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:339; }","duration":"635.122547ms","start":"2026-07-03T19:00:25.439529Z","end":"2026-07-03T19:00:26.074651Z","steps":["trace[2061828087] 'agreement among raft nodes before linearized reading' (duration: 634.851475ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:26.074662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:25.379796Z","time spent":"694.843068ms","remote":"127.0.0.1:33634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/attachdetach-controller\" "} {"level":"warn","ts":"2026-07-03T19:00:26.074758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:25.439511Z","time spent":"635.20312ms","remote":"127.0.0.1:33716","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":"warn","ts":"2026-07-03T19:00:27.884276Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.137009129s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-03T19:00:28.005349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.258071604s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-03T19:00:28.005549Z","caller":"traceutil/trace.go:171","msg":"trace[271027161] linearizableReadLoop","detail":"{readStateIndex:352; appliedIndex:350; }","duration":"1.443032387s","start":"2026-07-03T19:00:26.562498Z","end":"2026-07-03T19:00:28.00553Z","steps":["trace[271027161] 'read index received' (duration: 184.795716ms)","trace[271027161] 'applied index is now lower than readState.Index' (duration: 1.258235601s)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:00:28.005745Z","caller":"traceutil/trace.go:171","msg":"trace[412643703] transaction","detail":"{read_only:false; response_revision:340; number_of_response:1; }","duration":"1.926193941s","start":"2026-07-03T19:00:26.079541Z","end":"2026-07-03T19:00:28.005734Z","steps":["trace[412643703] 'process raft request' (duration: 667.647356ms)","trace[412643703] 'compare' (duration: 1.257846135s)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:00:28.005802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:26.07952Z","time spent":"1.926255492s","remote":"127.0.0.1:33716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T19:00:28.006024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.443503717s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T19:00:28.006098Z","caller":"traceutil/trace.go:171","msg":"trace[1711619506] transaction","detail":"{read_only:false; response_revision:341; number_of_response:1; }","duration":"1.924245387s","start":"2026-07-03T19:00:26.081799Z","end":"2026-07-03T19:00:28.006044Z","steps":["trace[1711619506] 'process raft request' (duration: 1.923655052s)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:00:28.006167Z","caller":"traceutil/trace.go:171","msg":"trace[471398454] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:341; }","duration":"1.443652533s","start":"2026-07-03T19:00:26.562487Z","end":"2026-07-03T19:00:28.00614Z","steps":["trace[471398454] 'agreement among raft nodes before linearized reading' (duration: 1.443436424s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:28.006302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:26.08179Z","time spent":"1.924403433s","remote":"127.0.0.1:33634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":197,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T19:00:28.029044Z","caller":"traceutil/trace.go:171","msg":"trace[1706169876] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"405.711915ms","start":"2026-07-03T19:00:27.623305Z","end":"2026-07-03T19:00:28.029017Z","steps":["trace[1706169876] 'process raft request' (duration: 405.581069ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:28.029242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:27.623276Z","time spent":"405.848111ms","remote":"127.0.0.1:33716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T19:00:28.02943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.526513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"warn","ts":"2026-07-03T19:00:28.029442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.386446521s","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-07-03T19:00:28.029491Z","caller":"traceutil/trace.go:171","msg":"trace[81475126] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:342; }","duration":"503.648018ms","start":"2026-07-03T19:00:27.525828Z","end":"2026-07-03T19:00:28.029476Z","steps":["trace[81475126] 'agreement among raft nodes before linearized reading' (duration: 503.508222ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:00:28.029498Z","caller":"traceutil/trace.go:171","msg":"trace[2086069371] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:342; }","duration":"1.386557806s","start":"2026-07-03T19:00:26.642926Z","end":"2026-07-03T19:00:28.029484Z","steps":["trace[2086069371] 'agreement among raft nodes before linearized reading' (duration: 1.386404869s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:28.02953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:26.6429Z","time spent":"1.386624938s","remote":"127.0.0.1:33716","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-03T19:00:28.029531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:27.525785Z","time spent":"503.738852ms","remote":"127.0.0.1:33618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4349,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-07-03T19:00:28.029557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.385854855s","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-03T19:00:28.029653Z","caller":"traceutil/trace.go:171","msg":"trace[787651259] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:342; }","duration":"1.385945699s","start":"2026-07-03T19:00:26.643679Z","end":"2026-07-03T19:00:28.029624Z","steps":["trace[787651259] 'agreement among raft nodes before linearized reading' (duration: 1.385797902s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:28.029691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:26.643628Z","time spent":"1.386055144s","remote":"127.0.0.1:33716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-03T19:00:28.48948Z","caller":"traceutil/trace.go:171","msg":"trace[1873627414] linearizableReadLoop","detail":"{readStateIndex:367; appliedIndex:366; }","duration":"258.360344ms","start":"2026-07-03T19:00:28.231091Z","end":"2026-07-03T19:00:28.489451Z","steps":["trace[1873627414] 'read index received' (duration: 243.942369ms)","trace[1873627414] 'applied index is now lower than readState.Index' (duration: 14.415825ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:00:28.489633Z","caller":"traceutil/trace.go:171","msg":"trace[320090277] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"312.138497ms","start":"2026-07-03T19:00:28.177439Z","end":"2026-07-03T19:00:28.489577Z","steps":["trace[320090277] 'process raft request' (duration: 297.384337ms)","trace[320090277] 'compare' (duration: 14.484448ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:00:28.489899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:28.177316Z","time spent":"312.429859ms","remote":"127.0.0.1:33704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1251,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T19:00:28.490119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.000142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-07-03T19:00:28.490173Z","caller":"traceutil/trace.go:171","msg":"trace[2134091155] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:356; }","duration":"259.099956ms","start":"2026-07-03T19:00:28.231056Z","end":"2026-07-03T19:00:28.490156Z","steps":["trace[2134091155] 'agreement among raft nodes before linearized reading' (duration: 258.541442ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:00:28.547523Z","caller":"traceutil/trace.go:171","msg":"trace[749988848] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"315.22688ms","start":"2026-07-03T19:00:28.232243Z","end":"2026-07-03T19:00:28.54747Z","steps":["trace[749988848] 'process raft request' (duration: 314.969258ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:28.547675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.190639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:3706"} {"level":"warn","ts":"2026-07-03T19:00:28.547742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:28.232215Z","time spent":"315.438289ms","remote":"127.0.0.1:33554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1723,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T19:00:28.547768Z","caller":"traceutil/trace.go:171","msg":"trace[794568967] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:361; }","duration":"315.301173ms","start":"2026-07-03T19:00:28.232446Z","end":"2026-07-03T19:00:28.547747Z","steps":["trace[794568967] 'agreement among raft nodes before linearized reading' (duration: 315.126986ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:28.547824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:28.23241Z","time spent":"315.400918ms","remote":"127.0.0.1:33912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":3729,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"info","ts":"2026-07-03T19:00:28.547836Z","caller":"traceutil/trace.go:171","msg":"trace[769194781] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"314.157255ms","start":"2026-07-03T19:00:28.233645Z","end":"2026-07-03T19:00:28.547802Z","steps":["trace[769194781] 'process raft request' (duration: 313.778838ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:00:28.547906Z","caller":"traceutil/trace.go:171","msg":"trace[2088871854] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"313.104698ms","start":"2026-07-03T19:00:28.234775Z","end":"2026-07-03T19:00:28.547879Z","steps":["trace[2088871854] 'process raft request' (duration: 312.726802ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:28.547924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.80385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-07-03T19:00:28.547919Z","caller":"traceutil/trace.go:171","msg":"trace[1256510562] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"313.84763ms","start":"2026-07-03T19:00:28.234039Z","end":"2026-07-03T19:00:28.547886Z","steps":["trace[1256510562] 'process raft request' (duration: 313.424692ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:00:28.547998Z","caller":"traceutil/trace.go:171","msg":"trace[2047451580] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:361; }","duration":"302.919425ms","start":"2026-07-03T19:00:28.245063Z","end":"2026-07-03T19:00:28.547982Z","steps":["trace[2047451580] 'agreement among raft nodes before linearized reading' (duration: 302.724337ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:28.548054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:28.245052Z","time spent":"302.992529ms","remote":"127.0.0.1:33634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":237,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-07-03T19:00:28.548055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:28.234747Z","time spent":"313.226003ms","remote":"127.0.0.1:33610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4355,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T19:00:28.548132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.536093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"warn","ts":"2026-07-03T19:00:28.548102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:28.234032Z","time spent":"313.965815ms","remote":"127.0.0.1:33960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2150,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T19:00:28.547898Z","caller":"traceutil/trace.go:171","msg":"trace[1696050427] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"314.538381ms","start":"2026-07-03T19:00:28.23332Z","end":"2026-07-03T19:00:28.547859Z","steps":["trace[1696050427] 'process raft request' (duration: 314.040719ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:00:28.54818Z","caller":"traceutil/trace.go:171","msg":"trace[1597908463] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:361; }","duration":"312.604176ms","start":"2026-07-03T19:00:28.235565Z","end":"2026-07-03T19:00:28.548169Z","steps":["trace[1597908463] 'agreement among raft nodes before linearized reading' (duration: 312.514033ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:28.548224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:28.235558Z","time spent":"312.658038ms","remote":"127.0.0.1:33634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":257,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"warn","ts":"2026-07-03T19:00:28.54823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:28.233312Z","time spent":"314.890196ms","remote":"127.0.0.1:33634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":156,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-03T19:00:28.547979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:28.233635Z","time spent":"314.278639ms","remote":"127.0.0.1:33608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":596,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T19:00:28.901132Z","caller":"traceutil/trace.go:171","msg":"trace[1431660184] linearizableReadLoop","detail":"{readStateIndex:374; appliedIndex:372; }","duration":"348.120677ms","start":"2026-07-03T19:00:28.552986Z","end":"2026-07-03T19:00:28.901107Z","steps":["trace[1431660184] 'read index received' (duration: 322.184142ms)","trace[1431660184] 'applied index is now lower than readState.Index' (duration: 25.934985ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:00:28.901263Z","caller":"traceutil/trace.go:171","msg":"trace[1966219128] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"403.402933ms","start":"2026-07-03T19:00:28.497826Z","end":"2026-07-03T19:00:28.901229Z","steps":["trace[1966219128] 'process raft request' (duration: 377.573193ms)","trace[1966219128] 'compare' (duration: 25.453363ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:00:28.901407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.403039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-07-03T19:00:28.901439Z","caller":"traceutil/trace.go:171","msg":"trace[1601113815] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:363; }","duration":"348.478912ms","start":"2026-07-03T19:00:28.552951Z","end":"2026-07-03T19:00:28.90143Z","steps":["trace[1601113815] 'agreement among raft nodes before linearized reading' (duration: 348.369047ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:28.901465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:28.552934Z","time spent":"348.524644ms","remote":"127.0.0.1:33634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":257,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"warn","ts":"2026-07-03T19:00:28.901513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:28.497801Z","time spent":"403.635052ms","remote":"127.0.0.1:33704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2267,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T19:00:28.901268Z","caller":"traceutil/trace.go:171","msg":"trace[790350382] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"401.208267ms","start":"2026-07-03T19:00:28.500031Z","end":"2026-07-03T19:00:28.90124Z","steps":["trace[790350382] 'process raft request' (duration: 401.018189ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:28.901795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:28.500017Z","time spent":"401.631896ms","remote":"127.0.0.1:33618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3527,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-03T19:00:28.935381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.176132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-07-03T19:00:28.935434Z","caller":"traceutil/trace.go:171","msg":"trace[1919715188] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"382.430844ms","start":"2026-07-03T19:00:28.552972Z","end":"2026-07-03T19:00:28.935403Z","steps":["trace[1919715188] 'process raft request' (duration: 382.068329ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:00:28.935486Z","caller":"traceutil/trace.go:171","msg":"trace[1319312472] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:367; }","duration":"382.295478ms","start":"2026-07-03T19:00:28.553166Z","end":"2026-07-03T19:00:28.935461Z","steps":["trace[1319312472] 'agreement among raft nodes before linearized reading' (duration: 382.136671ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:00:28.935497Z","caller":"traceutil/trace.go:171","msg":"trace[382092560] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"381.154169ms","start":"2026-07-03T19:00:28.55431Z","end":"2026-07-03T19:00:28.935464Z","steps":["trace[382092560] 'process raft request' (duration: 380.886328ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:28.935539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:28.553156Z","time spent":"382.370751ms","remote":"127.0.0.1:33634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":237,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-07-03T19:00:28.935621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:28.554291Z","time spent":"381.267913ms","remote":"127.0.0.1:33618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3378,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-03T19:00:28.935664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:28.552946Z","time spent":"382.549199ms","remote":"127.0.0.1:33554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1739,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T19:00:28.935791Z","caller":"traceutil/trace.go:171","msg":"trace[248363717] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"379.578309ms","start":"2026-07-03T19:00:28.556081Z","end":"2026-07-03T19:00:28.935659Z","steps":["trace[248363717] 'process raft request' (duration: 379.158971ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:00:28.936097Z","caller":"traceutil/trace.go:171","msg":"trace[1925727616] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"382.967367ms","start":"2026-07-03T19:00:28.553116Z","end":"2026-07-03T19:00:28.936083Z","steps":["trace[1925727616] 'process raft request' (duration: 382.031546ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:28.936276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:28.553109Z","time spent":"383.068691ms","remote":"127.0.0.1:33634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":172,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-03T19:00:28.937041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:28.556074Z","time spent":"379.970047ms","remote":"127.0.0.1:33912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4002,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T19:00:29.043897Z","caller":"traceutil/trace.go:171","msg":"trace[295872148] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"137.999097ms","start":"2026-07-03T19:00:28.905865Z","end":"2026-07-03T19:00:29.043864Z","steps":["trace[295872148] 'process raft request' (duration: 129.738278ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:00:29.04412Z","caller":"traceutil/trace.go:171","msg":"trace[1771671636] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"137.438462ms","start":"2026-07-03T19:00:28.90666Z","end":"2026-07-03T19:00:29.044098Z","steps":["trace[1771671636] 'process raft request' (duration: 137.128009ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:00:29.044266Z","caller":"traceutil/trace.go:171","msg":"trace[1680983066] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"134.375909ms","start":"2026-07-03T19:00:28.909878Z","end":"2026-07-03T19:00:29.044254Z","steps":["trace[1680983066] 'process raft request' (duration: 133.967022ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:00:29.044415Z","caller":"traceutil/trace.go:171","msg":"trace[730630165] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"133.00278ms","start":"2026-07-03T19:00:28.911394Z","end":"2026-07-03T19:00:29.044397Z","steps":["trace[730630165] 'process raft request' (duration: 132.76021ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:00:29.04444Z","caller":"traceutil/trace.go:171","msg":"trace[1171922215] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"133.093295ms","start":"2026-07-03T19:00:28.911311Z","end":"2026-07-03T19:00:29.044404Z","steps":["trace[1171922215] 'process raft request' (duration: 132.599833ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:00:29.044716Z","caller":"traceutil/trace.go:171","msg":"trace[546593858] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"130.079402ms","start":"2026-07-03T19:00:28.914623Z","end":"2026-07-03T19:00:29.044702Z","steps":["trace[546593858] 'process raft request' (duration: 129.972268ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:00:29.044723Z","caller":"traceutil/trace.go:171","msg":"trace[297636277] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"132.54916ms","start":"2026-07-03T19:00:28.912154Z","end":"2026-07-03T19:00:29.044703Z","steps":["trace[297636277] 'process raft request' (duration: 132.200885ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:00:29.096217Z","caller":"traceutil/trace.go:171","msg":"trace[1449847717] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"152.981268ms","start":"2026-07-03T19:00:28.943219Z","end":"2026-07-03T19:00:29.0962Z","steps":["trace[1449847717] 'process raft request' (duration: 152.949357ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:00:29.09619Z","caller":"traceutil/trace.go:171","msg":"trace[1683367464] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"155.408443ms","start":"2026-07-03T19:00:28.940742Z","end":"2026-07-03T19:00:29.096151Z","steps":["trace[1683367464] 'process raft request' (duration: 155.299729ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:00:29.096364Z","caller":"traceutil/trace.go:171","msg":"trace[565981245] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"154.150699ms","start":"2026-07-03T19:00:28.942086Z","end":"2026-07-03T19:00:29.096237Z","steps":["trace[565981245] 'process raft request' (duration: 154.038634ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:00:29.096428Z","caller":"traceutil/trace.go:171","msg":"trace[1153611585] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"155.684295ms","start":"2026-07-03T19:00:28.940734Z","end":"2026-07-03T19:00:29.096418Z","steps":["trace[1153611585] 'process raft request' (duration: 155.34459ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:00:29.096626Z","caller":"traceutil/trace.go:171","msg":"trace[1960574941] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"156.204019ms","start":"2026-07-03T19:00:28.940404Z","end":"2026-07-03T19:00:29.096608Z","steps":["trace[1960574941] 'process raft request' (duration: 155.506758ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:00:29.218931Z","caller":"traceutil/trace.go:171","msg":"trace[950502860] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"118.627219ms","start":"2026-07-03T19:00:29.100266Z","end":"2026-07-03T19:00:29.218893Z","steps":["trace[950502860] 'process raft request' (duration: 105.607465ms)","trace[950502860] 'compare' (duration: 12.705371ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:00:29.219287Z","caller":"traceutil/trace.go:171","msg":"trace[830547987] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"118.825418ms","start":"2026-07-03T19:00:29.100434Z","end":"2026-07-03T19:00:29.219259Z","steps":["trace[830547987] 'process raft request' (duration: 118.369628ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:29.219362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.294912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/kube-proxy\" ","response":"range_response_count:1 size:2901"} {"level":"info","ts":"2026-07-03T19:00:29.2195Z","caller":"traceutil/trace.go:171","msg":"trace[2132178814] range","detail":"{range_begin:/registry/daemonsets/kube-system/kube-proxy; range_end:; response_count:1; response_revision:388; }","duration":"117.469279ms","start":"2026-07-03T19:00:29.102003Z","end":"2026-07-03T19:00:29.219473Z","steps":["trace[2132178814] 'agreement among raft nodes before linearized reading' (duration: 117.190347ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:00:29.295062Z","caller":"traceutil/trace.go:171","msg":"trace[13931642] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"152.788222ms","start":"2026-07-03T19:00:29.142211Z","end":"2026-07-03T19:00:29.294999Z","steps":["trace[13931642] 'process raft request' (duration: 152.403306ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:00:29.295353Z","caller":"traceutil/trace.go:171","msg":"trace[2063105131] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"140.007418ms","start":"2026-07-03T19:00:29.155317Z","end":"2026-07-03T19:00:29.295324Z","steps":["trace[2063105131] 'process raft request' (duration: 139.666563ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:29.295669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.302382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-z99kn\" ","response":"range_response_count:1 size:3439"} {"level":"warn","ts":"2026-07-03T19:00:29.295667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.193026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:1 size:3475"} {"level":"info","ts":"2026-07-03T19:00:29.295767Z","caller":"traceutil/trace.go:171","msg":"trace[1655846759] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-z99kn; range_end:; response_count:1; response_revision:390; }","duration":"152.442678ms","start":"2026-07-03T19:00:29.143302Z","end":"2026-07-03T19:00:29.295745Z","steps":["trace[1655846759] 'agreement among raft nodes before linearized reading' (duration: 152.24589ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:00:29.295775Z","caller":"traceutil/trace.go:171","msg":"trace[336930298] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:1; response_revision:390; }","duration":"140.340333ms","start":"2026-07-03T19:00:29.155416Z","end":"2026-07-03T19:00:29.295757Z","steps":["trace[336930298] 'agreement among raft nodes before linearized reading' (duration: 140.135104ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:29.29583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.64853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-7c96b6546b\" ","response":"range_response_count:1 size:3774"} {"level":"info","ts":"2026-07-03T19:00:29.296054Z","caller":"traceutil/trace.go:171","msg":"trace[2064017552] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-7c96b6546b; range_end:; response_count:1; response_revision:390; }","duration":"144.88284ms","start":"2026-07-03T19:00:29.151127Z","end":"2026-07-03T19:00:29.29601Z","steps":["trace[2064017552] 'agreement among raft nodes before linearized reading' (duration: 144.596827ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:00:33.423308Z","caller":"traceutil/trace.go:171","msg":"trace[531785621] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"227.622342ms","start":"2026-07-03T19:00:33.195653Z","end":"2026-07-03T19:00:33.423275Z","steps":["trace[531785621] 'process raft request' (duration: 227.418784ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:00:34.469872Z","caller":"traceutil/trace.go:171","msg":"trace[719072637] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"130.606899ms","start":"2026-07-03T19:00:34.339223Z","end":"2026-07-03T19:00:34.46983Z","steps":["trace[719072637] 'process raft request' (duration: 130.374848ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:00:42.772679Z","caller":"traceutil/trace.go:171","msg":"trace[1988265098] transaction","detail":"{read_only:false; response_revision:437; number_of_response:1; }","duration":"201.945094ms","start":"2026-07-03T19:00:42.570701Z","end":"2026-07-03T19:00:42.772646Z","steps":["trace[1988265098] 'process raft request' (duration: 190.156494ms)","trace[1988265098] 'compare' (duration: 11.169593ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:00:46.767465Z","caller":"traceutil/trace.go:171","msg":"trace[262807770] linearizableReadLoop","detail":"{readStateIndex:458; appliedIndex:457; }","duration":"309.04228ms","start":"2026-07-03T19:00:46.458392Z","end":"2026-07-03T19:00:46.767434Z","steps":["trace[262807770] 'read index received' (duration: 308.791021ms)","trace[262807770] 'applied index is now lower than readState.Index' (duration: 249.799µs)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:00:46.767602Z","caller":"traceutil/trace.go:171","msg":"trace[175137627] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"507.379996ms","start":"2026-07-03T19:00:46.260172Z","end":"2026-07-03T19:00:46.767552Z","steps":["trace[175137627] 'process raft request' (duration: 507.087506ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:46.767842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.364831ms","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-03T19:00:46.768034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:46.260125Z","time spent":"507.740175ms","remote":"127.0.0.1:33716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T19:00:46.76855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.168336ms","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-03T19:00:46.768658Z","caller":"traceutil/trace.go:171","msg":"trace[554869492] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:442; }","duration":"248.300976ms","start":"2026-07-03T19:00:46.520339Z","end":"2026-07-03T19:00:46.76864Z","steps":["trace[554869492] 'agreement among raft nodes before linearized reading' (duration: 248.130107ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:00:46.768032Z","caller":"traceutil/trace.go:171","msg":"trace[1620934698] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:442; }","duration":"309.622439ms","start":"2026-07-03T19:00:46.458345Z","end":"2026-07-03T19:00:46.767967Z","steps":["trace[1620934698] 'agreement among raft nodes before linearized reading' (duration: 309.20768ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:46.768851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:46.458327Z","time spent":"310.491027ms","remote":"127.0.0.1:33716","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-03T19:00:50.670733Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605829753404104186,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-03T19:00:51.17148Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605829753404104186,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-03T19:00:51.359391Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.48612634s","expected-duration":"1s"} {"level":"info","ts":"2026-07-03T19:00:51.359809Z","caller":"traceutil/trace.go:171","msg":"trace[2142364922] linearizableReadLoop","detail":"{readStateIndex:470; appliedIndex:469; }","duration":"1.189335352s","start":"2026-07-03T19:00:50.170453Z","end":"2026-07-03T19:00:51.359788Z","steps":["trace[2142364922] 'read index received' (duration: 1.189115712s)","trace[2142364922] 'applied index is now lower than readState.Index' (duration: 218.73µs)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:00:51.359937Z","caller":"traceutil/trace.go:171","msg":"trace[1869651766] transaction","detail":"{read_only:false; response_revision:453; number_of_response:1; }","duration":"1.48680317s","start":"2026-07-03T19:00:49.873123Z","end":"2026-07-03T19:00:51.359926Z","steps":["trace[1869651766] 'process raft request' (duration: 1.48649788s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:51.36005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:49.873092Z","time spent":"1.486874459s","remote":"127.0.0.1:33912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T19:00:51.360388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.974634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-03T19:00:51.360415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.591162ms","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-03T19:00:51.36048Z","caller":"traceutil/trace.go:171","msg":"trace[1096282259] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:453; }","duration":"530.132894ms","start":"2026-07-03T19:00:50.830326Z","end":"2026-07-03T19:00:51.360459Z","steps":["trace[1096282259] 'agreement among raft nodes before linearized reading' (duration: 529.936454ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:51.360523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:50.830308Z","time spent":"530.207134ms","remote":"127.0.0.1:33716","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":"warn","ts":"2026-07-03T19:00:51.36042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.084302ms","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-07-03T19:00:51.360593Z","caller":"traceutil/trace.go:171","msg":"trace[1354636410] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:453; }","duration":"516.312353ms","start":"2026-07-03T19:00:50.844269Z","end":"2026-07-03T19:00:51.360581Z","steps":["trace[1354636410] 'agreement among raft nodes before linearized reading' (duration: 516.111632ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:51.36063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:50.844237Z","time spent":"516.385413ms","remote":"127.0.0.1:33716","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":"info","ts":"2026-07-03T19:00:51.360758Z","caller":"traceutil/trace.go:171","msg":"trace[1605849367] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:453; }","duration":"515.866103ms","start":"2026-07-03T19:00:50.84478Z","end":"2026-07-03T19:00:51.360646Z","steps":["trace[1605849367] 'agreement among raft nodes before linearized reading' (duration: 515.558722ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:51.360779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.190297483s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T19:00:51.360934Z","caller":"traceutil/trace.go:171","msg":"trace[95537957] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:453; }","duration":"1.190521773s","start":"2026-07-03T19:00:50.170394Z","end":"2026-07-03T19:00:51.360916Z","steps":["trace[95537957] 'agreement among raft nodes before linearized reading' (duration: 1.189911242s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:51.361025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:50.170371Z","time spent":"1.190630983s","remote":"127.0.0.1:33460","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-03T19:00:51.361085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.653197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-03T19:00:51.360818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:50.844773Z","time spent":"516.034383ms","remote":"127.0.0.1:33716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-03T19:00:51.361127Z","caller":"traceutil/trace.go:171","msg":"trace[1589558884] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:453; }","duration":"471.728777ms","start":"2026-07-03T19:00:50.889387Z","end":"2026-07-03T19:00:51.361115Z","steps":["trace[1589558884] 'agreement among raft nodes before linearized reading' (duration: 470.881136ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:51.361158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:50.889367Z","time spent":"471.785937ms","remote":"127.0.0.1:33716","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-03T19:00:52.685778Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.219168236s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-03T19:00:52.706255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.239615523s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-03T19:00:52.706519Z","caller":"traceutil/trace.go:171","msg":"trace[107153856] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"1.32820023s","start":"2026-07-03T19:00:51.378271Z","end":"2026-07-03T19:00:52.706471Z","steps":["trace[107153856] 'process raft request' (duration: 88.219386ms)","trace[107153856] 'compare' (duration: 1.239205852s)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:00:52.706737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:51.378245Z","time spent":"1.32837068s","remote":"127.0.0.1:33716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T19:00:52.706736Z","caller":"traceutil/trace.go:171","msg":"trace[1479368284] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"926.629192ms","start":"2026-07-03T19:00:51.780083Z","end":"2026-07-03T19:00:52.706713Z","steps":["trace[1479368284] 'process raft request' (duration: 926.582382ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:52.706877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:51.780049Z","time spent":"926.752182ms","remote":"127.0.0.1:33716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T19:00:52.706923Z","caller":"traceutil/trace.go:171","msg":"trace[1012650392] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"1.328086019s","start":"2026-07-03T19:00:51.378825Z","end":"2026-07-03T19:00:52.706911Z","steps":["trace[1012650392] 'process raft request' (duration: 1.327752298s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:52.70698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:51.378816Z","time spent":"1.328133269s","remote":"127.0.0.1:33716","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-03T19:00:52.70704Z","caller":"traceutil/trace.go:171","msg":"trace[1392892933] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"1.325825448s","start":"2026-07-03T19:00:51.381202Z","end":"2026-07-03T19:00:52.707027Z","steps":["trace[1392892933] 'process raft request' (duration: 1.325420977s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:52.707091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:51.381176Z","time spent":"1.325888529s","remote":"127.0.0.1:33716","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-03T19:00:52.707168Z","caller":"traceutil/trace.go:171","msg":"trace[441465450] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"1.32860815s","start":"2026-07-03T19:00:51.378549Z","end":"2026-07-03T19:00:52.707157Z","steps":["trace[441465450] 'process raft request' (duration: 1.327814699s)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:00:52.707182Z","caller":"traceutil/trace.go:171","msg":"trace[1058784979] linearizableReadLoop","detail":"{readStateIndex:474; appliedIndex:470; }","duration":"1.325554787s","start":"2026-07-03T19:00:51.381616Z","end":"2026-07-03T19:00:52.70717Z","steps":["trace[1058784979] 'read index received' (duration: 84.950342ms)","trace[1058784979] 'applied index is now lower than readState.Index' (duration: 1.240603045s)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:00:52.707234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:51.378524Z","time spent":"1.32867579s","remote":"127.0.0.1:33716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T19:00:52.707292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.325671627s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T19:00:52.707328Z","caller":"traceutil/trace.go:171","msg":"trace[1591006400] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:458; }","duration":"1.325727897s","start":"2026-07-03T19:00:51.381585Z","end":"2026-07-03T19:00:52.707313Z","steps":["trace[1591006400] 'agreement among raft nodes before linearized reading' (duration: 1.325667717s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:52.707359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:51.381573Z","time spent":"1.325778007s","remote":"127.0.0.1:33538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-07-03T19:00:52.707364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.322335815s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T19:00:52.707398Z","caller":"traceutil/trace.go:171","msg":"trace[1142576130] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:458; }","duration":"1.322394755s","start":"2026-07-03T19:00:51.384992Z","end":"2026-07-03T19:00:52.707387Z","steps":["trace[1142576130] 'agreement among raft nodes before linearized reading' (duration: 1.322328105s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:00:52.707433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:00:51.384973Z","time spent":"1.322454255s","remote":"127.0.0.1:33460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-03T19:00:52.956229Z","caller":"traceutil/trace.go:171","msg":"trace[536957374] linearizableReadLoop","detail":"{readStateIndex:484; appliedIndex:483; }","duration":"172.858551ms","start":"2026-07-03T19:00:52.783342Z","end":"2026-07-03T19:00:52.956201Z","steps":["trace[536957374] 'read index received' (duration: 110.318067ms)","trace[536957374] 'applied index is now lower than readState.Index' (duration: 62.539354ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:00:52.956427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.061052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-67659f764b\" ","response":"range_response_count:1 size:3810"} {"level":"info","ts":"2026-07-03T19:00:52.956473Z","caller":"traceutil/trace.go:171","msg":"trace[705442444] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-67659f764b; range_end:; response_count:1; response_revision:467; }","duration":"173.168102ms","start":"2026-07-03T19:00:52.783295Z","end":"2026-07-03T19:00:52.956463Z","steps":["trace[705442444] 'agreement among raft nodes before linearized reading' (duration: 173.012402ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:00:52.956618Z","caller":"traceutil/trace.go:171","msg":"trace[1715500349] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"199.256906ms","start":"2026-07-03T19:00:52.757351Z","end":"2026-07-03T19:00:52.956608Z","steps":["trace[1715500349] 'process raft request' (duration: 136.336591ms)","trace[1715500349] 'compare' (duration: 62.405424ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:01:04.945101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.308048ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-03T19:01:04.945228Z","caller":"traceutil/trace.go:171","msg":"trace[1076051293] linearizableReadLoop","detail":"{readStateIndex:564; appliedIndex:563; }","duration":"331.543551ms","start":"2026-07-03T19:01:04.613663Z","end":"2026-07-03T19:01:04.945206Z","steps":["trace[1076051293] 'read index received' (duration: 73.131482ms)","trace[1076051293] 'applied index is now lower than readState.Index' (duration: 258.410159ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:01:04.945516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.840623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-03T19:01:04.945512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.850618ms","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-07-03T19:01:04.945601Z","caller":"traceutil/trace.go:171","msg":"trace[1499988280] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:544; }","duration":"331.977634ms","start":"2026-07-03T19:01:04.613601Z","end":"2026-07-03T19:01:04.945578Z","steps":["trace[1499988280] 'agreement among raft nodes before linearized reading' (duration: 331.854093ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:01:04.945613Z","caller":"traceutil/trace.go:171","msg":"trace[1913890515] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:544; }","duration":"132.965159ms","start":"2026-07-03T19:01:04.812624Z","end":"2026-07-03T19:01:04.945589Z","steps":["trace[1913890515] 'agreement among raft nodes before linearized reading' (duration: 132.760668ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:01:04.945634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:01:04.613579Z","time spent":"332.049004ms","remote":"127.0.0.1:33550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"warn","ts":"2026-07-03T19:01:04.945887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.354422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-03T19:01:04.945894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.349169ms","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-03T19:01:04.945926Z","caller":"traceutil/trace.go:171","msg":"trace[1053855632] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:544; }","duration":"171.889464ms","start":"2026-07-03T19:01:04.774025Z","end":"2026-07-03T19:01:04.945915Z","steps":["trace[1053855632] 'agreement among raft nodes before linearized reading' (duration: 171.314479ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:01:04.945922Z","caller":"traceutil/trace.go:171","msg":"trace[1683400378] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:544; }","duration":"133.421053ms","start":"2026-07-03T19:01:04.812489Z","end":"2026-07-03T19:01:04.94591Z","steps":["trace[1683400378] 'agreement among raft nodes before linearized reading' (duration: 133.359022ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:01:04.94593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.344412ms","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-03T19:01:04.94598Z","caller":"traceutil/trace.go:171","msg":"trace[713221909] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:544; }","duration":"133.416373ms","start":"2026-07-03T19:01:04.812548Z","end":"2026-07-03T19:01:04.945964Z","steps":["trace[713221909] 'agreement among raft nodes before linearized reading' (duration: 133.285382ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:01:05.677767Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605829753404104370,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-03T19:01:06.178762Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605829753404104370,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-03T19:01:06.560956Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.416622971s","expected-duration":"1s"} {"level":"info","ts":"2026-07-03T19:01:06.561432Z","caller":"traceutil/trace.go:171","msg":"trace[2060366908] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"1.417095785s","start":"2026-07-03T19:01:05.144159Z","end":"2026-07-03T19:01:06.561255Z","steps":["trace[2060366908] 'process raft request' (duration: 1.416985954s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:01:06.561554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:01:05.144138Z","time spent":"1.417355607s","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T19:01:06.579804Z","caller":"traceutil/trace.go:171","msg":"trace[893439953] linearizableReadLoop","detail":"{readStateIndex:580; appliedIndex:569; }","duration":"1.407018761s","start":"2026-07-03T19:01:05.172766Z","end":"2026-07-03T19:01:06.579785Z","steps":["trace[893439953] 'read index received' (duration: 1.388505098s)","trace[893439953] 'applied index is now lower than readState.Index' (duration: 18.512553ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:01:06.579969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.407182573s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T19:01:06.580001Z","caller":"traceutil/trace.go:171","msg":"trace[509804504] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:560; }","duration":"1.407248633s","start":"2026-07-03T19:01:05.172744Z","end":"2026-07-03T19:01:06.579992Z","steps":["trace[509804504] 'agreement among raft nodes before linearized reading' (duration: 1.407123132s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:01:06.580037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:01:05.172738Z","time spent":"1.407293353s","remote":"127.0.0.1:33460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-03T19:01:06.580154Z","caller":"traceutil/trace.go:171","msg":"trace[1768602609] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"1.407178822s","start":"2026-07-03T19:01:05.172918Z","end":"2026-07-03T19:01:06.580097Z","steps":["trace[1768602609] 'process raft request' (duration: 1.406369705s)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:01:06.580274Z","caller":"traceutil/trace.go:171","msg":"trace[1826401585] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"1.359770519s","start":"2026-07-03T19:01:05.220494Z","end":"2026-07-03T19:01:06.580265Z","steps":["trace[1826401585] 'process raft request' (duration: 1.359145454s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:01:06.580345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:01:05.220476Z","time spent":"1.35983151s","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T19:01:06.58034Z","caller":"traceutil/trace.go:171","msg":"trace[173944995] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"1.396637025s","start":"2026-07-03T19:01:05.183676Z","end":"2026-07-03T19:01:06.580313Z","steps":["trace[173944995] 'process raft request' (duration: 1.395728737s)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:01:06.580411Z","caller":"traceutil/trace.go:171","msg":"trace[52632310] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"1.396599164s","start":"2026-07-03T19:01:05.183774Z","end":"2026-07-03T19:01:06.580373Z","steps":["trace[52632310] 'process raft request' (duration: 1.395700257s)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:01:06.580389Z","caller":"traceutil/trace.go:171","msg":"trace[1044563854] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"1.274617952s","start":"2026-07-03T19:01:05.305714Z","end":"2026-07-03T19:01:06.580332Z","steps":["trace[1044563854] 'process raft request' (duration: 1.274014077s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:01:06.580441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:01:05.18365Z","time spent":"1.396741625s","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-03T19:01:06.580443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:01:05.172911Z","time spent":"1.407377183s","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T19:01:06.580469Z","caller":"traceutil/trace.go:171","msg":"trace[1378037181] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"1.435894811s","start":"2026-07-03T19:01:05.144531Z","end":"2026-07-03T19:01:06.580426Z","steps":["trace[1378037181] 'process raft request' (duration: 1.434491279s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:01:06.580583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:01:05.305695Z","time spent":"1.274786474s","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T19:01:06.580649Z","caller":"traceutil/trace.go:171","msg":"trace[424220577] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"1.409549462s","start":"2026-07-03T19:01:05.171088Z","end":"2026-07-03T19:01:06.580638Z","steps":["trace[424220577] 'process raft request' (duration: 1.408159841s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:01:06.580672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:01:05.144521Z","time spent":"1.436064452s","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-03T19:01:06.580716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:01:05.171076Z","time spent":"1.409609703s","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T19:01:06.580237Z","caller":"traceutil/trace.go:171","msg":"trace[834450685] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"1.405200966s","start":"2026-07-03T19:01:05.175025Z","end":"2026-07-03T19:01:06.580226Z","steps":["trace[834450685] 'process raft request' (duration: 1.404321008s)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:01:06.580224Z","caller":"traceutil/trace.go:171","msg":"trace[151537688] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"1.434000525s","start":"2026-07-03T19:01:05.146152Z","end":"2026-07-03T19:01:06.580153Z","steps":["trace[151537688] 'process raft request' (duration: 1.432945656s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:01:06.580794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:01:05.175019Z","time spent":"1.40574118s","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T19:01:06.580818Z","caller":"traceutil/trace.go:171","msg":"trace[1892822813] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"1.413437564s","start":"2026-07-03T19:01:05.167372Z","end":"2026-07-03T19:01:06.580809Z","steps":["trace[1892822813] 'process raft request' (duration: 1.41183274s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:01:06.580867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:01:05.167349Z","time spent":"1.413493014s","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-03T19:01:06.580937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:01:05.146118Z","time spent":"1.434748371s","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-03T19:01:06.580597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:01:05.183769Z","time spent":"1.396693395s","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T19:01:06.581158Z","caller":"traceutil/trace.go:171","msg":"trace[1522474165] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"1.385399192s","start":"2026-07-03T19:01:05.195374Z","end":"2026-07-03T19:01:06.580773Z","steps":["trace[1522474165] 'process raft request' (duration: 1.384203151s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:01:06.581364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:01:05.195351Z","time spent":"1.385952957s","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T19:01:06.801495Z","caller":"traceutil/trace.go:171","msg":"trace[2109096260] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"193.378257ms","start":"2026-07-03T19:01:06.608083Z","end":"2026-07-03T19:01:06.801461Z","steps":["trace[2109096260] 'process raft request' (duration: 108.562414ms)","trace[2109096260] 'compare' (duration: 84.497001ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:01:06.801903Z","caller":"traceutil/trace.go:171","msg":"trace[1968872102] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"190.29969ms","start":"2026-07-03T19:01:06.611589Z","end":"2026-07-03T19:01:06.801889Z","steps":["trace[1968872102] 'process raft request' (duration: 189.819496ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:01:06.801953Z","caller":"traceutil/trace.go:171","msg":"trace[873177449] linearizableReadLoop","detail":"{readStateIndex:592; appliedIndex:589; }","duration":"179.289193ms","start":"2026-07-03T19:01:06.622641Z","end":"2026-07-03T19:01:06.80193Z","steps":["trace[873177449] 'read index received' (duration: 5.413618ms)","trace[873177449] 'applied index is now lower than readState.Index' (duration: 173.872555ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:01:06.801928Z","caller":"traceutil/trace.go:171","msg":"trace[1288845774] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"188.761976ms","start":"2026-07-03T19:01:06.613118Z","end":"2026-07-03T19:01:06.80188Z","steps":["trace[1288845774] 'process raft request' (duration: 188.541124ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:01:06.802093Z","caller":"traceutil/trace.go:171","msg":"trace[2053730652] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"167.025615ms","start":"2026-07-03T19:01:06.635025Z","end":"2026-07-03T19:01:06.80205Z","steps":["trace[2053730652] 'process raft request' (duration: 166.805944ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:01:06.802153Z","caller":"traceutil/trace.go:171","msg":"trace[1764409244] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"150.314568ms","start":"2026-07-03T19:01:06.651819Z","end":"2026-07-03T19:01:06.802134Z","steps":["trace[1764409244] 'process raft request' (duration: 150.161237ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:01:06.802303Z","caller":"traceutil/trace.go:171","msg":"trace[2131686771] transaction","detail":"{read_only:false; number_of_response:0; response_revision:572; }","duration":"166.51055ms","start":"2026-07-03T19:01:06.635784Z","end":"2026-07-03T19:01:06.802295Z","steps":["trace[2131686771] 'process raft request' (duration: 166.152107ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:01:06.802317Z","caller":"traceutil/trace.go:171","msg":"trace[1684684898] transaction","detail":"{read_only:false; number_of_response:0; response_revision:571; }","duration":"168.66032ms","start":"2026-07-03T19:01:06.633644Z","end":"2026-07-03T19:01:06.802304Z","steps":["trace[1684684898] 'process raft request' (duration: 168.150995ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:01:06.802311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.642386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T19:01:06.802494Z","caller":"traceutil/trace.go:171","msg":"trace[125943732] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:573; }","duration":"179.753687ms","start":"2026-07-03T19:01:06.622598Z","end":"2026-07-03T19:01:06.802352Z","steps":["trace[125943732] 'agreement among raft nodes before linearized reading' (duration: 179.482875ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:01:06.802518Z","caller":"traceutil/trace.go:171","msg":"trace[1060136669] transaction","detail":"{read_only:false; number_of_response:0; response_revision:572; }","duration":"166.644042ms","start":"2026-07-03T19:01:06.635821Z","end":"2026-07-03T19:01:06.802465Z","steps":["trace[1060136669] 'process raft request' (duration: 166.137308ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:01:06.802914Z","caller":"traceutil/trace.go:171","msg":"trace[1870338303] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"144.60165ms","start":"2026-07-03T19:01:06.658294Z","end":"2026-07-03T19:01:06.802895Z","steps":["trace[1870338303] 'process raft request' (duration: 143.736001ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:01:06.802916Z","caller":"traceutil/trace.go:171","msg":"trace[1328024800] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"144.242416ms","start":"2026-07-03T19:01:06.658644Z","end":"2026-07-03T19:01:06.802886Z","steps":["trace[1328024800] 'process raft request' (duration: 144.001984ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:01:07.046262Z","caller":"traceutil/trace.go:171","msg":"trace[62670234] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"234.78484ms","start":"2026-07-03T19:01:06.811442Z","end":"2026-07-03T19:01:07.046227Z","steps":["trace[62670234] 'process raft request' (duration: 199.12229ms)","trace[62670234] 'compare' (duration: 35.509387ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:01:07.104108Z","caller":"traceutil/trace.go:171","msg":"trace[65865924] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"288.267966ms","start":"2026-07-03T19:01:06.815821Z","end":"2026-07-03T19:01:07.104089Z","steps":["trace[65865924] 'process raft request' (duration: 288.042994ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:01:07.104589Z","caller":"traceutil/trace.go:171","msg":"trace[1482500910] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"249.076302ms","start":"2026-07-03T19:01:06.855483Z","end":"2026-07-03T19:01:07.10456Z","steps":["trace[1482500910] 'process raft request' (duration: 248.647299ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:01:07.104533Z","caller":"traceutil/trace.go:171","msg":"trace[1514726921] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"285.384761ms","start":"2026-07-03T19:01:06.819096Z","end":"2026-07-03T19:01:07.10448Z","steps":["trace[1514726921] 'process raft request' (duration: 284.917817ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:01:07.104911Z","caller":"traceutil/trace.go:171","msg":"trace[904394785] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"233.600445ms","start":"2026-07-03T19:01:06.871298Z","end":"2026-07-03T19:01:07.104899Z","steps":["trace[904394785] 'process raft request' (duration: 233.199132ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:01:07.10492Z","caller":"traceutil/trace.go:171","msg":"trace[125529838] linearizableReadLoop","detail":"{readStateIndex:604; appliedIndex:599; }","duration":"136.535784ms","start":"2026-07-03T19:01:06.968317Z","end":"2026-07-03T19:01:07.104853Z","steps":["trace[125529838] 'read index received' (duration: 42.282883ms)","trace[125529838] 'applied index is now lower than readState.Index' (duration: 94.250591ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:01:07.10543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.06406ms","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-03T19:01:07.105503Z","caller":"traceutil/trace.go:171","msg":"trace[79130983] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:580; }","duration":"137.19173ms","start":"2026-07-03T19:01:06.968271Z","end":"2026-07-03T19:01:07.105463Z","steps":["trace[79130983] 'agreement among raft nodes before linearized reading' (duration: 136.776297ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:01:07.136819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.123737ms","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-07-03T19:01:07.137027Z","caller":"traceutil/trace.go:171","msg":"trace[1054862903] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:581; }","duration":"160.402499ms","start":"2026-07-03T19:01:06.976602Z","end":"2026-07-03T19:01:07.137005Z","steps":["trace[1054862903] 'agreement among raft nodes before linearized reading' (duration: 160.067406ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:01:07.136907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.823195ms","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-03T19:01:07.13726Z","caller":"traceutil/trace.go:171","msg":"trace[1088468903] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:581; }","duration":"160.210938ms","start":"2026-07-03T19:01:06.977025Z","end":"2026-07-03T19:01:07.137236Z","steps":["trace[1088468903] 'agreement among raft nodes before linearized reading' (duration: 159.767974ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:01:07.136901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.505534ms","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-03T19:01:07.137395Z","caller":"traceutil/trace.go:171","msg":"trace[223102571] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:581; }","duration":"159.062618ms","start":"2026-07-03T19:01:06.97831Z","end":"2026-07-03T19:01:07.137372Z","steps":["trace[223102571] 'agreement among raft nodes before linearized reading' (duration: 158.421973ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:01:07.88324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.742231ms","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-03T19:01:07.883502Z","caller":"traceutil/trace.go:171","msg":"trace[253953316] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:581; }","duration":"526.095154ms","start":"2026-07-03T19:01:07.35737Z","end":"2026-07-03T19:01:07.883465Z","steps":["trace[253953316] 'range keys from in-memory index tree' (duration: 525.712351ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:01:07.88354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.906642ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-03T19:01:07.883801Z","caller":"traceutil/trace.go:171","msg":"trace[685066951] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"770.244623ms","start":"2026-07-03T19:01:07.113538Z","end":"2026-07-03T19:01:07.883782Z","steps":["trace[685066951] 'process raft request' (duration: 770.145432ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:01:07.883921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:01:07.113528Z","time spent":"770.333605ms","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10855,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T19:01:07.883955Z","caller":"traceutil/trace.go:171","msg":"trace[1718003490] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"773.521375ms","start":"2026-07-03T19:01:07.110404Z","end":"2026-07-03T19:01:07.883926Z","steps":["trace[1718003490] 'process raft request' (duration: 152.098658ms)","trace[1718003490] 'compare' (duration: 620.751831ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:01:07.884063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:01:07.110379Z","time spent":"773.628076ms","remote":"127.0.0.1:33716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T19:01:08.436061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.875522ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-03T19:01:08.436194Z","caller":"traceutil/trace.go:171","msg":"trace[1448656955] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"1.29438226s","start":"2026-07-03T19:01:07.141796Z","end":"2026-07-03T19:01:08.436179Z","steps":["trace[1448656955] 'process raft request' (duration: 1.29433768s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:01:08.436231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:01:07.141788Z","time spent":"1.294427601s","remote":"127.0.0.1:33716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T19:01:08.436324Z","caller":"traceutil/trace.go:171","msg":"trace[578493206] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"1.294783675s","start":"2026-07-03T19:01:07.141531Z","end":"2026-07-03T19:01:08.436315Z","steps":["trace[578493206] 'process raft request' (duration: 924.580339ms)","trace[578493206] 'compare' (duration: 369.769932ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:01:08.436349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:01:07.141507Z","time spent":"1.294826085s","remote":"127.0.0.1:33716","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-03T19:01:08.436511Z","caller":"traceutil/trace.go:171","msg":"trace[235026384] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"1.294651143s","start":"2026-07-03T19:01:07.141855Z","end":"2026-07-03T19:01:08.436506Z","steps":["trace[235026384] 'process raft request' (duration: 1.29430365s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:01:08.436538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:01:07.141849Z","time spent":"1.294675244s","remote":"127.0.0.1:33716","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-03T19:01:08.436708Z","caller":"traceutil/trace.go:171","msg":"trace[330011637] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"1.274732219s","start":"2026-07-03T19:01:07.161969Z","end":"2026-07-03T19:01:08.436701Z","steps":["trace[330011637] 'process raft request' (duration: 1.274424946s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:01:08.436741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:01:07.16194Z","time spent":"1.274779699s","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23057,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T19:01:08.436809Z","caller":"traceutil/trace.go:171","msg":"trace[2061633922] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"1.268523612s","start":"2026-07-03T19:01:07.168272Z","end":"2026-07-03T19:01:08.436795Z","steps":["trace[2061633922] 'process raft request' (duration: 1.26832464s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:01:08.436873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:01:07.168252Z","time spent":"1.268591553s","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64392,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T19:01:08.44574Z","caller":"traceutil/trace.go:171","msg":"trace[649326861] linearizableReadLoop","detail":"{readStateIndex:613; appliedIndex:612; }","duration":"275.411655ms","start":"2026-07-03T19:01:08.170296Z","end":"2026-07-03T19:01:08.445707Z","steps":["trace[649326861] 'read index received' (duration: 275.133653ms)","trace[649326861] 'applied index is now lower than readState.Index' (duration: 276.952µs)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:01:08.445882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.562517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T19:01:08.445915Z","caller":"traceutil/trace.go:171","msg":"trace[1684088820] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:589; }","duration":"275.659767ms","start":"2026-07-03T19:01:08.170242Z","end":"2026-07-03T19:01:08.445902Z","steps":["trace[1684088820] 'agreement among raft nodes before linearized reading' (duration: 275.575566ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:01:08.44587Z","caller":"traceutil/trace.go:171","msg":"trace[620810949] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"548.92968ms","start":"2026-07-03T19:01:07.89687Z","end":"2026-07-03T19:01:08.4458Z","steps":["trace[620810949] 'process raft request' (duration: 548.661418ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:01:08.446129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:01:07.896837Z","time spent":"549.128523ms","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T19:01:08.611973Z","caller":"traceutil/trace.go:171","msg":"trace[1808483004] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"103.752108ms","start":"2026-07-03T19:01:08.508207Z","end":"2026-07-03T19:01:08.611959Z","steps":["trace[1808483004] 'process raft request' (duration: 103.512616ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:01:08.611827Z","caller":"traceutil/trace.go:171","msg":"trace[1135457498] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"124.361157ms","start":"2026-07-03T19:01:08.487426Z","end":"2026-07-03T19:01:08.611787Z","steps":["trace[1135457498] 'process raft request' (duration: 93.445617ms)","trace[1135457498] 'compare' (duration: 30.643958ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:01:08.848342Z","caller":"traceutil/trace.go:171","msg":"trace[2065897208] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"141.367693ms","start":"2026-07-03T19:01:08.706947Z","end":"2026-07-03T19:01:08.848315Z","steps":["trace[2065897208] 'process raft request' (duration: 141.265812ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:01:08.848592Z","caller":"traceutil/trace.go:171","msg":"trace[1194092519] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"151.569192ms","start":"2026-07-03T19:01:08.697011Z","end":"2026-07-03T19:01:08.84858Z","steps":["trace[1194092519] 'process raft request' (duration: 67.060161ms)","trace[1194092519] 'compare' (duration: 83.925205ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:01:09.168827Z","caller":"traceutil/trace.go:171","msg":"trace[835894360] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"136.198915ms","start":"2026-07-03T19:01:09.032584Z","end":"2026-07-03T19:01:09.168783Z","steps":["trace[835894360] 'process raft request' (duration: 54.04018ms)","trace[835894360] 'compare' (duration: 81.783551ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:01:09.768541Z","caller":"traceutil/trace.go:171","msg":"trace[1447406092] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"122.485405ms","start":"2026-07-03T19:01:09.646014Z","end":"2026-07-03T19:01:09.768499Z","steps":["trace[1447406092] 'process raft request' (duration: 110.12648ms)","trace[1447406092] 'compare' (duration: 11.607397ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:01:14.424541Z","caller":"traceutil/trace.go:171","msg":"trace[471562561] linearizableReadLoop","detail":"{readStateIndex:664; appliedIndex:663; }","duration":"418.71931ms","start":"2026-07-03T19:01:14.005791Z","end":"2026-07-03T19:01:14.424511Z","steps":["trace[471562561] 'read index received' (duration: 418.573638ms)","trace[471562561] 'applied index is now lower than readState.Index' (duration: 135.122µs)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:01:14.424584Z","caller":"traceutil/trace.go:171","msg":"trace[1716897543] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"467.605064ms","start":"2026-07-03T19:01:13.956925Z","end":"2026-07-03T19:01:14.42453Z","steps":["trace[1716897543] 'process raft request' (duration: 467.410852ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:01:14.424746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:01:13.956916Z","time spent":"467.751196ms","remote":"127.0.0.1:33558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":397,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-03T19:01:14.424824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.994829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T19:01:14.424896Z","caller":"traceutil/trace.go:171","msg":"trace[1695537613] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:639; }","duration":"255.118011ms","start":"2026-07-03T19:01:14.169757Z","end":"2026-07-03T19:01:14.424875Z","steps":["trace[1695537613] 'agreement among raft nodes before linearized reading' (duration: 254.99398ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:01:14.42498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.127076ms","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-03T19:01:14.425027Z","caller":"traceutil/trace.go:171","msg":"trace[1042066530] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:639; }","duration":"419.269118ms","start":"2026-07-03T19:01:14.005746Z","end":"2026-07-03T19:01:14.425015Z","steps":["trace[1042066530] 'agreement among raft nodes before linearized reading' (duration: 418.930973ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:01:14.425066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:01:14.005735Z","time spent":"419.321358ms","remote":"127.0.0.1:33716","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-03T19:01:14.747082Z","caller":"traceutil/trace.go:171","msg":"trace[2127371047] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"317.906756ms","start":"2026-07-03T19:01:14.429144Z","end":"2026-07-03T19:01:14.747051Z","steps":["trace[2127371047] 'process raft request' (duration: 317.761354ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:01:14.747241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:01:14.429122Z","time spent":"318.044398ms","remote":"127.0.0.1:33554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1749,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T19:01:14.750043Z","caller":"traceutil/trace.go:171","msg":"trace[1018651471] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"320.348157ms","start":"2026-07-03T19:01:14.429657Z","end":"2026-07-03T19:01:14.750005Z","steps":["trace[1018651471] 'process raft request' (duration: 320.097073ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:01:14.750096Z","caller":"traceutil/trace.go:171","msg":"trace[203312347] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"320.354797ms","start":"2026-07-03T19:01:14.429703Z","end":"2026-07-03T19:01:14.750058Z","steps":["trace[203312347] 'process raft request' (duration: 320.185485ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:01:14.750199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:01:14.42967Z","time spent":"320.470728ms","remote":"127.0.0.1:33716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T19:01:14.750218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:01:14.429627Z","time spent":"320.499719ms","remote":"127.0.0.1:33634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":182,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T19:01:14.757846Z","caller":"traceutil/trace.go:171","msg":"trace[871004095] linearizableReadLoop","detail":"{readStateIndex:667; appliedIndex:664; }","duration":"285.975752ms","start":"2026-07-03T19:01:14.471844Z","end":"2026-07-03T19:01:14.757819Z","steps":["trace[871004095] 'read index received' (duration: 275.097397ms)","trace[871004095] 'applied index is now lower than readState.Index' (duration: 10.877025ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:01:14.758095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.226865ms","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-07-03T19:01:14.758125Z","caller":"traceutil/trace.go:171","msg":"trace[508306753] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:642; }","duration":"286.334065ms","start":"2026-07-03T19:01:14.471783Z","end":"2026-07-03T19:01:14.758117Z","steps":["trace[508306753] 'agreement among raft nodes before linearized reading' (duration: 286.172403ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:01:14.758263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.256053ms","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-03T19:01:14.758446Z","caller":"traceutil/trace.go:171","msg":"trace[1269418645] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:642; }","duration":"281.469906ms","start":"2026-07-03T19:01:14.476956Z","end":"2026-07-03T19:01:14.758426Z","steps":["trace[1269418645] 'agreement among raft nodes before linearized reading' (duration: 281.234892ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:01:14.758364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.098704ms","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-03T19:01:14.758613Z","caller":"traceutil/trace.go:171","msg":"trace[1458786367] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:642; }","duration":"282.386107ms","start":"2026-07-03T19:01:14.47621Z","end":"2026-07-03T19:01:14.758596Z","steps":["trace[1458786367] 'agreement among raft nodes before linearized reading' (duration: 281.819859ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:01:16.102623Z","caller":"traceutil/trace.go:171","msg":"trace[1682322375] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"750.64243ms","start":"2026-07-03T19:01:15.351951Z","end":"2026-07-03T19:01:16.102594Z","steps":["trace[1682322375] 'process raft request' (duration: 748.663725ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:01:16.102869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:01:15.35192Z","time spent":"750.778472ms","remote":"127.0.0.1:33550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036101,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T19:01:16.670908Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605829753404104565,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-03T19:01:17.16123Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.044827896s","expected-duration":"1s"} {"level":"info","ts":"2026-07-03T19:01:17.161652Z","caller":"traceutil/trace.go:171","msg":"trace[1246907598] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"1.045327412s","start":"2026-07-03T19:01:16.116298Z","end":"2026-07-03T19:01:17.161625Z","steps":["trace[1246907598] 'process raft request' (duration: 1.045179561s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:01:17.161806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:01:16.116279Z","time spent":"1.045449124s","remote":"127.0.0.1:33618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T19:01:18.170016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000209793s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-03T19:01:18.170126Z","caller":"traceutil/trace.go:171","msg":"trace[1409150079] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000371945s","start":"2026-07-03T19:01:16.169721Z","end":"2026-07-03T19:01:18.170093Z","steps":["trace[1409150079] 'agreement among raft nodes before linearized reading' (duration: 2.000236793s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:01:18.17018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:01:16.169705Z","time spent":"2.000463917s","remote":"127.0.0.1:33460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-03T19:01:18.710062Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.548615844s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-03T19:01:19.822064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:01:18.623604Z","time spent":"1.198452216s","remote":"127.0.0.1:44638","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-03T19:01:19.822096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.465061237s","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-03T19:01:19.822252Z","caller":"traceutil/trace.go:171","msg":"trace[187437107] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:651; }","duration":"2.465230879s","start":"2026-07-03T19:01:17.356995Z","end":"2026-07-03T19:01:19.822226Z","steps":["trace[187437107] 'range keys from in-memory index tree' (duration: 2.465037046s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:01:19.8227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.112265281s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-03T19:01:19.823046Z","caller":"traceutil/trace.go:171","msg":"trace[1372794594] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"437.09325ms","start":"2026-07-03T19:01:19.38593Z","end":"2026-07-03T19:01:19.823023Z","steps":["trace[1372794594] 'process raft request' (duration: 436.971679ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:01:19.823146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:01:19.385896Z","time spent":"437.194771ms","remote":"127.0.0.1:33716","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-07-03T19:01:19.823466Z","caller":"traceutil/trace.go:171","msg":"trace[1029675909] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"3.663314134s","start":"2026-07-03T19:01:16.160136Z","end":"2026-07-03T19:01:19.82345Z","steps":["trace[1029675909] 'process raft request' (duration: 2.550117839s)","trace[1029675909] 'compare' (duration: 1.111618662s)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:01:19.823524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:01:16.160102Z","time spent":"3.663394026s","remote":"127.0.0.1:33634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":818,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T19:01:19.823939Z","caller":"traceutil/trace.go:171","msg":"trace[1664238169] linearizableReadLoop","detail":"{readStateIndex:678; appliedIndex:676; }","duration":"3.654142905s","start":"2026-07-03T19:01:16.16977Z","end":"2026-07-03T19:01:19.823913Z","steps":["trace[1664238169] 'read index received' (duration: 991.712267ms)","trace[1664238169] 'applied index is now lower than readState.Index' (duration: 2.662427228s)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:01:19.824192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.070319993s","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-07-03T19:01:19.824254Z","caller":"traceutil/trace.go:171","msg":"trace[352195178] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:653; }","duration":"3.070432474s","start":"2026-07-03T19:01:16.753807Z","end":"2026-07-03T19:01:19.82424Z","steps":["trace[352195178] 'agreement among raft nodes before linearized reading' (duration: 3.070285932s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:01:19.824302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:01:16.753773Z","time spent":"3.070519446s","remote":"127.0.0.1:33716","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-07-03T19:01:19.824467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.855493033s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-03T19:01:19.82453Z","caller":"traceutil/trace.go:171","msg":"trace[38465995] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:653; }","duration":"2.855607084s","start":"2026-07-03T19:01:16.968901Z","end":"2026-07-03T19:01:19.824508Z","steps":["trace[38465995] 'agreement among raft nodes before linearized reading' (duration: 2.855441802s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:01:19.824552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.079308791s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-03T19:01:19.824575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:01:16.968879Z","time spent":"2.855686715s","remote":"127.0.0.1:33812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":51,"response size":30,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-07-03T19:01:19.8246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"722.773563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.169\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-03T19:01:19.824619Z","caller":"traceutil/trace.go:171","msg":"trace[190906921] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:653; }","duration":"1.079430903s","start":"2026-07-03T19:01:18.745165Z","end":"2026-07-03T19:01:19.824596Z","steps":["trace[190906921] 'agreement among raft nodes before linearized reading' (duration: 1.079322802s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:01:19.824659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:01:18.745136Z","time spent":"1.079516064s","remote":"127.0.0.1:33460","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-03T19:01:19.824663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.053624423s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-03T19:01:19.824748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.056867876s","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-03T19:01:19.82477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.653548725s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T19:01:19.824779Z","caller":"traceutil/trace.go:171","msg":"trace[475559195] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:653; }","duration":"3.053802146s","start":"2026-07-03T19:01:16.770939Z","end":"2026-07-03T19:01:19.824741Z","steps":["trace[475559195] 'agreement among raft nodes before linearized reading' (duration: 3.053566463s)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:01:19.8248Z","caller":"traceutil/trace.go:171","msg":"trace[997675279] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:653; }","duration":"3.056939637s","start":"2026-07-03T19:01:16.767839Z","end":"2026-07-03T19:01:19.824779Z","steps":["trace[997675279] 'agreement among raft nodes before linearized reading' (duration: 3.056859006s)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:01:19.824811Z","caller":"traceutil/trace.go:171","msg":"trace[624721682] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:653; }","duration":"1.653631307s","start":"2026-07-03T19:01:18.171167Z","end":"2026-07-03T19:01:19.824799Z","steps":["trace[624721682] 'agreement among raft nodes before linearized reading' (duration: 1.653558156s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:01:19.824843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:01:16.767819Z","time spent":"3.057016407s","remote":"127.0.0.1:33716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-03T19:01:19.824847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:01:18.171144Z","time spent":"1.653694418s","remote":"127.0.0.1:33466","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-03T19:01:19.824854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:01:16.770911Z","time spent":"3.053929698s","remote":"127.0.0.1:33716","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-03T19:01:19.825092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.05330796s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-03T19:01:19.825284Z","caller":"traceutil/trace.go:171","msg":"trace[1275654899] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:653; }","duration":"3.053655585s","start":"2026-07-03T19:01:16.771559Z","end":"2026-07-03T19:01:19.825215Z","steps":["trace[1275654899] 'agreement among raft nodes before linearized reading' (duration: 3.053158048s)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:01:19.824656Z","caller":"traceutil/trace.go:171","msg":"trace[292071519] range","detail":"{range_begin:/registry/masterleases/199.204.45.169; range_end:; response_count:1; response_revision:653; }","duration":"722.878585ms","start":"2026-07-03T19:01:19.101763Z","end":"2026-07-03T19:01:19.824641Z","steps":["trace[292071519] 'agreement among raft nodes before linearized reading' (duration: 722.705482ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:01:19.825365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:01:16.771536Z","time spent":"3.053806137s","remote":"127.0.0.1:33634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":42,"response size":30,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-07-03T19:01:19.825855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.055219906s","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-07-03T19:01:19.825908Z","caller":"traceutil/trace.go:171","msg":"trace[418634123] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:653; }","duration":"3.055295096s","start":"2026-07-03T19:01:16.770602Z","end":"2026-07-03T19:01:19.825897Z","steps":["trace[418634123] 'agreement among raft nodes before linearized reading' (duration: 3.054006668s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:01:19.825944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:01:16.770589Z","time spent":"3.055347038s","remote":"127.0.0.1:33716","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-03T19:01:19.826928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:01:19.101735Z","time spent":"723.622475ms","remote":"127.0.0.1:33494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.169\" "} {"level":"info","ts":"2026-07-03T19:01:20.460881Z","caller":"traceutil/trace.go:171","msg":"trace[1816073594] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"128.088819ms","start":"2026-07-03T19:01:20.332763Z","end":"2026-07-03T19:01:20.460851Z","steps":["trace[1816073594] 'process raft request' (duration: 126.274723ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:01:21.688189Z","caller":"traceutil/trace.go:171","msg":"trace[14705511] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"223.46865ms","start":"2026-07-03T19:01:21.464687Z","end":"2026-07-03T19:01:21.688155Z","steps":["trace[14705511] 'process raft request' (duration: 220.066499ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:01:22.535876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.539146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T19:01:22.535991Z","caller":"traceutil/trace.go:171","msg":"trace[1567652695] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:704; }","duration":"196.754519ms","start":"2026-07-03T19:01:22.339209Z","end":"2026-07-03T19:01:22.535964Z","steps":["trace[1567652695] 'range keys from in-memory index tree' (duration: 196.397594ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:01:39.266496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.169480958s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-07-03T19:01:39.266564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.166051926s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-03T19:01:39.266611Z","caller":"traceutil/trace.go:171","msg":"trace[172436134] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:835; }","duration":"1.169642021s","start":"2026-07-03T19:01:38.096947Z","end":"2026-07-03T19:01:39.266589Z","steps":["trace[172436134] 'range keys from in-memory index tree' (duration: 1.169293084s)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:01:39.26665Z","caller":"traceutil/trace.go:171","msg":"trace[1163838816] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:835; }","duration":"1.166180739s","start":"2026-07-03T19:01:38.100449Z","end":"2026-07-03T19:01:39.26663Z","steps":["trace[1163838816] 'range keys from in-memory index tree' (duration: 1.165896913s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:01:39.266693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:01:38.100434Z","time spent":"1.166248731s","remote":"127.0.0.1:33716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-03T19:01:39.266794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:01:38.09693Z","time spent":"1.169844275s","remote":"127.0.0.1:33716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-03T19:01:39.26681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.096373183s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T19:01:39.26689Z","caller":"traceutil/trace.go:171","msg":"trace[946040597] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:835; }","duration":"1.096481136s","start":"2026-07-03T19:01:38.170395Z","end":"2026-07-03T19:01:39.266876Z","steps":["trace[946040597] 'range keys from in-memory index tree' (duration: 1.096306672s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:01:39.266923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:01:38.17038Z","time spent":"1.096535366s","remote":"127.0.0.1:33460","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-03T19:01:39.267249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.214723285s","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-03T19:01:39.267297Z","caller":"traceutil/trace.go:171","msg":"trace[1986883120] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:835; }","duration":"1.214808457s","start":"2026-07-03T19:01:38.052474Z","end":"2026-07-03T19:01:39.267282Z","steps":["trace[1986883120] 'range keys from in-memory index tree' (duration: 1.214527192s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:01:39.267338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:01:38.052452Z","time spent":"1.214874529s","remote":"127.0.0.1:33716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-03T19:01:39.267473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.173508742s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-03T19:01:39.267516Z","caller":"traceutil/trace.go:171","msg":"trace[1465277839] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:835; }","duration":"1.173571054s","start":"2026-07-03T19:01:38.093933Z","end":"2026-07-03T19:01:39.267504Z","steps":["trace[1465277839] 'range keys from in-memory index tree' (duration: 1.173389569s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:01:39.267548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:01:38.093922Z","time spent":"1.173618714s","remote":"127.0.0.1:33716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-03T19:01:39.267607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.1888394s","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-07-03T19:01:39.267647Z","caller":"traceutil/trace.go:171","msg":"trace[1953363002] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:835; }","duration":"1.188900801s","start":"2026-07-03T19:01:38.078734Z","end":"2026-07-03T19:01:39.267635Z","steps":["trace[1953363002] 'range keys from in-memory index tree' (duration: 1.188674616s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:01:39.267676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:01:38.078719Z","time spent":"1.188950182s","remote":"127.0.0.1:33716","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-07-03T19:01:39.603224Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605829753404105016,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-03T19:01:39.614174Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.370002532s","expected-duration":"1s"} {"level":"info","ts":"2026-07-03T19:01:39.614535Z","caller":"traceutil/trace.go:171","msg":"trace[1125457799] transaction","detail":"{read_only:false; response_revision:836; number_of_response:1; }","duration":"1.370466902s","start":"2026-07-03T19:01:38.244046Z","end":"2026-07-03T19:01:39.614513Z","steps":["trace[1125457799] 'process raft request' (duration: 1.370311899s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:01:39.614677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:01:38.244024Z","time spent":"1.370581474s","remote":"127.0.0.1:33678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T19:01:39.629517Z","caller":"traceutil/trace.go:171","msg":"trace[433560463] transaction","detail":"{read_only:false; response_revision:837; number_of_response:1; }","duration":"354.898316ms","start":"2026-07-03T19:01:39.27458Z","end":"2026-07-03T19:01:39.629478Z","steps":["trace[433560463] 'process raft request' (duration: 354.680911ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:01:39.629577Z","caller":"traceutil/trace.go:171","msg":"trace[161629189] transaction","detail":"{read_only:false; response_revision:840; number_of_response:1; }","duration":"351.652098ms","start":"2026-07-03T19:01:39.277911Z","end":"2026-07-03T19:01:39.629563Z","steps":["trace[161629189] 'process raft request' (duration: 351.547756ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:01:39.629669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:01:39.274561Z","time spent":"355.020498ms","remote":"127.0.0.1:33716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T19:01:39.629689Z","caller":"traceutil/trace.go:171","msg":"trace[333244187] linearizableReadLoop","detail":"{readStateIndex:873; appliedIndex:867; }","duration":"527.114023ms","start":"2026-07-03T19:01:39.102546Z","end":"2026-07-03T19:01:39.62966Z","steps":["trace[333244187] 'read index received' (duration: 511.865542ms)","trace[333244187] 'applied index is now lower than readState.Index' (duration: 15.247351ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:01:39.629721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:01:39.277901Z","time spent":"351.75289ms","remote":"127.0.0.1:33716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T19:01:39.629589Z","caller":"traceutil/trace.go:171","msg":"trace[1764515367] transaction","detail":"{read_only:false; response_revision:839; number_of_response:1; }","duration":"353.124168ms","start":"2026-07-03T19:01:39.276436Z","end":"2026-07-03T19:01:39.62956Z","steps":["trace[1764515367] 'process raft request' (duration: 352.993355ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:01:39.629807Z","caller":"traceutil/trace.go:171","msg":"trace[1149701154] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"351.834492ms","start":"2026-07-03T19:01:39.277963Z","end":"2026-07-03T19:01:39.629798Z","steps":["trace[1149701154] 'process raft request' (duration: 351.562626ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:01:39.629832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.150371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-03T19:01:39.629848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:01:39.277958Z","time spent":"351.867352ms","remote":"127.0.0.1:33716","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-03T19:01:39.629848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:01:39.276432Z","time spent":"353.338252ms","remote":"127.0.0.1:33716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T19:01:39.629858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.868943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-03T19:01:39.629868Z","caller":"traceutil/trace.go:171","msg":"trace[989948065] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:841; }","duration":"356.218493ms","start":"2026-07-03T19:01:39.273641Z","end":"2026-07-03T19:01:39.629859Z","steps":["trace[989948065] 'agreement among raft nodes before linearized reading' (duration: 356.162382ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:01:39.629563Z","caller":"traceutil/trace.go:171","msg":"trace[1805441662] transaction","detail":"{read_only:false; response_revision:838; number_of_response:1; }","duration":"353.132468ms","start":"2026-07-03T19:01:39.276392Z","end":"2026-07-03T19:01:39.629524Z","steps":["trace[1805441662] 'process raft request' (duration: 352.990975ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:01:39.629899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:01:39.273614Z","time spent":"356.278544ms","remote":"127.0.0.1:33466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-03T19:01:39.629902Z","caller":"traceutil/trace.go:171","msg":"trace[733328928] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:841; }","duration":"383.928134ms","start":"2026-07-03T19:01:39.24596Z","end":"2026-07-03T19:01:39.629888Z","steps":["trace[733328928] 'agreement among raft nodes before linearized reading' (duration: 383.826332ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:01:39.629921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:01:39.276382Z","time spent":"353.519446ms","remote":"127.0.0.1:33716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T19:01:39.629929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.359298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.169\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-07-03T19:01:39.629938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:01:39.245821Z","time spent":"384.106708ms","remote":"127.0.0.1:33504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":31,"response size":30,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"info","ts":"2026-07-03T19:01:39.630046Z","caller":"traceutil/trace.go:171","msg":"trace[1430860563] range","detail":"{range_begin:/registry/masterleases/199.204.45.169; range_end:; response_count:1; response_revision:841; }","duration":"527.45177ms","start":"2026-07-03T19:01:39.10251Z","end":"2026-07-03T19:01:39.629962Z","steps":["trace[1430860563] 'agreement among raft nodes before linearized reading' (duration: 527.267586ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:01:39.630086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:01:39.102489Z","time spent":"527.588952ms","remote":"127.0.0.1:33494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.169\" "} {"level":"info","ts":"2026-07-03T19:02:19.721641Z","caller":"traceutil/trace.go:171","msg":"trace[2118927997] linearizableReadLoop","detail":"{readStateIndex:1140; appliedIndex:1139; }","duration":"411.948138ms","start":"2026-07-03T19:02:19.309662Z","end":"2026-07-03T19:02:19.72161Z","steps":["trace[2118927997] 'read index received' (duration: 384.088149ms)","trace[2118927997] 'applied index is now lower than readState.Index' (duration: 27.859319ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:02:19.721708Z","caller":"traceutil/trace.go:171","msg":"trace[686079499] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"536.971643ms","start":"2026-07-03T19:02:19.184662Z","end":"2026-07-03T19:02:19.721634Z","steps":["trace[686079499] 'process raft request' (duration: 509.042032ms)","trace[686079499] 'compare' (duration: 27.765676ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:02:19.721814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.134044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-03T19:02:19.721848Z","caller":"traceutil/trace.go:171","msg":"trace[156298006] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1099; }","duration":"412.228857ms","start":"2026-07-03T19:02:19.309608Z","end":"2026-07-03T19:02:19.721837Z","steps":["trace[156298006] 'agreement among raft nodes before linearized reading' (duration: 412.113714ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:02:19.721876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:02:19.309586Z","time spent":"412.282868ms","remote":"127.0.0.1:33618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":12,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-07-03T19:02:19.721893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:02:19.184643Z","time spent":"537.157938ms","remote":"127.0.0.1:33494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T19:02:20.804705Z","caller":"traceutil/trace.go:171","msg":"trace[1898190868] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"263.744601ms","start":"2026-07-03T19:02:20.540929Z","end":"2026-07-03T19:02:20.804674Z","steps":["trace[1898190868] 'process raft request' (duration: 263.580566ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:02:44.496927Z","caller":"traceutil/trace.go:171","msg":"trace[574437340] transaction","detail":"{read_only:false; response_revision:1181; number_of_response:1; }","duration":"297.771768ms","start":"2026-07-03T19:02:44.199132Z","end":"2026-07-03T19:02:44.496904Z","steps":["trace[574437340] 'process raft request' (duration: 233.351765ms)","trace[574437340] 'compare' (duration: 64.32562ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:02:44.534375Z","caller":"traceutil/trace.go:171","msg":"trace[649704692] transaction","detail":"{read_only:false; response_revision:1182; number_of_response:1; }","duration":"334.589106ms","start":"2026-07-03T19:02:44.199773Z","end":"2026-07-03T19:02:44.534362Z","steps":["trace[649704692] 'process raft request' (duration: 334.499093ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:02:44.534482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:02:44.199754Z","time spent":"334.671529ms","remote":"127.0.0.1:33716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T19:02:44.534639Z","caller":"traceutil/trace.go:171","msg":"trace[1155606690] linearizableReadLoop","detail":"{readStateIndex:1228; appliedIndex:1225; }","duration":"307.588668ms","start":"2026-07-03T19:02:44.227024Z","end":"2026-07-03T19:02:44.534613Z","steps":["trace[1155606690] 'read index received' (duration: 205.609133ms)","trace[1155606690] 'applied index is now lower than readState.Index' (duration: 101.977475ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:02:44.534723Z","caller":"traceutil/trace.go:171","msg":"trace[1992463012] transaction","detail":"{read_only:false; response_revision:1183; number_of_response:1; }","duration":"334.752781ms","start":"2026-07-03T19:02:44.199914Z","end":"2026-07-03T19:02:44.534667Z","steps":["trace[1992463012] 'process raft request' (duration: 334.420881ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:02:44.534924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:02:44.199906Z","time spent":"334.918457ms","remote":"127.0.0.1:33716","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-03T19:02:44.534949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.907588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-03T19:02:44.535079Z","caller":"traceutil/trace.go:171","msg":"trace[349221283] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1183; }","duration":"307.98354ms","start":"2026-07-03T19:02:44.226995Z","end":"2026-07-03T19:02:44.534979Z","steps":["trace[349221283] 'agreement among raft nodes before linearized reading' (duration: 307.719272ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:02:44.535215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:02:44.226983Z","time spent":"308.132996ms","remote":"127.0.0.1:33716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-03T19:02:44.794193Z","caller":"traceutil/trace.go:171","msg":"trace[443513834] transaction","detail":"{read_only:false; response_revision:1184; number_of_response:1; }","duration":"255.532525ms","start":"2026-07-03T19:02:44.538633Z","end":"2026-07-03T19:02:44.794166Z","steps":["trace[443513834] 'process raft request' (duration: 183.506875ms)","trace[443513834] 'compare' (duration: 71.629347ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T19:02:49.008905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.24038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T19:02:49.008964Z","caller":"traceutil/trace.go:171","msg":"trace[1773795885] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1197; }","duration":"104.358104ms","start":"2026-07-03T19:02:48.904593Z","end":"2026-07-03T19:02:49.008952Z","steps":["trace[1773795885] 'count revisions from in-memory index tree' (duration: 104.129226ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:02:49.28224Z","caller":"traceutil/trace.go:171","msg":"trace[1672777685] transaction","detail":"{read_only:false; response_revision:1198; number_of_response:1; }","duration":"136.810483ms","start":"2026-07-03T19:02:49.145403Z","end":"2026-07-03T19:02:49.282213Z","steps":["trace[1672777685] 'process raft request' (duration: 70.348771ms)","trace[1672777685] 'compare' (duration: 66.264256ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:02:49.282378Z","caller":"traceutil/trace.go:171","msg":"trace[1641821460] transaction","detail":"{read_only:false; response_revision:1199; number_of_response:1; }","duration":"112.948806ms","start":"2026-07-03T19:02:49.169402Z","end":"2026-07-03T19:02:49.282351Z","steps":["trace[1641821460] 'process raft request' (duration: 112.749709ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:02:54.971672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.623628ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-03T19:02:54.971772Z","caller":"traceutil/trace.go:171","msg":"trace[1555398163] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"131.040781ms","start":"2026-07-03T19:02:54.84072Z","end":"2026-07-03T19:02:54.971761Z","steps":["trace[1555398163] 'process raft request' (duration: 23.276288ms)","trace[1555398163] 'compare' (duration: 107.526325ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:02:54.972005Z","caller":"traceutil/trace.go:171","msg":"trace[1378878610] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"131.227717ms","start":"2026-07-03T19:02:54.840747Z","end":"2026-07-03T19:02:54.971975Z","steps":["trace[1378878610] 'process raft request' (duration: 130.988249ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T19:02:55.167903Z","caller":"traceutil/trace.go:171","msg":"trace[321823436] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"154.299293ms","start":"2026-07-03T19:02:55.013571Z","end":"2026-07-03T19:02:55.16787Z","steps":["trace[321823436] 'process raft request' (duration: 84.181002ms)","trace[321823436] 'compare' (duration: 69.964706ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:03:31.069538Z","caller":"traceutil/trace.go:171","msg":"trace[1502123740] linearizableReadLoop","detail":"{readStateIndex:1427; appliedIndex:1426; }","duration":"217.585788ms","start":"2026-07-03T19:03:30.851929Z","end":"2026-07-03T19:03:31.069515Z","steps":["trace[1502123740] 'read index received' (duration: 217.404202ms)","trace[1502123740] 'applied index is now lower than readState.Index' (duration: 180.596µs)"],"step_count":2} {"level":"info","ts":"2026-07-03T19:03:31.069647Z","caller":"traceutil/trace.go:171","msg":"trace[699080222] transaction","detail":"{read_only:false; response_revision:1371; number_of_response:1; }","duration":"461.373141ms","start":"2026-07-03T19:03:30.608232Z","end":"2026-07-03T19:03:31.069605Z","steps":["trace[699080222] 'process raft request' (duration: 461.146213ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T19:03:31.069817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T19:03:30.608204Z","time spent":"461.538057ms","remote":"127.0.0.1:33716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T19:03:31.069693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.742024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T19:03:31.0699Z","caller":"traceutil/trace.go:171","msg":"trace[2021549224] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1371; }","duration":"217.997053ms","start":"2026-07-03T19:03:30.851876Z","end":"2026-07-03T19:03:31.069873Z","steps":["trace[2021549224] 'agreement among raft nodes before linearized reading' (duration: 217.766045ms)"],"step_count":1}