{"level":"warn","ts":"2026-07-16T06:55:18.126007Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-16T06:55:18.126117Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.18:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.18:2380","--initial-cluster=instance=https://199.19.213.18:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.18:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.18:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-16T06:55:18.126198Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-16T06:55:18.12621Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.18:2380"]} {"level":"info","ts":"2026-07-16T06:55:18.126256Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-16T06:55:18.126978Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.18:2379"]} {"level":"info","ts":"2026-07-16T06:55:18.12707Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.18:2380"],"listen-peer-urls":["https://199.19.213.18:2380"],"advertise-client-urls":["https://199.19.213.18:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.18:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.18:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-16T06:55:18.129992Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.655853ms"} {"level":"info","ts":"2026-07-16T06:55:18.13648Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6d0358acb671c233","cluster-id":"ba212af698de913f"} {"level":"info","ts":"2026-07-16T06:55:18.136554Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 switched to configuration voters=()"} {"level":"info","ts":"2026-07-16T06:55:18.136606Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 became follower at term 0"} {"level":"info","ts":"2026-07-16T06:55:18.136621Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6d0358acb671c233 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-16T06:55:18.136638Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 became follower at term 1"} {"level":"info","ts":"2026-07-16T06:55:18.136679Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 switched to configuration voters=(7855219673882804787)"} {"level":"warn","ts":"2026-07-16T06:55:18.141412Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-16T06:55:18.142951Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-16T06:55:18.144323Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-16T06:55:18.147918Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6d0358acb671c233","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-16T06:55:18.148119Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6d0358acb671c233","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-16T06:55:18.148456Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-16T06:55:18.14851Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-16T06:55:18.148532Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-16T06:55:18.150001Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 switched to configuration voters=(7855219673882804787)"} {"level":"info","ts":"2026-07-16T06:55:18.150381Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ba212af698de913f","local-member-id":"6d0358acb671c233","added-peer-id":"6d0358acb671c233","added-peer-peer-urls":["https://199.19.213.18:2380"]} {"level":"info","ts":"2026-07-16T06:55:18.154071Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-16T06:55:18.154419Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6d0358acb671c233","initial-advertise-peer-urls":["https://199.19.213.18:2380"],"listen-peer-urls":["https://199.19.213.18:2380"],"advertise-client-urls":["https://199.19.213.18:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.18:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-16T06:55:18.154475Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-16T06:55:18.154985Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.18:2380"} {"level":"info","ts":"2026-07-16T06:55:18.155027Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.18:2380"} {"level":"info","ts":"2026-07-16T06:55:18.33716Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 is starting a new election at term 1"} {"level":"info","ts":"2026-07-16T06:55:18.337233Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-16T06:55:18.337261Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 received MsgPreVoteResp from 6d0358acb671c233 at term 1"} {"level":"info","ts":"2026-07-16T06:55:18.33728Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 became candidate at term 2"} {"level":"info","ts":"2026-07-16T06:55:18.337292Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 received MsgVoteResp from 6d0358acb671c233 at term 2"} {"level":"info","ts":"2026-07-16T06:55:18.337308Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6d0358acb671c233 became leader at term 2"} {"level":"info","ts":"2026-07-16T06:55:18.337321Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6d0358acb671c233 elected leader 6d0358acb671c233 at term 2"} {"level":"info","ts":"2026-07-16T06:55:18.33842Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6d0358acb671c233","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.18:2379]}","request-path":"/0/members/6d0358acb671c233/attributes","cluster-id":"ba212af698de913f","publish-timeout":"7s"} {"level":"info","ts":"2026-07-16T06:55:18.338464Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-16T06:55:18.338873Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-16T06:55:18.339066Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T06:55:18.34013Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-16T06:55:18.34019Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-16T06:55:18.342297Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.18:2379"} {"level":"info","ts":"2026-07-16T06:55:18.342593Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-16T06:55:18.343571Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ba212af698de913f","local-member-id":"6d0358acb671c233","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T06:55:18.343658Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T06:55:18.343685Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-07-16T06:55:23.984256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.95006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-07-16T06:55:23.98434Z","caller":"traceutil/trace.go:171","msg":"trace[1739551786] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:281; }","duration":"161.064074ms","start":"2026-07-16T06:55:23.823261Z","end":"2026-07-16T06:55:23.984325Z","steps":["trace[1739551786] 'range keys from in-memory index tree' (duration: 160.88962ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:55:24.356779Z","caller":"traceutil/trace.go:171","msg":"trace[1616732687] linearizableReadLoop","detail":"{readStateIndex:288; appliedIndex:287; }","duration":"363.827721ms","start":"2026-07-16T06:55:23.992924Z","end":"2026-07-16T06:55:24.356752Z","steps":["trace[1616732687] 'read index received' (duration: 363.803781ms)","trace[1616732687] 'applied index is now lower than readState.Index' (duration: 22.8µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:55:24.356865Z","caller":"traceutil/trace.go:171","msg":"trace[1849167655] transaction","detail":"{read_only:false; response_revision:282; number_of_response:1; }","duration":"369.643482ms","start":"2026-07-16T06:55:23.987187Z","end":"2026-07-16T06:55:24.356831Z","steps":["trace[1849167655] 'process raft request' (duration: 369.441307ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:55:24.357066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.105199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/coredns\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:55:24.357142Z","caller":"traceutil/trace.go:171","msg":"trace[568812261] range","detail":"{range_begin:/registry/configmaps/kube-system/coredns; range_end:; response_count:0; response_revision:282; }","duration":"364.195372ms","start":"2026-07-16T06:55:23.99292Z","end":"2026-07-16T06:55:24.357115Z","steps":["trace[568812261] 'agreement among raft nodes before linearized reading' (duration: 363.983886ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:55:24.357204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:55:23.992884Z","time spent":"364.304574ms","remote":"127.0.0.1:38528","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/configmaps/kube-system/coredns\" "} {"level":"warn","ts":"2026-07-16T06:55:24.357376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:55:23.987159Z","time spent":"369.772325ms","remote":"127.0.0.1:38632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":178,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-16T06:55:24.857626Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13993703743532595845,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-16T06:55:24.950733Z","caller":"traceutil/trace.go:171","msg":"trace[1027133035] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"893.639443ms","start":"2026-07-16T06:55:24.057052Z","end":"2026-07-16T06:55:24.950691Z","steps":["trace[1027133035] 'process raft request' (duration: 892.835161ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:55:24.950855Z","caller":"traceutil/trace.go:171","msg":"trace[484091660] transaction","detail":"{read_only:false; number_of_response:0; response_revision:285; }","duration":"771.103765ms","start":"2026-07-16T06:55:24.179726Z","end":"2026-07-16T06:55:24.950829Z","steps":["trace[484091660] 'process raft request' (duration: 771.076564ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:55:24.950989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:55:24.179689Z","time spent":"771.241809ms","remote":"127.0.0.1:38612","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-16T06:55:24.951098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:55:24.05703Z","time spent":"893.85246ms","remote":"127.0.0.1:38500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-16T06:55:24.951192Z","caller":"traceutil/trace.go:171","msg":"trace[1280022074] transaction","detail":"{read_only:false; number_of_response:0; response_revision:283; }","duration":"786.44117ms","start":"2026-07-16T06:55:24.164737Z","end":"2026-07-16T06:55:24.951178Z","steps":["trace[1280022074] 'process raft request' (duration: 785.856394ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:55:24.951274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:55:24.16471Z","time spent":"786.530702ms","remote":"127.0.0.1:38596","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-16T06:55:24.951433Z","caller":"traceutil/trace.go:171","msg":"trace[1083612420] transaction","detail":"{read_only:false; number_of_response:0; response_revision:284; }","duration":"772.011029ms","start":"2026-07-16T06:55:24.179412Z","end":"2026-07-16T06:55:24.951423Z","steps":["trace[1083612420] 'process raft request' (duration: 771.336441ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:55:24.951449Z","caller":"traceutil/trace.go:171","msg":"trace[1486343705] transaction","detail":"{read_only:false; number_of_response:0; response_revision:284; }","duration":"772.216235ms","start":"2026-07-16T06:55:24.179201Z","end":"2026-07-16T06:55:24.951417Z","steps":["trace[1486343705] 'process raft request' (duration: 771.518326ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:55:24.951519Z","caller":"traceutil/trace.go:171","msg":"trace[427892337] transaction","detail":"{read_only:false; response_revision:285; number_of_response:1; }","duration":"771.822755ms","start":"2026-07-16T06:55:24.179685Z","end":"2026-07-16T06:55:24.951508Z","steps":["trace[427892337] 'process raft request' (duration: 771.083654ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:55:24.951568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:55:24.179677Z","time spent":"771.871426ms","remote":"127.0.0.1:38612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-16T06:55:24.951634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:55:24.179184Z","time spent":"772.345819ms","remote":"127.0.0.1:38612","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-16T06:55:24.951483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:55:24.179381Z","time spent":"772.081763ms","remote":"127.0.0.1:38612","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-16T06:55:24.951835Z","caller":"traceutil/trace.go:171","msg":"trace[383433319] transaction","detail":"{read_only:false; response_revision:284; number_of_response:1; }","duration":"772.940935ms","start":"2026-07-16T06:55:24.178878Z","end":"2026-07-16T06:55:24.951818Z","steps":["trace[383433319] 'process raft request' (duration: 771.783813ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:55:24.951974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:55:24.178859Z","time spent":"773.071459ms","remote":"127.0.0.1:38612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3959,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-16T06:55:25.428682Z","caller":"traceutil/trace.go:171","msg":"trace[2088300061] transaction","detail":"{read_only:false; response_revision:287; number_of_response:1; }","duration":"471.108799ms","start":"2026-07-16T06:55:24.957554Z","end":"2026-07-16T06:55:25.428663Z","steps":["trace[2088300061] 'process raft request' (duration: 471.069947ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:55:25.428702Z","caller":"traceutil/trace.go:171","msg":"trace[1143120126] transaction","detail":"{read_only:false; response_revision:286; number_of_response:1; }","duration":"1.066911336s","start":"2026-07-16T06:55:24.361752Z","end":"2026-07-16T06:55:25.428663Z","steps":["trace[1143120126] 'process raft request' (duration: 1.010559256s)","trace[1143120126] 'compare' (duration: 56.164654ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:55:25.428792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:55:24.957536Z","time spent":"471.207871ms","remote":"127.0.0.1:38500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":625,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-16T06:55:25.428794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:55:24.361742Z","time spent":"1.067007677s","remote":"127.0.0.1:38528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":597,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-16T06:55:25.428781Z","caller":"traceutil/trace.go:171","msg":"trace[152020050] linearizableReadLoop","detail":"{readStateIndex:296; appliedIndex:288; }","duration":"1.071842112s","start":"2026-07-16T06:55:24.356875Z","end":"2026-07-16T06:55:25.428718Z","steps":["trace[152020050] 'read index received' (duration: 593.01828ms)","trace[152020050] 'applied index is now lower than readState.Index' (duration: 478.818392ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:55:25.428914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.367399276s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-16T06:55:25.428982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.371317403s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-07-16T06:55:25.429024Z","caller":"traceutil/trace.go:171","msg":"trace[106397201] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:287; }","duration":"1.371406576s","start":"2026-07-16T06:55:24.057606Z","end":"2026-07-16T06:55:25.429012Z","steps":["trace[106397201] 'agreement among raft nodes before linearized reading' (duration: 1.371292983s)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:55:25.429031Z","caller":"traceutil/trace.go:171","msg":"trace[1712548716] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:287; }","duration":"1.36753992s","start":"2026-07-16T06:55:24.061458Z","end":"2026-07-16T06:55:25.428998Z","steps":["trace[1712548716] 'agreement among raft nodes before linearized reading' (duration: 1.367336764s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:55:25.429049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.067722868s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cronjob-controller\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-16T06:55:25.429067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:55:24.057591Z","time spent":"1.371461717s","remote":"127.0.0.1:38790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":687,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-07-16T06:55:25.42909Z","caller":"traceutil/trace.go:171","msg":"trace[1812468053] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cronjob-controller; range_end:; response_count:0; response_revision:287; }","duration":"1.067806172s","start":"2026-07-16T06:55:24.361275Z","end":"2026-07-16T06:55:25.429082Z","steps":["trace[1812468053] 'agreement among raft nodes before linearized reading' (duration: 1.067744349s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:55:25.429093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:55:24.061447Z","time spent":"1.367636223s","remote":"127.0.0.1:38684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"warn","ts":"2026-07-16T06:55:25.429102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.863151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2920"} {"level":"warn","ts":"2026-07-16T06:55:25.429117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:55:24.361254Z","time spent":"1.067856102s","remote":"127.0.0.1:38632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/cronjob-controller\" "} {"level":"info","ts":"2026-07-16T06:55:25.42914Z","caller":"traceutil/trace.go:171","msg":"trace[1739345736] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:287; }","duration":"475.924001ms","start":"2026-07-16T06:55:24.953205Z","end":"2026-07-16T06:55:25.429129Z","steps":["trace[1739345736] 'agreement among raft nodes before linearized reading' (duration: 475.847359ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:55:25.429168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:55:24.95319Z","time spent":"475.971683ms","remote":"127.0.0.1:38612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":2943,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-07-16T06:55:25.429221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.272382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4016"} {"level":"info","ts":"2026-07-16T06:55:25.429241Z","caller":"traceutil/trace.go:171","msg":"trace[2061452450] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:287; }","duration":"476.292262ms","start":"2026-07-16T06:55:24.952941Z","end":"2026-07-16T06:55:25.429234Z","steps":["trace[2061452450] 'agreement among raft nodes before linearized reading' (duration: 476.257042ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:55:25.429257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:55:24.952887Z","time spent":"476.366664ms","remote":"127.0.0.1:38596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4039,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-07-16T06:55:25.42931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.819028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-07-16T06:55:25.429336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"811.582204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:55:25.429336Z","caller":"traceutil/trace.go:171","msg":"trace[1517104374] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:287; }","duration":"297.880678ms","start":"2026-07-16T06:55:25.131447Z","end":"2026-07-16T06:55:25.429328Z","steps":["trace[1517104374] 'agreement among raft nodes before linearized reading' (duration: 297.825686ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:55:25.429353Z","caller":"traceutil/trace.go:171","msg":"trace[1239691732] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:287; }","duration":"811.616405ms","start":"2026-07-16T06:55:24.617731Z","end":"2026-07-16T06:55:25.429348Z","steps":["trace[1239691732] 'agreement among raft nodes before linearized reading' (duration: 811.588235ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:55:25.42937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:55:24.617717Z","time spent":"811.648726ms","remote":"127.0.0.1:38684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-16T06:55:25.429419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.375715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2920"} {"level":"info","ts":"2026-07-16T06:55:25.429441Z","caller":"traceutil/trace.go:171","msg":"trace[35194276] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:287; }","duration":"335.433897ms","start":"2026-07-16T06:55:25.094Z","end":"2026-07-16T06:55:25.429434Z","steps":["trace[35194276] 'agreement among raft nodes before linearized reading' (duration: 335.398075ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:55:25.429442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"896.467022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-16T06:55:25.429464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:55:25.09398Z","time spent":"335.478748ms","remote":"127.0.0.1:38612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":2943,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"info","ts":"2026-07-16T06:55:25.429483Z","caller":"traceutil/trace.go:171","msg":"trace[1550721421] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:287; }","duration":"896.602016ms","start":"2026-07-16T06:55:24.532868Z","end":"2026-07-16T06:55:25.42947Z","steps":["trace[1550721421] 'agreement among raft nodes before linearized reading' (duration: 896.496323ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:55:25.429526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:55:24.532847Z","time spent":"896.670188ms","remote":"127.0.0.1:38684","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-16T06:55:25.429577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.562864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3466"} {"level":"info","ts":"2026-07-16T06:55:25.429605Z","caller":"traceutil/trace.go:171","msg":"trace[1763355902] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:287; }","duration":"474.647906ms","start":"2026-07-16T06:55:24.954948Z","end":"2026-07-16T06:55:25.429596Z","steps":["trace[1763355902] 'agreement among raft nodes before linearized reading' (duration: 474.601575ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:55:25.429612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.689107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:5892"} {"level":"warn","ts":"2026-07-16T06:55:25.429636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:55:24.95493Z","time spent":"474.698769ms","remote":"127.0.0.1:38612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":3489,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"info","ts":"2026-07-16T06:55:25.429646Z","caller":"traceutil/trace.go:171","msg":"trace[280818931] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:287; }","duration":"474.723299ms","start":"2026-07-16T06:55:24.954916Z","end":"2026-07-16T06:55:25.42964Z","steps":["trace[280818931] 'agreement among raft nodes before linearized reading' (duration: 474.665257ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:55:25.429664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:55:24.954874Z","time spent":"474.785541ms","remote":"127.0.0.1:38612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":5915,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"warn","ts":"2026-07-16T06:55:25.429595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"896.479162ms","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-16T06:55:25.430996Z","caller":"traceutil/trace.go:171","msg":"trace[852241834] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:287; }","duration":"897.89004ms","start":"2026-07-16T06:55:24.533091Z","end":"2026-07-16T06:55:25.430981Z","steps":["trace[852241834] 'agreement among raft nodes before linearized reading' (duration: 896.438311ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:55:25.431044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:55:24.533085Z","time spent":"897.942093ms","remote":"127.0.0.1:38684","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-16T06:55:28.245068Z","caller":"traceutil/trace.go:171","msg":"trace[375352154] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"101.797565ms","start":"2026-07-16T06:55:28.143235Z","end":"2026-07-16T06:55:28.245033Z","steps":["trace[375352154] 'process raft request' (duration: 57.619454ms)","trace[375352154] 'compare' (duration: 43.931365ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:55:57.414654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.304677ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-16T06:55:59.086107Z","caller":"traceutil/trace.go:171","msg":"trace[230662813] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"418.663138ms","start":"2026-07-16T06:55:58.667408Z","end":"2026-07-16T06:55:59.086071Z","steps":["trace[230662813] 'process raft request' (duration: 418.448362ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:55:59.086258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:55:58.667386Z","time spent":"418.808011ms","remote":"127.0.0.1:38476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-16T06:55:59.09552Z","caller":"traceutil/trace.go:171","msg":"trace[524199529] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"411.51764ms","start":"2026-07-16T06:55:58.683973Z","end":"2026-07-16T06:55:59.095491Z","steps":["trace[524199529] 'process raft request' (duration: 411.122169ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:55:59.095851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:55:58.683942Z","time spent":"411.638935ms","remote":"127.0.0.1:38476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":59616,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T06:55:59.095985Z","caller":"traceutil/trace.go:171","msg":"trace[246116294] transaction","detail":"{read_only:false; number_of_response:1; response_revision:584; }","duration":"369.901069ms","start":"2026-07-16T06:55:58.726071Z","end":"2026-07-16T06:55:59.095972Z","steps":["trace[246116294] 'process raft request' (duration: 369.515399ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:55:59.096046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:55:58.72604Z","time spent":"369.973883ms","remote":"127.0.0.1:38476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":97,"response count":0,"response size":150531,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T06:55:59.096345Z","caller":"traceutil/trace.go:171","msg":"trace[1062926095] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"396.984729ms","start":"2026-07-16T06:55:58.699319Z","end":"2026-07-16T06:55:59.096304Z","steps":["trace[1062926095] 'process raft request' (duration: 396.057354ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:55:59.096617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:55:58.699292Z","time spent":"397.151574ms","remote":"127.0.0.1:38476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T06:55:59.552128Z","caller":"traceutil/trace.go:171","msg":"trace[303824507] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"187.589938ms","start":"2026-07-16T06:55:59.364506Z","end":"2026-07-16T06:55:59.552096Z","steps":["trace[303824507] 'process raft request' (duration: 92.610841ms)","trace[303824507] 'compare' (duration: 94.805171ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:55:59.558571Z","caller":"traceutil/trace.go:171","msg":"trace[485314267] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"182.692722ms","start":"2026-07-16T06:55:59.375834Z","end":"2026-07-16T06:55:59.558527Z","steps":["trace[485314267] 'process raft request' (duration: 182.452715ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:55:59.55875Z","caller":"traceutil/trace.go:171","msg":"trace[1911262496] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"169.677922ms","start":"2026-07-16T06:55:59.389046Z","end":"2026-07-16T06:55:59.558723Z","steps":["trace[1911262496] 'process raft request' (duration: 169.408095ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:56:05.478674Z","caller":"traceutil/trace.go:171","msg":"trace[971642608] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"255.064394ms","start":"2026-07-16T06:56:05.223578Z","end":"2026-07-16T06:56:05.478642Z","steps":["trace[971642608] 'process raft request' (duration: 254.787216ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:56:05.667946Z","caller":"traceutil/trace.go:171","msg":"trace[1688644692] linearizableReadLoop","detail":"{readStateIndex:664; appliedIndex:662; }","duration":"228.853989ms","start":"2026-07-16T06:56:05.438969Z","end":"2026-07-16T06:56:05.667823Z","steps":["trace[1688644692] 'read index received' (duration: 39.526774ms)","trace[1688644692] 'applied index is now lower than readState.Index' (duration: 189.323385ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:56:05.668416Z","caller":"traceutil/trace.go:171","msg":"trace[1887357794] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"412.93148ms","start":"2026-07-16T06:56:05.25546Z","end":"2026-07-16T06:56:05.668391Z","steps":["trace[1887357794] 'process raft request' (duration: 409.878226ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:56:05.668576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:56:05.255431Z","time spent":"413.032832ms","remote":"127.0.0.1:38526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036277,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T06:56:05.668902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.930059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:56:05.668955Z","caller":"traceutil/trace.go:171","msg":"trace[873351635] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:644; }","duration":"230.0046ms","start":"2026-07-16T06:56:05.438926Z","end":"2026-07-16T06:56:05.668931Z","steps":["trace[873351635] 'agreement among raft nodes before linearized reading' (duration: 229.926008ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:56:05.990397Z","caller":"traceutil/trace.go:171","msg":"trace[1399567007] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"313.731046ms","start":"2026-07-16T06:56:05.676614Z","end":"2026-07-16T06:56:05.990345Z","steps":["trace[1399567007] 'process raft request' (duration: 310.404924ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:56:05.990987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:56:05.676582Z","time spent":"314.138617ms","remote":"127.0.0.1:38684","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-16T06:56:06.025256Z","caller":"traceutil/trace.go:171","msg":"trace[1147624195] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"348.426145ms","start":"2026-07-16T06:56:05.676736Z","end":"2026-07-16T06:56:06.025162Z","steps":["trace[1147624195] 'process raft request' (duration: 348.164959ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:56:06.025542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:56:05.676712Z","time spent":"348.649571ms","remote":"127.0.0.1:38684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T06:56:06.025614Z","caller":"traceutil/trace.go:171","msg":"trace[177540370] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"348.738804ms","start":"2026-07-16T06:56:05.676818Z","end":"2026-07-16T06:56:06.025556Z","steps":["trace[177540370] 'process raft request' (duration: 348.298322ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:56:06.025676Z","caller":"traceutil/trace.go:171","msg":"trace[306065051] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"346.912953ms","start":"2026-07-16T06:56:05.67874Z","end":"2026-07-16T06:56:06.025653Z","steps":["trace[306065051] 'process raft request' (duration: 346.713098ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:56:06.025836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:56:05.676811Z","time spent":"348.904698ms","remote":"127.0.0.1:38684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T06:56:06.025636Z","caller":"traceutil/trace.go:171","msg":"trace[898980541] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"291.802059ms","start":"2026-07-16T06:56:05.733799Z","end":"2026-07-16T06:56:06.025601Z","steps":["trace[898980541] 'process raft request' (duration: 291.755989ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:56:06.025929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:56:05.678716Z","time spent":"347.035848ms","remote":"127.0.0.1:38684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T06:56:06.94102Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13993703743532596554,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-16T06:56:07.003723Z","caller":"traceutil/trace.go:171","msg":"trace[953744338] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"565.937312ms","start":"2026-07-16T06:56:06.437755Z","end":"2026-07-16T06:56:07.003693Z","steps":["trace[953744338] 'process raft request' (duration: 565.368346ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:56:07.004063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:56:06.437727Z","time spent":"566.070045ms","remote":"127.0.0.1:38526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036297,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T06:56:07.018115Z","caller":"traceutil/trace.go:171","msg":"trace[1288743093] linearizableReadLoop","detail":"{readStateIndex:671; appliedIndex:669; }","duration":"577.623514ms","start":"2026-07-16T06:56:06.440469Z","end":"2026-07-16T06:56:07.018092Z","steps":["trace[1288743093] 'read index received' (duration: 561.91271ms)","trace[1288743093] 'applied index is now lower than readState.Index' (duration: 15.709334ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:56:07.018247Z","caller":"traceutil/trace.go:171","msg":"trace[685510863] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"461.74514ms","start":"2026-07-16T06:56:06.556487Z","end":"2026-07-16T06:56:07.018232Z","steps":["trace[685510863] 'process raft request' (duration: 461.462853ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:56:07.018311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.81967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:56:07.018338Z","caller":"traceutil/trace.go:171","msg":"trace[1426237705] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:651; }","duration":"577.906813ms","start":"2026-07-16T06:56:06.440424Z","end":"2026-07-16T06:56:07.018331Z","steps":["trace[1426237705] 'agreement among raft nodes before linearized reading' (duration: 577.751439ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:56:07.018361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:56:06.440404Z","time spent":"577.952364ms","remote":"127.0.0.1:38444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-16T06:56:07.018359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:56:06.556457Z","time spent":"461.837002ms","remote":"127.0.0.1:38684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T06:56:08.662534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.703682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:56:08.662645Z","caller":"traceutil/trace.go:171","msg":"trace[2001356889] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:672; }","duration":"221.903316ms","start":"2026-07-16T06:56:08.440718Z","end":"2026-07-16T06:56:08.662621Z","steps":["trace[2001356889] 'range keys from in-memory index tree' (duration: 221.602558ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:56:08.66264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.84395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:56:08.66275Z","caller":"traceutil/trace.go:171","msg":"trace[576443702] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:0; response_revision:672; }","duration":"284.027445ms","start":"2026-07-16T06:56:08.378702Z","end":"2026-07-16T06:56:08.662729Z","steps":["trace[576443702] 'range keys from in-memory index tree' (duration: 283.701806ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:56:18.914178Z","caller":"traceutil/trace.go:171","msg":"trace[757159287] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"150.503223ms","start":"2026-07-16T06:56:18.763639Z","end":"2026-07-16T06:56:18.914142Z","steps":["trace[757159287] 'process raft request' (duration: 150.337958ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:56:21.767771Z","caller":"traceutil/trace.go:171","msg":"trace[1045434959] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"453.543824ms","start":"2026-07-16T06:56:21.314186Z","end":"2026-07-16T06:56:21.767729Z","steps":["trace[1045434959] 'process raft request' (duration: 453.297506ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:56:21.767966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:56:21.314166Z","time spent":"453.704757ms","remote":"127.0.0.1:38612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4021,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T06:56:21.78667Z","caller":"traceutil/trace.go:171","msg":"trace[915486375] linearizableReadLoop","detail":"{readStateIndex:834; appliedIndex:832; }","duration":"345.443264ms","start":"2026-07-16T06:56:21.441216Z","end":"2026-07-16T06:56:21.786659Z","steps":["trace[915486375] 'read index received' (duration: 326.203851ms)","trace[915486375] 'applied index is now lower than readState.Index' (duration: 19.238662ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:56:21.786764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.550986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:56:21.786796Z","caller":"traceutil/trace.go:171","msg":"trace[525906369] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:810; }","duration":"345.62944ms","start":"2026-07-16T06:56:21.441151Z","end":"2026-07-16T06:56:21.78678Z","steps":["trace[525906369] 'agreement among raft nodes before linearized reading' (duration: 345.551857ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:56:21.786819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:56:21.441126Z","time spent":"345.6876ms","remote":"127.0.0.1:38454","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-16T06:56:21.786772Z","caller":"traceutil/trace.go:171","msg":"trace[1772641659] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"416.318164ms","start":"2026-07-16T06:56:21.37041Z","end":"2026-07-16T06:56:21.786728Z","steps":["trace[1772641659] 'process raft request' (duration: 416.18182ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:56:21.787033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:56:21.370375Z","time spent":"416.496169ms","remote":"127.0.0.1:38684","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-16T06:56:57.45743Z","caller":"traceutil/trace.go:171","msg":"trace[1533052636] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"273.543366ms","start":"2026-07-16T06:56:57.18385Z","end":"2026-07-16T06:56:57.457393Z","steps":["trace[1533052636] 'process raft request' (duration: 249.450731ms)","trace[1533052636] 'compare' (duration: 23.865939ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:56:57.459075Z","caller":"traceutil/trace.go:171","msg":"trace[961356625] linearizableReadLoop","detail":"{readStateIndex:1061; appliedIndex:1059; }","duration":"109.372594ms","start":"2026-07-16T06:56:57.349677Z","end":"2026-07-16T06:56:57.45905Z","steps":["trace[961356625] 'read index received' (duration: 83.597862ms)","trace[961356625] 'applied index is now lower than readState.Index' (duration: 25.773062ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:56:57.459263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.557631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-16T06:56:57.459346Z","caller":"traceutil/trace.go:171","msg":"trace[759505236] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1026; }","duration":"109.683054ms","start":"2026-07-16T06:56:57.349643Z","end":"2026-07-16T06:56:57.459326Z","steps":["trace[759505236] 'agreement among raft nodes before linearized reading' (duration: 109.522909ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:57:20.593232Z","caller":"traceutil/trace.go:171","msg":"trace[558417868] linearizableReadLoop","detail":"{readStateIndex:1140; appliedIndex:1138; }","duration":"200.736926ms","start":"2026-07-16T06:57:20.392468Z","end":"2026-07-16T06:57:20.593205Z","steps":["trace[558417868] 'read index received' (duration: 83.622474ms)","trace[558417868] 'applied index is now lower than readState.Index' (duration: 117.113162ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:57:20.593329Z","caller":"traceutil/trace.go:171","msg":"trace[454030867] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"203.460122ms","start":"2026-07-16T06:57:20.389845Z","end":"2026-07-16T06:57:20.593305Z","steps":["trace[454030867] 'process raft request' (duration: 187.658509ms)","trace[454030867] 'compare' (duration: 15.34798ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:57:20.593524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.035224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-16T06:57:20.593623Z","caller":"traceutil/trace.go:171","msg":"trace[1230735252] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1101; }","duration":"201.156028ms","start":"2026-07-16T06:57:20.392445Z","end":"2026-07-16T06:57:20.593601Z","steps":["trace[1230735252] 'agreement among raft nodes before linearized reading' (duration: 200.929302ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:57:20.593665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.665458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:57:20.593763Z","caller":"traceutil/trace.go:171","msg":"trace[317790686] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1101; }","duration":"152.816483ms","start":"2026-07-16T06:57:20.440924Z","end":"2026-07-16T06:57:20.59374Z","steps":["trace[317790686] 'agreement among raft nodes before linearized reading' (duration: 152.641709ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:57:20.593849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.254414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-16T06:57:20.593956Z","caller":"traceutil/trace.go:171","msg":"trace[1411486437] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1101; }","duration":"199.41656ms","start":"2026-07-16T06:57:20.394522Z","end":"2026-07-16T06:57:20.593938Z","steps":["trace[1411486437] 'agreement among raft nodes before linearized reading' (duration: 199.017038ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:57:21.74949Z","caller":"traceutil/trace.go:171","msg":"trace[915713586] linearizableReadLoop","detail":"{readStateIndex:1143; appliedIndex:1142; }","duration":"308.322052ms","start":"2026-07-16T06:57:21.441143Z","end":"2026-07-16T06:57:21.749465Z","steps":["trace[915713586] 'read index received' (duration: 308.151156ms)","trace[915713586] 'applied index is now lower than readState.Index' (duration: 169.776µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:57:21.749555Z","caller":"traceutil/trace.go:171","msg":"trace[867606059] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"430.345341ms","start":"2026-07-16T06:57:21.319175Z","end":"2026-07-16T06:57:21.749521Z","steps":["trace[867606059] 'process raft request' (duration: 430.152345ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:57:21.749741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.558708ms","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-16T06:57:21.749833Z","caller":"traceutil/trace.go:171","msg":"trace[1267622636] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1104; }","duration":"308.671271ms","start":"2026-07-16T06:57:21.441137Z","end":"2026-07-16T06:57:21.749809Z","steps":["trace[1267622636] 'agreement among raft nodes before linearized reading' (duration: 308.431894ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:57:21.749803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:57:21.319156Z","time spent":"430.524287ms","remote":"127.0.0.1:38684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T06:57:21.749886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:57:21.441095Z","time spent":"308.780434ms","remote":"127.0.0.1:38754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":53,"response size":30,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-07-16T06:57:21.836394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.698774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:57:21.83642Z","caller":"traceutil/trace.go:171","msg":"trace[962034321] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"302.882159ms","start":"2026-07-16T06:57:21.533505Z","end":"2026-07-16T06:57:21.836387Z","steps":["trace[962034321] 'process raft request' (duration: 240.242823ms)","trace[962034321] 'compare' (duration: 62.41825ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:57:21.836457Z","caller":"traceutil/trace.go:171","msg":"trace[1893212154] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1105; }","duration":"393.785786ms","start":"2026-07-16T06:57:21.442656Z","end":"2026-07-16T06:57:21.836441Z","steps":["trace[1893212154] 'agreement among raft nodes before linearized reading' (duration: 393.670633ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:57:21.836583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:57:21.533486Z","time spent":"303.002382ms","remote":"127.0.0.1:38684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T06:57:21.836566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:57:21.442649Z","time spent":"393.905839ms","remote":"127.0.0.1:38444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-16T06:57:21.83665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.342803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-16T06:57:21.837325Z","caller":"traceutil/trace.go:171","msg":"trace[1175758505] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1105; }","duration":"273.059342ms","start":"2026-07-16T06:57:21.564249Z","end":"2026-07-16T06:57:21.837308Z","steps":["trace[1175758505] 'agreement among raft nodes before linearized reading' (duration: 272.371144ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:57:22.785156Z","caller":"traceutil/trace.go:171","msg":"trace[680660367] transaction","detail":"{read_only:false; response_revision:1110; number_of_response:1; }","duration":"126.527896ms","start":"2026-07-16T06:57:22.658589Z","end":"2026-07-16T06:57:22.785117Z","steps":["trace[680660367] 'process raft request' (duration: 126.431724ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:57:42.052446Z","caller":"traceutil/trace.go:171","msg":"trace[628590517] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"208.475284ms","start":"2026-07-16T06:57:41.843943Z","end":"2026-07-16T06:57:42.052418Z","steps":["trace[628590517] 'process raft request' (duration: 208.327629ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:57:42.053117Z","caller":"traceutil/trace.go:171","msg":"trace[1225473655] linearizableReadLoop","detail":"{readStateIndex:1277; appliedIndex:1275; }","duration":"144.12885ms","start":"2026-07-16T06:57:41.908977Z","end":"2026-07-16T06:57:42.053106Z","steps":["trace[1225473655] 'read index received' (duration: 143.371599ms)","trace[1225473655] 'applied index is now lower than readState.Index' (duration: 756.741µs)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:57:42.053196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.209733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:57:42.053218Z","caller":"traceutil/trace.go:171","msg":"trace[241661332] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1232; }","duration":"144.288175ms","start":"2026-07-16T06:57:41.908923Z","end":"2026-07-16T06:57:42.053211Z","steps":["trace[241661332] 'agreement among raft nodes before linearized reading' (duration: 144.218423ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:57:42.053339Z","caller":"traceutil/trace.go:171","msg":"trace[1804572144] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"150.642453ms","start":"2026-07-16T06:57:41.902676Z","end":"2026-07-16T06:57:42.053318Z","steps":["trace[1804572144] 'process raft request' (duration: 150.380126ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:57:56.117662Z","caller":"traceutil/trace.go:171","msg":"trace[2001782571] linearizableReadLoop","detail":"{readStateIndex:1339; appliedIndex:1338; }","duration":"229.387621ms","start":"2026-07-16T06:57:55.888248Z","end":"2026-07-16T06:57:56.117636Z","steps":["trace[2001782571] 'read index received' (duration: 229.204646ms)","trace[2001782571] 'applied index is now lower than readState.Index' (duration: 181.565µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:57:56.117713Z","caller":"traceutil/trace.go:171","msg":"trace[378701022] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"267.835199ms","start":"2026-07-16T06:57:55.849839Z","end":"2026-07-16T06:57:56.117674Z","steps":["trace[378701022] 'process raft request' (duration: 267.612273ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:57:56.117828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.561566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-16T06:57:56.117872Z","caller":"traceutil/trace.go:171","msg":"trace[1845232555] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1291; }","duration":"229.671198ms","start":"2026-07-16T06:57:55.888191Z","end":"2026-07-16T06:57:56.117862Z","steps":["trace[1845232555] 'agreement among raft nodes before linearized reading' (duration: 229.562245ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:57:56.386716Z","caller":"traceutil/trace.go:171","msg":"trace[82541189] linearizableReadLoop","detail":"{readStateIndex:1340; appliedIndex:1339; }","duration":"268.96414ms","start":"2026-07-16T06:57:56.117728Z","end":"2026-07-16T06:57:56.386693Z","steps":["trace[82541189] 'read index received' (duration: 268.877118ms)","trace[82541189] 'applied index is now lower than readState.Index' (duration: 85.882µs)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:57:56.386889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.719194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:57:56.386948Z","caller":"traceutil/trace.go:171","msg":"trace[35026670] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1292; }","duration":"456.831347ms","start":"2026-07-16T06:57:55.930099Z","end":"2026-07-16T06:57:56.38693Z","steps":["trace[35026670] 'agreement among raft nodes before linearized reading' (duration: 456.690912ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:57:56.387004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.132905ms","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":"warn","ts":"2026-07-16T06:57:56.387004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:57:55.930071Z","time spent":"456.916679ms","remote":"127.0.0.1:43174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-07-16T06:57:56.38707Z","caller":"traceutil/trace.go:171","msg":"trace[1196673777] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1292; }","duration":"279.235038ms","start":"2026-07-16T06:57:56.107819Z","end":"2026-07-16T06:57:56.387054Z","steps":["trace[1196673777] 'agreement among raft nodes before linearized reading' (duration: 278.96565ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:57:56.387106Z","caller":"traceutil/trace.go:171","msg":"trace[633944996] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"283.60266ms","start":"2026-07-16T06:57:56.103166Z","end":"2026-07-16T06:57:56.386768Z","steps":["trace[633944996] 'process raft request' (duration: 283.357213ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:57:56.453098Z","caller":"traceutil/trace.go:171","msg":"trace[1337949420] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"149.151181ms","start":"2026-07-16T06:57:56.303926Z","end":"2026-07-16T06:57:56.453077Z","steps":["trace[1337949420] 'process raft request' (duration: 146.61676ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:57:57.031449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.520832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-16T06:57:57.031548Z","caller":"traceutil/trace.go:171","msg":"trace[51535176] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1297; }","duration":"149.667466ms","start":"2026-07-16T06:57:56.881858Z","end":"2026-07-16T06:57:57.031526Z","steps":["trace[51535176] 'range keys from in-memory index tree' (duration: 149.382527ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:57:57.687418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.860662ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-16T06:57:58.716701Z","caller":"traceutil/trace.go:171","msg":"trace[1237575968] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"242.032975ms","start":"2026-07-16T06:57:58.47464Z","end":"2026-07-16T06:57:58.716673Z","steps":["trace[1237575968] 'process raft request' (duration: 159.972345ms)","trace[1237575968] 'compare' (duration: 81.949527ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:57:58.970359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.378425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-16T06:57:58.97044Z","caller":"traceutil/trace.go:171","msg":"trace[607442288] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1302; }","duration":"135.510349ms","start":"2026-07-16T06:57:58.834913Z","end":"2026-07-16T06:57:58.970423Z","steps":["trace[607442288] 'range keys from in-memory index tree' (duration: 135.184549ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:58:09.25259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.648444ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T06:58:09.253122Z","caller":"traceutil/trace.go:171","msg":"trace[223743266] transaction","detail":"{read_only:false; response_revision:1384; number_of_response:1; }","duration":"409.401487ms","start":"2026-07-16T06:58:08.84368Z","end":"2026-07-16T06:58:09.253082Z","steps":["trace[223743266] 'process raft request' (duration: 161.160918ms)","trace[223743266] 'compare' (duration: 247.477287ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:58:09.253357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:58:08.843657Z","time spent":"409.627634ms","remote":"127.0.0.1:38684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T06:58:09.253499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.640288ms","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-16T06:58:09.253197Z","caller":"traceutil/trace.go:171","msg":"trace[1771926816] linearizableReadLoop","detail":"{readStateIndex:1435; appliedIndex:1434; }","duration":"239.325298ms","start":"2026-07-16T06:58:09.013846Z","end":"2026-07-16T06:58:09.253172Z","steps":["trace[1771926816] 'read index received' (duration: 40.17µs)","trace[1771926816] 'applied index is now lower than readState.Index' (duration: 239.282388ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:58:09.253602Z","caller":"traceutil/trace.go:171","msg":"trace[1918358310] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1384; }","duration":"239.780692ms","start":"2026-07-16T06:58:09.013803Z","end":"2026-07-16T06:58:09.253583Z","steps":["trace[1918358310] 'agreement among raft nodes before linearized reading' (duration: 239.435003ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:58:09.253843Z","caller":"traceutil/trace.go:171","msg":"trace[192167047] transaction","detail":"{read_only:false; response_revision:1385; number_of_response:1; }","duration":"226.760687ms","start":"2026-07-16T06:58:09.027039Z","end":"2026-07-16T06:58:09.2538Z","steps":["trace[192167047] 'process raft request' (duration: 226.608072ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:58:09.253943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.295838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:621"} {"level":"warn","ts":"2026-07-16T06:58:09.253944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.389115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-16T06:58:09.254021Z","caller":"traceutil/trace.go:171","msg":"trace[1639292692] range","detail":"{range_begin:/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1385; }","duration":"239.418542ms","start":"2026-07-16T06:58:09.014584Z","end":"2026-07-16T06:58:09.254003Z","steps":["trace[1639292692] 'agreement among raft nodes before linearized reading' (duration: 239.197306ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:58:09.254024Z","caller":"traceutil/trace.go:171","msg":"trace[1488916262] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1385; }","duration":"156.519258ms","start":"2026-07-16T06:58:09.097493Z","end":"2026-07-16T06:58:09.254012Z","steps":["trace[1488916262] 'agreement among raft nodes before linearized reading' (duration: 156.362933ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:58:09.253985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.016268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-16T06:58:09.254377Z","caller":"traceutil/trace.go:171","msg":"trace[1256237209] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1385; }","duration":"144.492701ms","start":"2026-07-16T06:58:09.109864Z","end":"2026-07-16T06:58:09.254357Z","steps":["trace[1256237209] 'agreement among raft nodes before linearized reading' (duration: 143.943385ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T07:02:23.520942Z","caller":"traceutil/trace.go:171","msg":"trace[1109565] transaction","detail":"{read_only:false; response_revision:2706; number_of_response:1; }","duration":"195.132302ms","start":"2026-07-16T07:02:23.325763Z","end":"2026-07-16T07:02:23.520895Z","steps":["trace[1109565] 'process raft request' (duration: 194.860155ms)"],"step_count":1}