{"level":"warn","ts":"2026-05-01T01:54:53.158916Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-01T01:54:53.159011Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.157: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.157:2380","--initial-cluster=instance=https://199.204.45.157:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.157:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.157: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-05-01T01:54:53.159078Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-01T01:54:53.159092Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.157:2380"]} {"level":"info","ts":"2026-05-01T01:54:53.15911Z","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-05-01T01:54:53.159688Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"]} {"level":"info","ts":"2026-05-01T01:54:53.159757Z","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.157:2380"],"listen-peer-urls":["https://199.204.45.157:2380"],"advertise-client-urls":["https://199.204.45.157:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.157: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-05-01T01:54:53.163585Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.632896ms"} {"level":"info","ts":"2026-05-01T01:54:53.171678Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5f170c75a1d97cc","cluster-id":"44a3176ec85feb9"} {"level":"info","ts":"2026-05-01T01:54:53.171771Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=()"} {"level":"info","ts":"2026-05-01T01:54:53.17181Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became follower at term 0"} {"level":"info","ts":"2026-05-01T01:54:53.171826Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5f170c75a1d97cc [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-01T01:54:53.171842Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became follower at term 1"} {"level":"info","ts":"2026-05-01T01:54:53.17188Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=(428247441089599436)"} {"level":"warn","ts":"2026-05-01T01:54:53.177838Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-01T01:54:53.181315Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-01T01:54:53.185539Z","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-05-01T01:54:53.190808Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5f170c75a1d97cc","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-01T01:54:53.19657Z","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-05-01T01:54:53.197114Z","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-05-01T01:54:53.19721Z","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-05-01T01:54:53.197231Z","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-05-01T01:54:53.19736Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5f170c75a1d97cc","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-01T01:54:53.197458Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.157:2380"} {"level":"info","ts":"2026-05-01T01:54:53.197491Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.157:2380"} {"level":"info","ts":"2026-05-01T01:54:53.197663Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5f170c75a1d97cc","initial-advertise-peer-urls":["https://199.204.45.157:2380"],"listen-peer-urls":["https://199.204.45.157:2380"],"advertise-client-urls":["https://199.204.45.157:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-01T01:54:53.197784Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-01T01:54:53.198339Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=(428247441089599436)"} {"level":"info","ts":"2026-05-01T01:54:53.198436Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"44a3176ec85feb9","local-member-id":"5f170c75a1d97cc","added-peer-id":"5f170c75a1d97cc","added-peer-peer-urls":["https://199.204.45.157:2380"]} {"level":"info","ts":"2026-05-01T01:54:53.473925Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc is starting a new election at term 1"} {"level":"info","ts":"2026-05-01T01:54:53.473981Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became pre-candidate at term 1"} {"level":"info","ts":"2026-05-01T01:54:53.47401Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc received MsgPreVoteResp from 5f170c75a1d97cc at term 1"} {"level":"info","ts":"2026-05-01T01:54:53.474025Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became candidate at term 2"} {"level":"info","ts":"2026-05-01T01:54:53.474037Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc received MsgVoteResp from 5f170c75a1d97cc at term 2"} {"level":"info","ts":"2026-05-01T01:54:53.474049Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became leader at term 2"} {"level":"info","ts":"2026-05-01T01:54:53.474062Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5f170c75a1d97cc elected leader 5f170c75a1d97cc at term 2"} {"level":"info","ts":"2026-05-01T01:54:53.527437Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-01T01:54:53.532707Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5f170c75a1d97cc","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.157:2379]}","request-path":"/0/members/5f170c75a1d97cc/attributes","cluster-id":"44a3176ec85feb9","publish-timeout":"7s"} {"level":"info","ts":"2026-05-01T01:54:53.532822Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-01T01:54:53.53326Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-01T01:54:53.533416Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-01T01:54:53.533431Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-01T01:54:53.537606Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.157:2379"} {"level":"info","ts":"2026-05-01T01:54:53.539128Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"44a3176ec85feb9","local-member-id":"5f170c75a1d97cc","cluster-version":"3.5"} {"level":"info","ts":"2026-05-01T01:54:53.539235Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-01T01:54:53.539259Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-01T01:54:53.540679Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-05-01T01:54:59.6838Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938291185725634215,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-01T01:54:59.889274Z","caller":"traceutil/trace.go:171","msg":"trace[1266595480] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"787.75501ms","start":"2026-05-01T01:54:59.101329Z","end":"2026-05-01T01:54:59.889084Z","steps":["trace[1266595480] 'process raft request' (duration: 740.314845ms)","trace[1266595480] 'compare' (duration: 46.957667ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T01:54:59.889997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:54:59.101305Z","time spent":"788.053891ms","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":192,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-01T01:55:01.676701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.360585198s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:5"} {"level":"info","ts":"2026-05-01T01:55:01.676893Z","caller":"traceutil/trace.go:171","msg":"trace[1868570224] linearizableReadLoop","detail":"{readStateIndex:318; appliedIndex:314; }","duration":"2.493876425s","start":"2026-05-01T01:54:59.183001Z","end":"2026-05-01T01:55:01.676877Z","steps":["trace[1868570224] 'read index received' (duration: 658.714129ms)","trace[1868570224] 'applied index is now lower than readState.Index' (duration: 1.835161456s)"],"step_count":2} {"level":"info","ts":"2026-05-01T01:55:01.676927Z","caller":"traceutil/trace.go:171","msg":"trace[325346482] transaction","detail":"{read_only:false; number_of_response:0; response_revision:306; }","duration":"2.380201774s","start":"2026-05-01T01:54:59.296703Z","end":"2026-05-01T01:55:01.676905Z","steps":["trace[325346482] 'process raft request' (duration: 2.38011204s)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:55:01.677518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.494465486s","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-05-01T01:55:01.677611Z","caller":"traceutil/trace.go:171","msg":"trace[90326008] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:306; }","duration":"2.494591681s","start":"2026-05-01T01:54:59.182989Z","end":"2026-05-01T01:55:01.677581Z","steps":["trace[90326008] 'agreement among raft nodes before linearized reading' (duration: 2.493978368s)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:55:01.677648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:54:59.182921Z","time spent":"2.494716346s","remote":"127.0.0.1:38744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-01T01:55:01.677026Z","caller":"traceutil/trace.go:171","msg":"trace[1535128096] transaction","detail":"{read_only:false; number_of_response:0; response_revision:306; }","duration":"2.3795833s","start":"2026-05-01T01:54:59.297396Z","end":"2026-05-01T01:55:01.67698Z","steps":["trace[1535128096] 'process raft request' (duration: 2.379453006s)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:55:01.67779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:54:59.296691Z","time spent":"2.380300677s","remote":"127.0.0.1:34590","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-01T01:55:01.677879Z","caller":"traceutil/trace.go:171","msg":"trace[1583530768] transaction","detail":"{read_only:false; number_of_response:0; response_revision:306; }","duration":"2.381232493s","start":"2026-05-01T01:54:59.295772Z","end":"2026-05-01T01:55:01.677005Z","steps":["trace[1583530768] 'process raft request' (duration: 1.020103876s)","trace[1583530768] 'compare' (duration: 1.360530965s)"],"step_count":2} {"level":"warn","ts":"2026-05-01T01:55:01.677953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:54:59.297386Z","time spent":"2.380479905s","remote":"127.0.0.1:34590","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-01T01:55:01.677971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:54:59.29576Z","time spent":"2.382167169s","remote":"127.0.0.1:34590","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-01T01:55:01.678237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.382577045s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:5897"} {"level":"info","ts":"2026-05-01T01:55:01.678359Z","caller":"traceutil/trace.go:171","msg":"trace[1706669670] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:306; }","duration":"2.382725931s","start":"2026-05-01T01:54:59.295616Z","end":"2026-05-01T01:55:01.678342Z","steps":["trace[1706669670] 'agreement among raft nodes before linearized reading' (duration: 2.382302025s)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:55:01.678411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:54:59.295597Z","time spent":"2.382802743s","remote":"127.0.0.1:34590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":5919,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"warn","ts":"2026-05-01T01:55:01.678559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.786073489s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-05-01T01:55:01.678559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.671436032s","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-05-01T01:55:01.678611Z","caller":"traceutil/trace.go:171","msg":"trace[780793835] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/disruption-controller; range_end:; response_count:1; response_revision:306; }","duration":"1.786155423s","start":"2026-05-01T01:54:59.892438Z","end":"2026-05-01T01:55:01.678593Z","steps":["trace[780793835] 'agreement among raft nodes before linearized reading' (duration: 1.785897843s)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:55:01.678618Z","caller":"traceutil/trace.go:171","msg":"trace[1783306952] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:306; }","duration":"1.671524016s","start":"2026-05-01T01:55:00.007078Z","end":"2026-05-01T01:55:01.678602Z","steps":["trace[1783306952] 'agreement among raft nodes before linearized reading' (duration: 1.671194594s)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:55:01.678664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:54:59.892422Z","time spent":"1.786229955s","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":229,"request content":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" "} {"level":"warn","ts":"2026-05-01T01:55:01.678691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.232034269s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-01T01:55:01.678697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:55:00.007057Z","time spent":"1.671622669s","remote":"127.0.0.1:38744","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-05-01T01:55:01.678784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.608198ms","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-05-01T01:55:01.678941Z","caller":"traceutil/trace.go:171","msg":"trace[2061966836] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:306; }","duration":"613.68224ms","start":"2026-05-01T01:55:01.065136Z","end":"2026-05-01T01:55:01.678818Z","steps":["trace[2061966836] 'agreement among raft nodes before linearized reading' (duration: 613.480713ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:55:01.678747Z","caller":"traceutil/trace.go:171","msg":"trace[1865223459] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:306; }","duration":"2.232103402s","start":"2026-05-01T01:54:59.446616Z","end":"2026-05-01T01:55:01.678719Z","steps":["trace[1865223459] 'agreement among raft nodes before linearized reading' (duration: 2.231911865s)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:55:01.678994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:55:01.065088Z","time spent":"613.897819ms","remote":"127.0.0.1:38744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-01T01:55:01.679005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:54:59.446593Z","time spent":"2.232402643s","remote":"127.0.0.1:38744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-01T01:55:03.020723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.320658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-01T01:55:03.020808Z","caller":"traceutil/trace.go:171","msg":"trace[829237716] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:333; }","duration":"186.440003ms","start":"2026-05-01T01:55:02.83435Z","end":"2026-05-01T01:55:03.02079Z","steps":["trace[829237716] 'range keys from in-memory index tree' (duration: 186.253566ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:55:03.324679Z","caller":"traceutil/trace.go:171","msg":"trace[1548882411] transaction","detail":"{read_only:false; response_revision:336; number_of_response:1; }","duration":"139.354368ms","start":"2026-05-01T01:55:03.185301Z","end":"2026-05-01T01:55:03.324656Z","steps":["trace[1548882411] 'process raft request' (duration: 139.214763ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:55:04.263325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.826477ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-01T01:55:04.263633Z","caller":"traceutil/trace.go:171","msg":"trace[704179463] linearizableReadLoop","detail":"{readStateIndex:363; appliedIndex:362; }","duration":"278.738512ms","start":"2026-05-01T01:55:03.984877Z","end":"2026-05-01T01:55:04.263615Z","steps":["trace[704179463] 'read index received' (duration: 98.568741ms)","trace[704179463] 'applied index is now lower than readState.Index' (duration: 180.168321ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T01:55:04.263745Z","caller":"traceutil/trace.go:171","msg":"trace[1561175818] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"283.080757ms","start":"2026-05-01T01:55:03.980652Z","end":"2026-05-01T01:55:04.263733Z","steps":["trace[1561175818] 'process raft request' (duration: 102.781332ms)","trace[1561175818] 'compare' (duration: 179.506014ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T01:55:04.263953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.050995ms","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-05-01T01:55:04.264035Z","caller":"traceutil/trace.go:171","msg":"trace[159709594] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:351; }","duration":"279.174869ms","start":"2026-05-01T01:55:03.98484Z","end":"2026-05-01T01:55:04.264015Z","steps":["trace[159709594] 'agreement among raft nodes before linearized reading' (duration: 278.958761ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:55:04.264106Z","caller":"traceutil/trace.go:171","msg":"trace[1848500939] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"278.994252ms","start":"2026-05-01T01:55:03.985098Z","end":"2026-05-01T01:55:04.264093Z","steps":["trace[1848500939] 'process raft request' (duration: 278.793264ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:55:04.264128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.757358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"warn","ts":"2026-05-01T01:55:04.26424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.181199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-05-01T01:55:04.264266Z","caller":"traceutil/trace.go:171","msg":"trace[648216263] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:353; }","duration":"229.886053ms","start":"2026-05-01T01:55:04.034309Z","end":"2026-05-01T01:55:04.264195Z","steps":["trace[648216263] 'agreement among raft nodes before linearized reading' (duration: 229.717666ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:55:04.264293Z","caller":"traceutil/trace.go:171","msg":"trace[1586149552] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:353; }","duration":"129.270192ms","start":"2026-05-01T01:55:04.135011Z","end":"2026-05-01T01:55:04.264281Z","steps":["trace[1586149552] 'agreement among raft nodes before linearized reading' (duration: 129.178659ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:55:04.264143Z","caller":"traceutil/trace.go:171","msg":"trace[1958180561] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"277.90553ms","start":"2026-05-01T01:55:03.986215Z","end":"2026-05-01T01:55:04.264121Z","steps":["trace[1958180561] 'process raft request' (duration: 277.752434ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:55:04.264542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.320007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-05-01T01:55:04.264586Z","caller":"traceutil/trace.go:171","msg":"trace[854835562] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:353; }","duration":"179.430742ms","start":"2026-05-01T01:55:04.085144Z","end":"2026-05-01T01:55:04.264575Z","steps":["trace[854835562] 'agreement among raft nodes before linearized reading' (duration: 179.287267ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:55:04.558523Z","caller":"traceutil/trace.go:171","msg":"trace[2123316346] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"288.862216ms","start":"2026-05-01T01:55:04.269632Z","end":"2026-05-01T01:55:04.558494Z","steps":["trace[2123316346] 'process raft request' (duration: 209.416781ms)","trace[2123316346] 'compare' (duration: 79.105692ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T01:55:04.558642Z","caller":"traceutil/trace.go:171","msg":"trace[1056933159] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"288.867966ms","start":"2026-05-01T01:55:04.269768Z","end":"2026-05-01T01:55:04.558636Z","steps":["trace[1056933159] 'process raft request' (duration: 288.619607ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:55:04.558939Z","caller":"traceutil/trace.go:171","msg":"trace[1327916577] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"288.204082ms","start":"2026-05-01T01:55:04.270727Z","end":"2026-05-01T01:55:04.558932Z","steps":["trace[1327916577] 'process raft request' (duration: 287.717483ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:55:04.5592Z","caller":"traceutil/trace.go:171","msg":"trace[782555613] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"287.789346ms","start":"2026-05-01T01:55:04.271376Z","end":"2026-05-01T01:55:04.559165Z","steps":["trace[782555613] 'process raft request' (duration: 287.435473ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:55:04.559268Z","caller":"traceutil/trace.go:171","msg":"trace[1163917112] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"281.777387ms","start":"2026-05-01T01:55:04.277482Z","end":"2026-05-01T01:55:04.559259Z","steps":["trace[1163917112] 'process raft request' (duration: 281.703214ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:55:04.559279Z","caller":"traceutil/trace.go:171","msg":"trace[611807181] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"281.911592ms","start":"2026-05-01T01:55:04.277346Z","end":"2026-05-01T01:55:04.559258Z","steps":["trace[611807181] 'process raft request' (duration: 281.798608ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:55:04.559337Z","caller":"traceutil/trace.go:171","msg":"trace[22464539] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"286.955923ms","start":"2026-05-01T01:55:04.272307Z","end":"2026-05-01T01:55:04.559263Z","steps":["trace[22464539] 'process raft request' (duration: 286.792567ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:55:04.559553Z","caller":"traceutil/trace.go:171","msg":"trace[414730763] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"281.536087ms","start":"2026-05-01T01:55:04.278Z","end":"2026-05-01T01:55:04.559536Z","steps":["trace[414730763] 'process raft request' (duration: 281.212745ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:55:04.55957Z","caller":"traceutil/trace.go:171","msg":"trace[144351362] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"281.444513ms","start":"2026-05-01T01:55:04.278116Z","end":"2026-05-01T01:55:04.55956Z","steps":["trace[144351362] 'process raft request' (duration: 281.34642ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:55:04.559715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.205945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4017"} {"level":"info","ts":"2026-05-01T01:55:04.559585Z","caller":"traceutil/trace.go:171","msg":"trace[224845732] linearizableReadLoop","detail":"{readStateIndex:370; appliedIndex:365; }","duration":"286.046589ms","start":"2026-05-01T01:55:04.273491Z","end":"2026-05-01T01:55:04.559538Z","steps":["trace[224845732] 'read index received' (duration: 205.565245ms)","trace[224845732] 'applied index is now lower than readState.Index' (duration: 80.480364ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T01:55:04.559747Z","caller":"traceutil/trace.go:171","msg":"trace[888744237] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:362; }","duration":"286.269748ms","start":"2026-05-01T01:55:04.273471Z","end":"2026-05-01T01:55:04.559741Z","steps":["trace[888744237] 'agreement among raft nodes before linearized reading' (duration: 286.196485ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:55:04.55994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.83455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"warn","ts":"2026-05-01T01:55:04.559943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.987135ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T01:55:04.559988Z","caller":"traceutil/trace.go:171","msg":"trace[1472560767] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:362; }","duration":"270.930313ms","start":"2026-05-01T01:55:04.289045Z","end":"2026-05-01T01:55:04.559975Z","steps":["trace[1472560767] 'agreement among raft nodes before linearized reading' (duration: 270.835659ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:55:04.560006Z","caller":"traceutil/trace.go:171","msg":"trace[1169780282] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:362; }","duration":"106.055349ms","start":"2026-05-01T01:55:04.453943Z","end":"2026-05-01T01:55:04.559998Z","steps":["trace[1169780282] 'agreement among raft nodes before linearized reading' (duration: 105.974006ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:55:04.560067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.861766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-05-01T01:55:04.560127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.963356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-05-01T01:55:04.560296Z","caller":"traceutil/trace.go:171","msg":"trace[819053069] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:362; }","duration":"224.172074ms","start":"2026-05-01T01:55:04.336095Z","end":"2026-05-01T01:55:04.560267Z","steps":["trace[819053069] 'agreement among raft nodes before linearized reading' (duration: 223.928975ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:55:04.564012Z","caller":"traceutil/trace.go:171","msg":"trace[1338153848] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:362; }","duration":"174.95496ms","start":"2026-05-01T01:55:04.385167Z","end":"2026-05-01T01:55:04.560122Z","steps":["trace[1338153848] 'agreement among raft nodes before linearized reading' (duration: 174.758792ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:55:05.970049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.526882ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-01T01:55:05.970211Z","caller":"traceutil/trace.go:171","msg":"trace[1217659834] linearizableReadLoop","detail":"{readStateIndex:409; appliedIndex:407; }","duration":"220.446353ms","start":"2026-05-01T01:55:05.749747Z","end":"2026-05-01T01:55:05.970193Z","steps":["trace[1217659834] 'read index received' (duration: 62.741781ms)","trace[1217659834] 'applied index is now lower than readState.Index' (duration: 157.703482ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T01:55:05.970295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.545536ms","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-05-01T01:55:05.970294Z","caller":"traceutil/trace.go:171","msg":"trace[1935175996] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"243.48952ms","start":"2026-05-01T01:55:05.726778Z","end":"2026-05-01T01:55:05.970267Z","steps":["trace[1935175996] 'process raft request' (duration: 243.362926ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:55:05.970327Z","caller":"traceutil/trace.go:171","msg":"trace[265980338] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:396; }","duration":"220.598228ms","start":"2026-05-01T01:55:05.74972Z","end":"2026-05-01T01:55:05.970318Z","steps":["trace[265980338] 'agreement among raft nodes before linearized reading' (duration: 220.527716ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:55:05.970372Z","caller":"traceutil/trace.go:171","msg":"trace[1214584574] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"244.089754ms","start":"2026-05-01T01:55:05.726239Z","end":"2026-05-01T01:55:05.970329Z","steps":["trace[1214584574] 'process raft request' (duration: 101.069743ms)","trace[1214584574] 'compare' (duration: 142.401757ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T01:55:10.153015Z","caller":"traceutil/trace.go:171","msg":"trace[620763613] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"157.13953ms","start":"2026-05-01T01:55:09.995835Z","end":"2026-05-01T01:55:10.152975Z","steps":["trace[620763613] 'process raft request' (duration: 156.892851ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:55:10.242678Z","caller":"traceutil/trace.go:171","msg":"trace[1987931250] linearizableReadLoop","detail":"{readStateIndex:422; appliedIndex:420; }","duration":"206.745792ms","start":"2026-05-01T01:55:10.035907Z","end":"2026-05-01T01:55:10.242653Z","steps":["trace[1987931250] 'read index received' (duration: 116.849808ms)","trace[1987931250] 'applied index is now lower than readState.Index' (duration: 89.894754ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T01:55:10.242807Z","caller":"traceutil/trace.go:171","msg":"trace[2049711083] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"245.32365ms","start":"2026-05-01T01:55:09.99745Z","end":"2026-05-01T01:55:10.242773Z","steps":["trace[2049711083] 'process raft request' (duration: 245.078701ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:55:10.242844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.924219ms","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-05-01T01:55:10.242927Z","caller":"traceutil/trace.go:171","msg":"trace[1992817247] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:408; }","duration":"207.027133ms","start":"2026-05-01T01:55:10.035846Z","end":"2026-05-01T01:55:10.242873Z","steps":["trace[1992817247] 'agreement among raft nodes before linearized reading' (duration: 206.904948ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:55:40.330335Z","caller":"traceutil/trace.go:171","msg":"trace[1990393238] linearizableReadLoop","detail":"{readStateIndex:563; appliedIndex:562; }","duration":"201.134542ms","start":"2026-05-01T01:55:40.129175Z","end":"2026-05-01T01:55:40.33031Z","steps":["trace[1990393238] 'read index received' (duration: 200.935922ms)","trace[1990393238] 'applied index is now lower than readState.Index' (duration: 197.77µs)"],"step_count":2} {"level":"warn","ts":"2026-05-01T01:55:40.330498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.303652ms","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":"info","ts":"2026-05-01T01:55:40.33056Z","caller":"traceutil/trace.go:171","msg":"trace[965635335] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:541; }","duration":"201.449702ms","start":"2026-05-01T01:55:40.129098Z","end":"2026-05-01T01:55:40.330548Z","steps":["trace[965635335] 'agreement among raft nodes before linearized reading' (duration: 201.342052ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:55:40.330395Z","caller":"traceutil/trace.go:171","msg":"trace[1698605829] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"225.67144ms","start":"2026-05-01T01:55:40.104701Z","end":"2026-05-01T01:55:40.330372Z","steps":["trace[1698605829] 'process raft request' (duration: 225.45949ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:55:40.500121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.197478ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-01T01:55:40.500458Z","caller":"traceutil/trace.go:171","msg":"trace[1694565870] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"124.619355ms","start":"2026-05-01T01:55:40.37581Z","end":"2026-05-01T01:55:40.50043Z","steps":["trace[1694565870] 'process raft request' (duration: 18.037117ms)","trace[1694565870] 'compare' (duration: 106.066048ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T01:55:40.500666Z","caller":"traceutil/trace.go:171","msg":"trace[2012614400] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"122.979814ms","start":"2026-05-01T01:55:40.377664Z","end":"2026-05-01T01:55:40.500644Z","steps":["trace[2012614400] 'process raft request' (duration: 122.559964ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:55:40.95803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.795896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T01:55:40.958233Z","caller":"traceutil/trace.go:171","msg":"trace[1112474511] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:544; }","duration":"328.012036ms","start":"2026-05-01T01:55:40.630166Z","end":"2026-05-01T01:55:40.958178Z","steps":["trace[1112474511] 'range keys from in-memory index tree' (duration: 327.715726ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:55:40.95861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:55:40.630149Z","time spent":"328.446276ms","remote":"127.0.0.1:34394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-01T01:55:41.186342Z","caller":"traceutil/trace.go:171","msg":"trace[530835930] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"491.992222ms","start":"2026-05-01T01:55:40.694321Z","end":"2026-05-01T01:55:41.186313Z","steps":["trace[530835930] 'process raft request' (duration: 491.793662ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:55:41.186489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:55:40.694291Z","time spent":"492.124592ms","remote":"127.0.0.1:34432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-01T01:55:41.463063Z","caller":"traceutil/trace.go:171","msg":"trace[442135486] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"765.647017ms","start":"2026-05-01T01:55:40.697389Z","end":"2026-05-01T01:55:41.463036Z","steps":["trace[442135486] 'process raft request' (duration: 765.415426ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:55:41.463357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:55:40.697366Z","time spent":"765.896158ms","remote":"127.0.0.1:34432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-01T01:55:41.463405Z","caller":"traceutil/trace.go:171","msg":"trace[617564995] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"737.629318ms","start":"2026-05-01T01:55:40.725704Z","end":"2026-05-01T01:55:41.463333Z","steps":["trace[617564995] 'process raft request' (duration: 737.341057ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:55:41.463488Z","caller":"traceutil/trace.go:171","msg":"trace[740956664] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"735.760657ms","start":"2026-05-01T01:55:40.727716Z","end":"2026-05-01T01:55:41.463477Z","steps":["trace[740956664] 'process raft request' (duration: 735.426017ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:55:41.463562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:55:40.72771Z","time spent":"735.812187ms","remote":"127.0.0.1:34432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-01T01:55:41.463604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:55:40.725673Z","time spent":"737.839808ms","remote":"127.0.0.1:34432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-01T01:55:41.463591Z","caller":"traceutil/trace.go:171","msg":"trace[1460482410] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"765.883558ms","start":"2026-05-01T01:55:40.697671Z","end":"2026-05-01T01:55:41.463554Z","steps":["trace[1460482410] 'process raft request' (duration: 765.294876ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:55:41.463734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:55:40.69766Z","time spent":"766.021918ms","remote":"127.0.0.1:34432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-01T01:55:41.4638Z","caller":"traceutil/trace.go:171","msg":"trace[370543852] linearizableReadLoop","detail":"{readStateIndex:578; appliedIndex:566; }","duration":"503.980294ms","start":"2026-05-01T01:55:40.9598Z","end":"2026-05-01T01:55:41.46378Z","steps":["trace[370543852] 'read index received' (duration: 226.369467ms)","trace[370543852] 'applied index is now lower than readState.Index' (duration: 277.609877ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T01:55:41.463425Z","caller":"traceutil/trace.go:171","msg":"trace[745011017] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"719.434771ms","start":"2026-05-01T01:55:40.743926Z","end":"2026-05-01T01:55:41.46336Z","steps":["trace[745011017] 'process raft request' (duration: 719.266361ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:55:41.463896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:55:40.743897Z","time spent":"719.962921ms","remote":"127.0.0.1:34432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-01T01:55:41.463889Z","caller":"traceutil/trace.go:171","msg":"trace[1495756076] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"718.506961ms","start":"2026-05-01T01:55:40.745367Z","end":"2026-05-01T01:55:41.463874Z","steps":["trace[1495756076] 'process raft request' (duration: 717.924581ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:55:41.463976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:55:40.745356Z","time spent":"718.584321ms","remote":"127.0.0.1:34432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-01T01:55:41.464054Z","caller":"traceutil/trace.go:171","msg":"trace[1179170543] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"598.941288ms","start":"2026-05-01T01:55:40.865098Z","end":"2026-05-01T01:55:41.464039Z","steps":["trace[1179170543] 'process raft request' (duration: 598.593928ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:55:41.463989Z","caller":"traceutil/trace.go:171","msg":"trace[895072834] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"718.906221ms","start":"2026-05-01T01:55:40.745006Z","end":"2026-05-01T01:55:41.463912Z","steps":["trace[895072834] 'process raft request' (duration: 718.234551ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:55:41.464186Z","caller":"traceutil/trace.go:171","msg":"trace[32316251] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"690.00781ms","start":"2026-05-01T01:55:40.774169Z","end":"2026-05-01T01:55:41.464177Z","steps":["trace[32316251] 'process raft request' (duration: 689.35663ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:55:41.464228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:55:40.774137Z","time spent":"690.0746ms","remote":"127.0.0.1:34432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-01T01:55:41.464175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.359684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T01:55:41.464313Z","caller":"traceutil/trace.go:171","msg":"trace[1144171339] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:556; }","duration":"504.542814ms","start":"2026-05-01T01:55:40.959761Z","end":"2026-05-01T01:55:41.464304Z","steps":["trace[1144171339] 'agreement among raft nodes before linearized reading' (duration: 504.333854ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:55:41.46434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:55:40.959745Z","time spent":"504.587834ms","remote":"127.0.0.1:34388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-01T01:55:41.464125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:55:40.865063Z","time spent":"599.032848ms","remote":"127.0.0.1:34432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-01T01:55:41.464158Z","caller":"traceutil/trace.go:171","msg":"trace[1391156582] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"711.239718ms","start":"2026-05-01T01:55:40.75291Z","end":"2026-05-01T01:55:41.46415Z","steps":["trace[1391156582] 'process raft request' (duration: 710.498788ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:55:41.464475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:55:40.752898Z","time spent":"711.540468ms","remote":"127.0.0.1:34432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-01T01:55:41.464421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:55:40.744998Z","time spent":"719.187841ms","remote":"127.0.0.1:34432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-01T01:55:41.467887Z","caller":"traceutil/trace.go:171","msg":"trace[1042012755] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"722.374574ms","start":"2026-05-01T01:55:40.745475Z","end":"2026-05-01T01:55:41.46785Z","steps":["trace[1042012755] 'process raft request' (duration: 717.870031ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:55:41.467973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:55:40.745468Z","time spent":"722.465654ms","remote":"127.0.0.1:34432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-01T01:55:41.583634Z","caller":"traceutil/trace.go:171","msg":"trace[2070082708] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"391.286339ms","start":"2026-05-01T01:55:41.192296Z","end":"2026-05-01T01:55:41.583583Z","steps":["trace[2070082708] 'process raft request' (duration: 332.533306ms)","trace[2070082708] 'compare' (duration: 58.420021ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T01:55:41.583786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:55:41.19228Z","time spent":"391.433679ms","remote":"127.0.0.1:39038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-01T01:55:41.583944Z","caller":"traceutil/trace.go:171","msg":"trace[199284760] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"101.640291ms","start":"2026-05-01T01:55:41.482285Z","end":"2026-05-01T01:55:41.583925Z","steps":["trace[199284760] 'process raft request' (duration: 101.430461ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:55:41.583979Z","caller":"traceutil/trace.go:171","msg":"trace[1426200983] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"102.660852ms","start":"2026-05-01T01:55:41.481285Z","end":"2026-05-01T01:55:41.583945Z","steps":["trace[1426200983] 'process raft request' (duration: 102.393312ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:55:41.584026Z","caller":"traceutil/trace.go:171","msg":"trace[1478139267] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"391.033158ms","start":"2026-05-01T01:55:41.192974Z","end":"2026-05-01T01:55:41.584007Z","steps":["trace[1478139267] 'process raft request' (duration: 390.524678ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:55:41.584045Z","caller":"traceutil/trace.go:171","msg":"trace[867565941] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"110.407579ms","start":"2026-05-01T01:55:41.473625Z","end":"2026-05-01T01:55:41.584033Z","steps":["trace[867565941] 'process raft request' (duration: 109.982299ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:55:41.584145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:55:41.192967Z","time spent":"391.099728ms","remote":"127.0.0.1:34432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7317,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-01T01:55:41.584349Z","caller":"traceutil/trace.go:171","msg":"trace[1138246197] transaction","detail":"{read_only:false; number_of_response:1; response_revision:558; }","duration":"390.144827ms","start":"2026-05-01T01:55:41.194192Z","end":"2026-05-01T01:55:41.584337Z","steps":["trace[1138246197] 'process raft request' (duration: 389.376417ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:55:41.58442Z","caller":"traceutil/trace.go:171","msg":"trace[1336148050] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"109.930907ms","start":"2026-05-01T01:55:41.474477Z","end":"2026-05-01T01:55:41.584408Z","steps":["trace[1336148050] 'process raft request' (duration: 109.174277ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:55:41.584477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:55:41.194185Z","time spent":"390.191597ms","remote":"127.0.0.1:34432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":7365,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-01T01:55:42.319081Z","caller":"traceutil/trace.go:171","msg":"trace[1746178203] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"229.508604ms","start":"2026-05-01T01:55:42.089546Z","end":"2026-05-01T01:55:42.319054Z","steps":["trace[1746178203] 'process raft request' (duration: 228.398263ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:55:42.337929Z","caller":"traceutil/trace.go:171","msg":"trace[316423208] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"239.985289ms","start":"2026-05-01T01:55:42.097915Z","end":"2026-05-01T01:55:42.3379Z","steps":["trace[316423208] 'process raft request' (duration: 239.246388ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:55:42.338783Z","caller":"traceutil/trace.go:171","msg":"trace[439630995] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"161.812599ms","start":"2026-05-01T01:55:42.176913Z","end":"2026-05-01T01:55:42.338726Z","steps":["trace[439630995] 'process raft request' (duration: 160.886688ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:55:42.979544Z","caller":"traceutil/trace.go:171","msg":"trace[903830547] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"554.608054ms","start":"2026-05-01T01:55:42.424902Z","end":"2026-05-01T01:55:42.97951Z","steps":["trace[903830547] 'process raft request' (duration: 548.379565ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:55:42.9799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:55:42.424877Z","time spent":"554.904564ms","remote":"127.0.0.1:34432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14661,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-01T01:55:42.987614Z","caller":"traceutil/trace.go:171","msg":"trace[79870749] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"550.459578ms","start":"2026-05-01T01:55:42.437132Z","end":"2026-05-01T01:55:42.987591Z","steps":["trace[79870749] 'process raft request' (duration: 549.496326ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:55:42.987792Z","caller":"traceutil/trace.go:171","msg":"trace[1926677288] linearizableReadLoop","detail":"{readStateIndex:643; appliedIndex:641; }","duration":"483.792565ms","start":"2026-05-01T01:55:42.503983Z","end":"2026-05-01T01:55:42.987776Z","steps":["trace[1926677288] 'read index received' (duration: 469.343494ms)","trace[1926677288] 'applied index is now lower than readState.Index' (duration: 14.447781ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T01:55:42.987902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:55:42.437074Z","time spent":"550.593709ms","remote":"127.0.0.1:34432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":74754,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-01T01:55:42.988173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.171965ms","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-05-01T01:55:42.988382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.760424ms","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-05-01T01:55:42.988424Z","caller":"traceutil/trace.go:171","msg":"trace[1251245385] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:616; }","duration":"483.828364ms","start":"2026-05-01T01:55:42.504586Z","end":"2026-05-01T01:55:42.988415Z","steps":["trace[1251245385] 'agreement among raft nodes before linearized reading' (duration: 483.750154ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:55:42.988455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:55:42.504575Z","time spent":"483.873194ms","remote":"127.0.0.1:38744","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-05-01T01:55:42.988579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.929654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T01:55:42.988622Z","caller":"traceutil/trace.go:171","msg":"trace[4239637] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:616; }","duration":"356.016894ms","start":"2026-05-01T01:55:42.632595Z","end":"2026-05-01T01:55:42.988612Z","steps":["trace[4239637] 'agreement among raft nodes before linearized reading' (duration: 355.950044ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:55:42.988647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:55:42.632572Z","time spent":"356.069184ms","remote":"127.0.0.1:34394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-01T01:55:42.988365Z","caller":"traceutil/trace.go:171","msg":"trace[2003722806] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:616; }","duration":"484.408435ms","start":"2026-05-01T01:55:42.503935Z","end":"2026-05-01T01:55:42.988344Z","steps":["trace[2003722806] 'agreement among raft nodes before linearized reading' (duration: 484.059415ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:55:42.988839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:55:42.503916Z","time spent":"484.912466ms","remote":"127.0.0.1:38744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-01T01:55:42.988292Z","caller":"traceutil/trace.go:171","msg":"trace[1897980341] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"452.782931ms","start":"2026-05-01T01:55:42.535496Z","end":"2026-05-01T01:55:42.988279Z","steps":["trace[1897980341] 'process raft request' (duration: 451.95495ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:55:42.988998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:55:42.535475Z","time spent":"453.479482ms","remote":"127.0.0.1:34432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570081,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-01T01:55:49.37897Z","caller":"traceutil/trace.go:171","msg":"trace[2030410538] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"307.875538ms","start":"2026-05-01T01:55:49.071068Z","end":"2026-05-01T01:55:49.378944Z","steps":["trace[2030410538] 'process raft request' (duration: 209.291048ms)","trace[2030410538] 'compare' (duration: 98.272298ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T01:55:49.379112Z","caller":"traceutil/trace.go:171","msg":"trace[609179301] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"129.55605ms","start":"2026-05-01T01:55:49.249528Z","end":"2026-05-01T01:55:49.379084Z","steps":["trace[609179301] 'process raft request' (duration: 129.371529ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:55:49.379171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:55:49.07104Z","time spent":"308.011568ms","remote":"127.0.0.1:38744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-01T01:55:49.814199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.359112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T01:55:49.814456Z","caller":"traceutil/trace.go:171","msg":"trace[1092993025] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:667; }","duration":"181.678275ms","start":"2026-05-01T01:55:49.632745Z","end":"2026-05-01T01:55:49.814423Z","steps":["trace[1092993025] 'range keys from in-memory index tree' (duration: 181.137872ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:55:51.080896Z","caller":"traceutil/trace.go:171","msg":"trace[1548880665] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"667.004337ms","start":"2026-05-01T01:55:50.413868Z","end":"2026-05-01T01:55:51.080873Z","steps":["trace[1548880665] 'process raft request' (duration: 666.861466ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:55:51.08106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:55:50.413852Z","time spent":"667.117328ms","remote":"127.0.0.1:38744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-01T01:55:51.170166Z","caller":"traceutil/trace.go:171","msg":"trace[1321966510] linearizableReadLoop","detail":"{readStateIndex:698; appliedIndex:696; }","duration":"537.202887ms","start":"2026-05-01T01:55:50.632939Z","end":"2026-05-01T01:55:51.170142Z","steps":["trace[1321966510] 'read index received' (duration: 447.780649ms)","trace[1321966510] 'applied index is now lower than readState.Index' (duration: 89.421268ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T01:55:51.170284Z","caller":"traceutil/trace.go:171","msg":"trace[81952961] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"754.194242ms","start":"2026-05-01T01:55:50.416063Z","end":"2026-05-01T01:55:51.170258Z","steps":["trace[81952961] 'process raft request' (duration: 706.967819ms)","trace[81952961] 'compare' (duration: 47.006702ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T01:55:51.170321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.360517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T01:55:51.170359Z","caller":"traceutil/trace.go:171","msg":"trace[1203136905] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:669; }","duration":"537.450179ms","start":"2026-05-01T01:55:50.632896Z","end":"2026-05-01T01:55:51.170346Z","steps":["trace[1203136905] 'agreement among raft nodes before linearized reading' (duration: 537.369428ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:55:51.170381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:55:50.416047Z","time spent":"754.287354ms","remote":"127.0.0.1:38744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-01T01:55:51.170401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:55:50.632875Z","time spent":"537.518009ms","remote":"127.0.0.1:34394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-01T01:55:51.171039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.491976ms","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-05-01T01:55:51.171093Z","caller":"traceutil/trace.go:171","msg":"trace[1306953664] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:669; }","duration":"101.594987ms","start":"2026-05-01T01:55:51.069483Z","end":"2026-05-01T01:55:51.171078Z","steps":["trace[1306953664] 'agreement among raft nodes before linearized reading' (duration: 101.437666ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:55:52.860291Z","caller":"traceutil/trace.go:171","msg":"trace[1391067008] linearizableReadLoop","detail":"{readStateIndex:731; appliedIndex:730; }","duration":"228.327972ms","start":"2026-05-01T01:55:52.631937Z","end":"2026-05-01T01:55:52.860265Z","steps":["trace[1391067008] 'read index received' (duration: 228.089771ms)","trace[1391067008] 'applied index is now lower than readState.Index' (duration: 237.371µs)"],"step_count":2} {"level":"info","ts":"2026-05-01T01:55:52.860432Z","caller":"traceutil/trace.go:171","msg":"trace[465516610] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"320.729648ms","start":"2026-05-01T01:55:52.539665Z","end":"2026-05-01T01:55:52.860395Z","steps":["trace[465516610] 'process raft request' (duration: 320.465146ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:55:52.860561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:55:52.539645Z","time spent":"320.849088ms","remote":"127.0.0.1:34432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5562,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-01T01:55:52.860455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.510333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T01:55:52.860621Z","caller":"traceutil/trace.go:171","msg":"trace[1311474634] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:701; }","duration":"228.715805ms","start":"2026-05-01T01:55:52.631882Z","end":"2026-05-01T01:55:52.860598Z","steps":["trace[1311474634] 'agreement among raft nodes before linearized reading' (duration: 228.514214ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:55:52.860715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.093445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T01:55:52.860802Z","caller":"traceutil/trace.go:171","msg":"trace[1635542444] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:701; }","duration":"134.216726ms","start":"2026-05-01T01:55:52.726569Z","end":"2026-05-01T01:55:52.860786Z","steps":["trace[1635542444] 'agreement among raft nodes before linearized reading' (duration: 133.975145ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:55:57.621594Z","caller":"traceutil/trace.go:171","msg":"trace[1023420257] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"122.630226ms","start":"2026-05-01T01:55:57.49894Z","end":"2026-05-01T01:55:57.621571Z","steps":["trace[1023420257] 'process raft request' (duration: 119.828773ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:56:02.610748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.786946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-9wncr\" ","response":"range_response_count:1 size:4741"} {"level":"info","ts":"2026-05-01T01:56:02.61084Z","caller":"traceutil/trace.go:171","msg":"trace[1671785338] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-9wncr; range_end:; response_count:1; response_revision:797; }","duration":"108.930527ms","start":"2026-05-01T01:56:02.50189Z","end":"2026-05-01T01:56:02.610821Z","steps":["trace[1671785338] 'range keys from in-memory index tree' (duration: 108.554003ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:56:02.869523Z","caller":"traceutil/trace.go:171","msg":"trace[2001230605] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"248.491071ms","start":"2026-05-01T01:56:02.621006Z","end":"2026-05-01T01:56:02.869497Z","steps":["trace[2001230605] 'process raft request' (duration: 248.317839ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:56:03.03869Z","caller":"traceutil/trace.go:171","msg":"trace[1565335122] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"163.996538ms","start":"2026-05-01T01:56:02.874661Z","end":"2026-05-01T01:56:03.038657Z","steps":["trace[1565335122] 'process raft request' (duration: 163.583584ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:56:03.040187Z","caller":"traceutil/trace.go:171","msg":"trace[1104185492] linearizableReadLoop","detail":"{readStateIndex:834; appliedIndex:832; }","duration":"109.780589ms","start":"2026-05-01T01:56:02.93038Z","end":"2026-05-01T01:56:03.040161Z","steps":["trace[1104185492] 'read index received' (duration: 107.877668ms)","trace[1104185492] 'applied index is now lower than readState.Index' (duration: 1.901971ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T01:56:03.040371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.962781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-01T01:56:03.040436Z","caller":"traceutil/trace.go:171","msg":"trace[168634276] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:800; }","duration":"110.083662ms","start":"2026-05-01T01:56:02.93034Z","end":"2026-05-01T01:56:03.040424Z","steps":["trace[168634276] 'agreement among raft nodes before linearized reading' (duration: 109.90564ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:56:03.04069Z","caller":"traceutil/trace.go:171","msg":"trace[1619720909] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"165.659677ms","start":"2026-05-01T01:56:02.87501Z","end":"2026-05-01T01:56:03.040669Z","steps":["trace[1619720909] 'process raft request' (duration: 165.042921ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:56:03.947109Z","caller":"traceutil/trace.go:171","msg":"trace[472388334] linearizableReadLoop","detail":"{readStateIndex:838; appliedIndex:837; }","duration":"313.683844ms","start":"2026-05-01T01:56:03.633391Z","end":"2026-05-01T01:56:03.947075Z","steps":["trace[472388334] 'read index received' (duration: 313.472912ms)","trace[472388334] 'applied index is now lower than readState.Index' (duration: 210.002µs)"],"step_count":2} {"level":"warn","ts":"2026-05-01T01:56:03.947336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.929536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T01:56:03.947355Z","caller":"traceutil/trace.go:171","msg":"trace[1260532236] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"473.032473ms","start":"2026-05-01T01:56:03.474269Z","end":"2026-05-01T01:56:03.947301Z","steps":["trace[1260532236] 'process raft request' (duration: 472.652609ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:56:03.947447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.854396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T01:56:03.947517Z","caller":"traceutil/trace.go:171","msg":"trace[1375400789] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:804; }","duration":"259.962048ms","start":"2026-05-01T01:56:03.687538Z","end":"2026-05-01T01:56:03.9475Z","steps":["trace[1375400789] 'agreement among raft nodes before linearized reading' (duration: 259.840757ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:56:03.947585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.65624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T01:56:03.947668Z","caller":"traceutil/trace.go:171","msg":"trace[315120200] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:804; }","duration":"113.811801ms","start":"2026-05-01T01:56:03.833837Z","end":"2026-05-01T01:56:03.947649Z","steps":["trace[315120200] 'agreement among raft nodes before linearized reading' (duration: 113.626329ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:56:03.947713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.412083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-01T01:56:03.947675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.898352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-01T01:56:03.94771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.076598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T01:56:03.947742Z","caller":"traceutil/trace.go:171","msg":"trace[1789222451] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:804; }","duration":"136.474084ms","start":"2026-05-01T01:56:03.811261Z","end":"2026-05-01T01:56:03.947735Z","steps":["trace[1789222451] 'agreement among raft nodes before linearized reading' (duration: 136.422383ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:56:03.947769Z","caller":"traceutil/trace.go:171","msg":"trace[1472257170] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:804; }","duration":"134.157489ms","start":"2026-05-01T01:56:03.813596Z","end":"2026-05-01T01:56:03.947753Z","steps":["trace[1472257170] 'agreement among raft nodes before linearized reading' (duration: 134.076768ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:56:03.947787Z","caller":"traceutil/trace.go:171","msg":"trace[747355210] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:804; }","duration":"113.995903ms","start":"2026-05-01T01:56:03.833752Z","end":"2026-05-01T01:56:03.947748Z","steps":["trace[747355210] 'agreement among raft nodes before linearized reading' (duration: 113.894832ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:56:03.947873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.046219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T01:56:03.947924Z","caller":"traceutil/trace.go:171","msg":"trace[1929202203] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:804; }","duration":"260.13765ms","start":"2026-05-01T01:56:03.687775Z","end":"2026-05-01T01:56:03.947913Z","steps":["trace[1929202203] 'agreement among raft nodes before linearized reading' (duration: 260.072239ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:56:03.948028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.314115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T01:56:03.948074Z","caller":"traceutil/trace.go:171","msg":"trace[680258544] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:804; }","duration":"113.374585ms","start":"2026-05-01T01:56:03.834686Z","end":"2026-05-01T01:56:03.948061Z","steps":["trace[680258544] 'agreement among raft nodes before linearized reading' (duration: 113.310644ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:56:03.947574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.225981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T01:56:03.948156Z","caller":"traceutil/trace.go:171","msg":"trace[1517735447] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:804; }","duration":"136.817728ms","start":"2026-05-01T01:56:03.811314Z","end":"2026-05-01T01:56:03.948131Z","steps":["trace[1517735447] 'agreement among raft nodes before linearized reading' (duration: 136.182381ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:56:03.948336Z","caller":"traceutil/trace.go:171","msg":"trace[2034192543] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:804; }","duration":"314.046177ms","start":"2026-05-01T01:56:03.633356Z","end":"2026-05-01T01:56:03.947402Z","steps":["trace[2034192543] 'agreement among raft nodes before linearized reading' (duration: 313.931276ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:56:03.948438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:56:03.63334Z","time spent":"315.082458ms","remote":"127.0.0.1:34394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-01T01:56:03.948499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:56:03.474244Z","time spent":"473.189065ms","remote":"127.0.0.1:38744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-01T01:56:07.296392Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938291185725635320,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-01T01:56:07.797408Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938291185725635320,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-01T01:56:07.856382Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.090008285s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-01T01:56:07.856898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:56:06.766221Z","time spent":"1.090672933s","remote":"127.0.0.1:34422","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-01T01:56:07.86369Z","caller":"traceutil/trace.go:171","msg":"trace[1299278120] linearizableReadLoop","detail":"{readStateIndex:856; appliedIndex:854; }","duration":"1.067670954s","start":"2026-05-01T01:56:06.796004Z","end":"2026-05-01T01:56:07.863675Z","steps":["trace[1299278120] 'read index received' (duration: 1.060711749s)","trace[1299278120] 'applied index is now lower than readState.Index' (duration: 6.958365ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T01:56:07.863765Z","caller":"traceutil/trace.go:171","msg":"trace[91822414] transaction","detail":"{read_only:false; response_revision:821; number_of_response:1; }","duration":"327.447866ms","start":"2026-05-01T01:56:07.536292Z","end":"2026-05-01T01:56:07.86374Z","steps":["trace[91822414] 'process raft request' (duration: 327.274924ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:56:07.863887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.067856806s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-01T01:56:07.863978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:56:07.536273Z","time spent":"327.622269ms","remote":"127.0.0.1:34642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-01T01:56:07.864006Z","caller":"traceutil/trace.go:171","msg":"trace[119928542] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:821; }","duration":"1.068017768s","start":"2026-05-01T01:56:06.795979Z","end":"2026-05-01T01:56:07.863997Z","steps":["trace[119928542] 'agreement among raft nodes before linearized reading' (duration: 1.067787035s)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:56:07.864185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:56:06.795963Z","time spent":"1.06820808s","remote":"127.0.0.1:34498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":9,"response size":29,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-05-01T01:56:07.866409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.007535554s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-01T01:56:07.866452Z","caller":"traceutil/trace.go:171","msg":"trace[1814293503] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:822; }","duration":"1.007606235s","start":"2026-05-01T01:56:06.858835Z","end":"2026-05-01T01:56:07.866442Z","steps":["trace[1814293503] 'agreement among raft nodes before linearized reading' (duration: 1.007511854s)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:56:07.866485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:56:06.858822Z","time spent":"1.007651795s","remote":"127.0.0.1:38842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":29,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-01T01:56:07.866509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.919393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-9wncr\" ","response":"range_response_count:1 size:5047"} {"level":"warn","ts":"2026-05-01T01:56:07.86678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"711.585173ms","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-05-01T01:56:07.866823Z","caller":"traceutil/trace.go:171","msg":"trace[622978031] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:822; }","duration":"711.673164ms","start":"2026-05-01T01:56:07.15514Z","end":"2026-05-01T01:56:07.866813Z","steps":["trace[622978031] 'agreement among raft nodes before linearized reading' (duration: 711.562413ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:56:07.866857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:56:07.155116Z","time spent":"711.733165ms","remote":"127.0.0.1:38744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-01T01:56:07.866791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.611599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-01T01:56:07.86691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.825569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T01:56:07.866991Z","caller":"traceutil/trace.go:171","msg":"trace[704988356] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:822; }","duration":"234.95428ms","start":"2026-05-01T01:56:07.632019Z","end":"2026-05-01T01:56:07.866973Z","steps":["trace[704988356] 'agreement among raft nodes before linearized reading' (duration: 234.811489ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:56:07.866983Z","caller":"traceutil/trace.go:171","msg":"trace[1405317403] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:822; }","duration":"603.826691ms","start":"2026-05-01T01:56:07.263139Z","end":"2026-05-01T01:56:07.866966Z","steps":["trace[1405317403] 'agreement among raft nodes before linearized reading' (duration: 603.546248ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:56:07.867053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:56:07.263111Z","time spent":"603.929293ms","remote":"127.0.0.1:38744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-01T01:56:07.867066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.793249ms","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-05-01T01:56:07.867181Z","caller":"traceutil/trace.go:171","msg":"trace[754028894] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:822; }","duration":"616.939621ms","start":"2026-05-01T01:56:07.25023Z","end":"2026-05-01T01:56:07.86717Z","steps":["trace[754028894] 'agreement among raft nodes before linearized reading' (duration: 616.634347ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:56:07.867241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:56:07.250212Z","time spent":"617.019311ms","remote":"127.0.0.1:38744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-01T01:56:07.866562Z","caller":"traceutil/trace.go:171","msg":"trace[224864838] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-9wncr; range_end:; response_count:1; response_revision:822; }","duration":"388.008594ms","start":"2026-05-01T01:56:07.47854Z","end":"2026-05-01T01:56:07.866549Z","steps":["trace[224864838] 'agreement among raft nodes before linearized reading' (duration: 387.821092ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:56:07.867307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T01:56:07.47852Z","time spent":"388.777813ms","remote":"127.0.0.1:34590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":5069,"request content":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-9wncr\" "} {"level":"warn","ts":"2026-05-01T01:56:07.869582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.136008ms","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-05-01T01:56:07.869655Z","caller":"traceutil/trace.go:171","msg":"trace[981320188] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:822; }","duration":"150.24906ms","start":"2026-05-01T01:56:07.719388Z","end":"2026-05-01T01:56:07.869637Z","steps":["trace[981320188] 'agreement among raft nodes before linearized reading' (duration: 147.392265ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:58:14.782653Z","caller":"traceutil/trace.go:171","msg":"trace[1326940959] transaction","detail":"{read_only:false; response_revision:1422; number_of_response:1; }","duration":"162.568894ms","start":"2026-05-01T01:58:14.620004Z","end":"2026-05-01T01:58:14.782573Z","steps":["trace[1326940959] 'process raft request' (duration: 162.335417ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:58:14.782657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.942001ms","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-05-01T01:58:14.782723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.761923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/kube-public/\" range_end:\"/registry/persistentvolumeclaims/kube-public0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-01T01:58:14.782755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.65132ms","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-05-01T01:58:14.782775Z","caller":"traceutil/trace.go:171","msg":"trace[1547545255] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1422; }","duration":"146.084655ms","start":"2026-05-01T01:58:14.636677Z","end":"2026-05-01T01:58:14.782762Z","steps":["trace[1547545255] 'agreement among raft nodes before linearized reading' (duration: 145.840097ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:58:14.782789Z","caller":"traceutil/trace.go:171","msg":"trace[1557978353] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1422; }","duration":"129.70014ms","start":"2026-05-01T01:58:14.653077Z","end":"2026-05-01T01:58:14.782777Z","steps":["trace[1557978353] 'agreement among raft nodes before linearized reading' (duration: 129.601447ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:58:14.782789Z","caller":"traceutil/trace.go:171","msg":"trace[723407497] range","detail":"{range_begin:/registry/persistentvolumeclaims/kube-public/; range_end:/registry/persistentvolumeclaims/kube-public0; response_count:0; response_revision:1422; }","duration":"157.827105ms","start":"2026-05-01T01:58:14.624945Z","end":"2026-05-01T01:58:14.782772Z","steps":["trace[723407497] 'agreement among raft nodes before linearized reading' (duration: 157.718652ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:58:14.783061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.627252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T01:58:14.783088Z","caller":"traceutil/trace.go:171","msg":"trace[1327867357] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1422; }","duration":"126.112467ms","start":"2026-05-01T01:58:14.656967Z","end":"2026-05-01T01:58:14.78308Z","steps":["trace[1327867357] 'agreement among raft nodes before linearized reading' (duration: 125.587651ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:58:15.650028Z","caller":"traceutil/trace.go:171","msg":"trace[143882177] linearizableReadLoop","detail":"{readStateIndex:1489; appliedIndex:1488; }","duration":"192.784683ms","start":"2026-05-01T01:58:15.457217Z","end":"2026-05-01T01:58:15.650002Z","steps":["trace[143882177] 'read index received' (duration: 192.646079ms)","trace[143882177] 'applied index is now lower than readState.Index' (duration: 135.314µs)"],"step_count":2} {"level":"info","ts":"2026-05-01T01:58:15.65007Z","caller":"traceutil/trace.go:171","msg":"trace[1938269078] transaction","detail":"{read_only:false; response_revision:1426; number_of_response:1; }","duration":"194.208038ms","start":"2026-05-01T01:58:15.455835Z","end":"2026-05-01T01:58:15.650043Z","steps":["trace[1938269078] 'process raft request' (duration: 194.025933ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:58:15.65023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.152334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T01:58:15.650277Z","caller":"traceutil/trace.go:171","msg":"trace[46902730] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1426; }","duration":"186.281958ms","start":"2026-05-01T01:58:15.463982Z","end":"2026-05-01T01:58:15.650264Z","steps":["trace[46902730] 'agreement among raft nodes before linearized reading' (duration: 186.159575ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:58:15.650331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.046728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/local-path-storage/\" range_end:\"/registry/cronjobs/local-path-storage0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-01T01:58:15.650336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.077942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/local-path-storage/\" range_end:\"/registry/configmaps/local-path-storage0\" limit:500 ","response":"range_response_count:2 size:3217"} {"level":"info","ts":"2026-05-01T01:58:15.650393Z","caller":"traceutil/trace.go:171","msg":"trace[1038574775] range","detail":"{range_begin:/registry/cronjobs/local-path-storage/; range_end:/registry/cronjobs/local-path-storage0; response_count:0; response_revision:1426; }","duration":"151.161481ms","start":"2026-05-01T01:58:15.499217Z","end":"2026-05-01T01:58:15.650379Z","steps":["trace[1038574775] 'agreement among raft nodes before linearized reading' (duration: 151.023347ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:58:15.650428Z","caller":"traceutil/trace.go:171","msg":"trace[1757917476] range","detail":"{range_begin:/registry/configmaps/local-path-storage/; range_end:/registry/configmaps/local-path-storage0; response_count:2; response_revision:1426; }","duration":"193.201276ms","start":"2026-05-01T01:58:15.457206Z","end":"2026-05-01T01:58:15.650407Z","steps":["trace[1757917476] 'agreement among raft nodes before linearized reading' (duration: 192.914677ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T01:58:15.650505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.252237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-public/system:controller:bootstrap-signer\" ","response":"range_response_count:1 size:741"} {"level":"info","ts":"2026-05-01T01:58:15.650537Z","caller":"traceutil/trace.go:171","msg":"trace[1715144620] range","detail":"{range_begin:/registry/rolebindings/kube-public/system:controller:bootstrap-signer; range_end:; response_count:1; response_revision:1426; }","duration":"178.300858ms","start":"2026-05-01T01:58:15.472226Z","end":"2026-05-01T01:58:15.650527Z","steps":["trace[1715144620] 'agreement among raft nodes before linearized reading' (duration: 178.235976ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:58:26.998955Z","caller":"traceutil/trace.go:171","msg":"trace[365776301] transaction","detail":"{read_only:false; response_revision:1463; number_of_response:1; }","duration":"123.410048ms","start":"2026-05-01T01:58:26.875519Z","end":"2026-05-01T01:58:26.99893Z","steps":["trace[365776301] 'process raft request' (duration: 123.325084ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:58:26.998958Z","caller":"traceutil/trace.go:171","msg":"trace[387852928] transaction","detail":"{read_only:false; response_revision:1462; number_of_response:1; }","duration":"123.749468ms","start":"2026-05-01T01:58:26.875171Z","end":"2026-05-01T01:58:26.99892Z","steps":["trace[387852928] 'process raft request' (duration: 66.955931ms)","trace[387852928] 'compare' (duration: 56.614201ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T01:58:26.999277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.633761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-2srjd\" ","response":"range_response_count:1 size:18525"} {"level":"info","ts":"2026-05-01T01:58:26.999326Z","caller":"traceutil/trace.go:171","msg":"trace[644489948] range","detail":"{range_begin:/registry/pods/kube-system/cilium-2srjd; range_end:; response_count:1; response_revision:1464; }","duration":"121.752874ms","start":"2026-05-01T01:58:26.877558Z","end":"2026-05-01T01:58:26.999311Z","steps":["trace[644489948] 'agreement among raft nodes before linearized reading' (duration: 121.571778ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T01:58:26.999108Z","caller":"traceutil/trace.go:171","msg":"trace[1677849172] linearizableReadLoop","detail":"{readStateIndex:1530; appliedIndex:1526; }","duration":"121.480586ms","start":"2026-05-01T01:58:26.877614Z","end":"2026-05-01T01:58:26.999094Z","steps":["trace[1677849172] 'read index received' (duration: 27.198521ms)","trace[1677849172] 'applied index is now lower than readState.Index' (duration: 94.280025ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T01:58:26.999213Z","caller":"traceutil/trace.go:171","msg":"trace[1724599868] transaction","detail":"{read_only:false; response_revision:1464; number_of_response:1; }","duration":"123.354054ms","start":"2026-05-01T01:58:26.875816Z","end":"2026-05-01T01:58:26.999171Z","steps":["trace[1724599868] 'process raft request' (duration: 123.071184ms)"],"step_count":1}