{"level":"warn","ts":"2026-02-05T17:34:30.69201Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-05T17:34:30.692132Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.3: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.3:2380","--initial-cluster=instance=https://199.204.45.3:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.3:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.3: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-02-05T17:34:30.692224Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-05T17:34:30.692238Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.3:2380"]} {"level":"info","ts":"2026-02-05T17:34:30.692259Z","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-02-05T17:34:30.694392Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"]} {"level":"info","ts":"2026-02-05T17:34:30.694469Z","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":2,"max-cpu-available":2,"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.3:2380"],"listen-peer-urls":["https://199.204.45.3:2380"],"advertise-client-urls":["https://199.204.45.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.3: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-02-05T17:34:30.823141Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"128.469037ms"} {"level":"info","ts":"2026-02-05T17:34:30.919324Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"597401b06ce1cf3d","cluster-id":"dfc54657ad8a2432"} {"level":"info","ts":"2026-02-05T17:34:30.919549Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=()"} {"level":"info","ts":"2026-02-05T17:34:30.919572Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became follower at term 0"} {"level":"info","ts":"2026-02-05T17:34:30.919664Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 597401b06ce1cf3d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-05T17:34:30.919718Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became follower at term 1"} {"level":"info","ts":"2026-02-05T17:34:30.919811Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=(6445778823926632253)"} {"level":"warn","ts":"2026-02-05T17:34:31.173525Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-05T17:34:31.217643Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-05T17:34:31.261215Z","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-02-05T17:34:31.401128Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"597401b06ce1cf3d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-05T17:34:31.401716Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"597401b06ce1cf3d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-05T17:34:31.402023Z","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-02-05T17:34:31.402096Z","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-02-05T17:34:31.402113Z","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-02-05T17:34:31.409196Z","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-02-05T17:34:31.40946Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"597401b06ce1cf3d","initial-advertise-peer-urls":["https://199.204.45.3:2380"],"listen-peer-urls":["https://199.204.45.3:2380"],"advertise-client-urls":["https://199.204.45.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-05T17:34:31.409487Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-05T17:34:31.412964Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.3:2380"} {"level":"info","ts":"2026-02-05T17:34:31.412982Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.3:2380"} {"level":"info","ts":"2026-02-05T17:34:31.43342Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=(6445778823926632253)"} {"level":"info","ts":"2026-02-05T17:34:31.433579Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"dfc54657ad8a2432","local-member-id":"597401b06ce1cf3d","added-peer-id":"597401b06ce1cf3d","added-peer-peer-urls":["https://199.204.45.3:2380"]} {"level":"info","ts":"2026-02-05T17:34:31.920658Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d is starting a new election at term 1"} {"level":"info","ts":"2026-02-05T17:34:31.92071Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became pre-candidate at term 1"} {"level":"info","ts":"2026-02-05T17:34:31.920818Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d received MsgPreVoteResp from 597401b06ce1cf3d at term 1"} {"level":"info","ts":"2026-02-05T17:34:31.920841Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became candidate at term 2"} {"level":"info","ts":"2026-02-05T17:34:31.920849Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d received MsgVoteResp from 597401b06ce1cf3d at term 2"} {"level":"info","ts":"2026-02-05T17:34:31.920859Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became leader at term 2"} {"level":"info","ts":"2026-02-05T17:34:31.920869Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 597401b06ce1cf3d elected leader 597401b06ce1cf3d at term 2"} {"level":"info","ts":"2026-02-05T17:34:31.925334Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"597401b06ce1cf3d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.3:2379]}","request-path":"/0/members/597401b06ce1cf3d/attributes","cluster-id":"dfc54657ad8a2432","publish-timeout":"7s"} {"level":"info","ts":"2026-02-05T17:34:31.925344Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-05T17:34:31.926756Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T17:34:31.933996Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-05T17:34:31.934039Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-05T17:34:31.925504Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-05T17:34:31.936064Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.3:2379"} {"level":"info","ts":"2026-02-05T17:34:31.936358Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-05T17:34:31.977091Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"dfc54657ad8a2432","local-member-id":"597401b06ce1cf3d","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T17:34:31.977342Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T17:34:31.977421Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T17:34:33.891279Z","caller":"traceutil/trace.go:171","msg":"trace[1555047944] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"369.029468ms","start":"2026-02-05T17:34:33.522226Z","end":"2026-02-05T17:34:33.891255Z","steps":["trace[1555047944] 'process raft request' (duration: 368.324587ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:33.892351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:34:33.522216Z","time spent":"369.248385ms","remote":"127.0.0.1:55802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":100,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:34:33.893276Z","caller":"traceutil/trace.go:171","msg":"trace[37199680] linearizableReadLoop","detail":"{readStateIndex:5; appliedIndex:4; }","duration":"368.545594ms","start":"2026-02-05T17:34:33.522617Z","end":"2026-02-05T17:34:33.891162Z","steps":["trace[37199680] 'read index received' (duration: 368.143663ms)","trace[37199680] 'applied index is now lower than readState.Index' (duration: 400.401µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:34:33.893482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.853739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-05T17:34:33.893637Z","caller":"traceutil/trace.go:171","msg":"trace[1536382828] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:0; response_revision:2; }","duration":"371.011974ms","start":"2026-02-05T17:34:33.52258Z","end":"2026-02-05T17:34:33.893592Z","steps":["trace[1536382828] 'agreement among raft nodes before linearized reading' (duration: 370.769507ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:33.89373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:34:33.522564Z","time spent":"371.115736ms","remote":"127.0.0.1:55928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":0,"response size":27,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-02-05T17:34:34.15165Z","caller":"traceutil/trace.go:171","msg":"trace[1468835303] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"628.917481ms","start":"2026-02-05T17:34:33.522688Z","end":"2026-02-05T17:34:34.151606Z","steps":["trace[1468835303] 'process raft request' (duration: 597.156465ms)","trace[1468835303] 'compare' (duration: 31.327494ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:34:34.151841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:34:33.522667Z","time spent":"629.060185ms","remote":"127.0.0.1:55814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:34:34.152284Z","caller":"traceutil/trace.go:171","msg":"trace[1890541610] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"597.951838ms","start":"2026-02-05T17:34:33.55432Z","end":"2026-02-05T17:34:34.152272Z","steps":["trace[1890541610] 'process raft request' (duration: 596.96177ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:34.152339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:34:33.554265Z","time spent":"598.047181ms","remote":"127.0.0.1:56198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-05T17:34:34.152525Z","caller":"traceutil/trace.go:171","msg":"trace[1874924134] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"597.606589ms","start":"2026-02-05T17:34:33.554906Z","end":"2026-02-05T17:34:34.152513Z","steps":["trace[1874924134] 'process raft request' (duration: 596.415135ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:34.152574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:34:33.554895Z","time spent":"597.65162ms","remote":"127.0.0.1:56286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-05T17:34:34.152682Z","caller":"traceutil/trace.go:171","msg":"trace[802948255] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"597.572616ms","start":"2026-02-05T17:34:33.555101Z","end":"2026-02-05T17:34:34.152673Z","steps":["trace[802948255] 'process raft request' (duration: 596.249339ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:34.152719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:34:33.55509Z","time spent":"597.611568ms","remote":"127.0.0.1:56286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-05T17:34:34.152821Z","caller":"traceutil/trace.go:171","msg":"trace[1421277840] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"597.622968ms","start":"2026-02-05T17:34:33.55519Z","end":"2026-02-05T17:34:34.152813Z","steps":["trace[1421277840] 'process raft request' (duration: 596.186977ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:34.152859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:34:33.555182Z","time spent":"597.660399ms","remote":"127.0.0.1:56286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-05T17:34:34.153018Z","caller":"traceutil/trace.go:171","msg":"trace[28852773] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"597.126034ms","start":"2026-02-05T17:34:33.55588Z","end":"2026-02-05T17:34:34.153006Z","steps":["trace[28852773] 'process raft request' (duration: 595.538759ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:34.15307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:34:33.55587Z","time spent":"597.176305ms","remote":"127.0.0.1:56286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-05T17:34:34.153188Z","caller":"traceutil/trace.go:171","msg":"trace[1085183940] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"597.230747ms","start":"2026-02-05T17:34:33.555948Z","end":"2026-02-05T17:34:34.153178Z","steps":["trace[1085183940] 'process raft request' (duration: 595.492068ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:34.153225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:34:33.555911Z","time spent":"597.297169ms","remote":"127.0.0.1:56286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-05T17:34:34.151533Z","caller":"traceutil/trace.go:171","msg":"trace[197502208] linearizableReadLoop","detail":"{readStateIndex:14; appliedIndex:5; }","duration":"257.964468ms","start":"2026-02-05T17:34:33.893548Z","end":"2026-02-05T17:34:34.151512Z","steps":["trace[197502208] 'read index received' (duration: 226.227813ms)","trace[197502208] 'applied index is now lower than readState.Index' (duration: 31.735835ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:34:34.157576Z","caller":"traceutil/trace.go:171","msg":"trace[1258167708] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"583.17452ms","start":"2026-02-05T17:34:33.57439Z","end":"2026-02-05T17:34:34.157565Z","steps":["trace[1258167708] 'process raft request' (duration: 577.068429ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:34.15762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:34:33.574373Z","time spent":"583.224161ms","remote":"127.0.0.1:55928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2845,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-05T17:34:34.157801Z","caller":"traceutil/trace.go:171","msg":"trace[1342001359] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"582.626423ms","start":"2026-02-05T17:34:33.575165Z","end":"2026-02-05T17:34:34.157792Z","steps":["trace[1342001359] 'process raft request' (duration: 576.319386ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:34.157843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:34:33.57515Z","time spent":"582.671525ms","remote":"127.0.0.1:56008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":845,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-05T17:34:34.15803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.413068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-05T17:34:34.158059Z","caller":"traceutil/trace.go:171","msg":"trace[531142234] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"537.49839ms","start":"2026-02-05T17:34:33.620552Z","end":"2026-02-05T17:34:34.15805Z","steps":["trace[531142234] 'agreement among raft nodes before linearized reading' (duration: 537.390897ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:34.158082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:34:33.620537Z","time spent":"537.539922ms","remote":"127.0.0.1:55860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-05T17:34:34.158202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.543619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-05T17:34:34.158257Z","caller":"traceutil/trace.go:171","msg":"trace[895922813] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"589.59599ms","start":"2026-02-05T17:34:33.568623Z","end":"2026-02-05T17:34:34.158219Z","steps":["trace[895922813] 'agreement among raft nodes before linearized reading' (duration: 589.558829ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:34.158336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:34:33.56861Z","time spent":"589.717324ms","remote":"127.0.0.1:55860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-05T17:34:34.158549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.662688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-05T17:34:34.158583Z","caller":"traceutil/trace.go:171","msg":"trace[1902178682] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"602.765491ms","start":"2026-02-05T17:34:33.555808Z","end":"2026-02-05T17:34:34.158573Z","steps":["trace[1902178682] 'agreement among raft nodes before linearized reading' (duration: 602.689339ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:34.158664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:34:33.5558Z","time spent":"602.854974ms","remote":"127.0.0.1:55832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-02-05T17:34:34.158852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.401003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.3\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-05T17:34:34.158915Z","caller":"traceutil/trace.go:171","msg":"trace[1105793504] range","detail":"{range_begin:/registry/masterleases/199.204.45.3; range_end:; response_count:0; response_revision:11; }","duration":"612.451285ms","start":"2026-02-05T17:34:33.546425Z","end":"2026-02-05T17:34:34.158877Z","steps":["trace[1105793504] 'agreement among raft nodes before linearized reading' (duration: 612.401803ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:34.158951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:34:33.546418Z","time spent":"612.524277ms","remote":"127.0.0.1:55784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":27,"request content":"key:\"/registry/masterleases/199.204.45.3\" "} {"level":"warn","ts":"2026-02-05T17:34:34.159132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.722372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-05T17:34:34.159161Z","caller":"traceutil/trace.go:171","msg":"trace[1397261179] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"612.769063ms","start":"2026-02-05T17:34:33.546384Z","end":"2026-02-05T17:34:34.159153Z","steps":["trace[1397261179] 'agreement among raft nodes before linearized reading' (duration: 612.726872ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:34.159214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:34:33.546374Z","time spent":"612.832665ms","remote":"127.0.0.1:55860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-05T17:34:34.159538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.540424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-05T17:34:34.159572Z","caller":"traceutil/trace.go:171","msg":"trace[111825762] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"251.613737ms","start":"2026-02-05T17:34:33.90795Z","end":"2026-02-05T17:34:34.159563Z","steps":["trace[111825762] 'agreement among raft nodes before linearized reading' (duration: 251.564715ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:34.619656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.627759ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-05T17:34:34.619983Z","caller":"traceutil/trace.go:171","msg":"trace[763607344] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"456.834491ms","start":"2026-02-05T17:34:34.162945Z","end":"2026-02-05T17:34:34.619779Z","steps":["trace[763607344] 'process raft request' (duration: 328.110841ms)","trace[763607344] 'compare' (duration: 127.449614ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:34:34.620165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:34:34.162919Z","time spent":"457.13811ms","remote":"127.0.0.1:56286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-05T17:34:34.634155Z","caller":"traceutil/trace.go:171","msg":"trace[2129861776] linearizableReadLoop","detail":"{readStateIndex:16; appliedIndex:14; }","duration":"463.626462ms","start":"2026-02-05T17:34:34.170513Z","end":"2026-02-05T17:34:34.63414Z","steps":["trace[2129861776] 'read index received' (duration: 320.550291ms)","trace[2129861776] 'applied index is now lower than readState.Index' (duration: 143.075471ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:34:34.634716Z","caller":"traceutil/trace.go:171","msg":"trace[64299905] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"464.270519ms","start":"2026-02-05T17:34:34.170434Z","end":"2026-02-05T17:34:34.634704Z","steps":["trace[64299905] 'process raft request' (duration: 463.444286ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:34.634966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:34:34.170418Z","time spent":"464.506645ms","remote":"127.0.0.1:56198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-05T17:34:34.635262Z","caller":"traceutil/trace.go:171","msg":"trace[77237193] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"464.67571ms","start":"2026-02-05T17:34:34.170578Z","end":"2026-02-05T17:34:34.635254Z","steps":["trace[77237193] 'process raft request' (duration: 463.358244ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:34.635409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:34:34.170574Z","time spent":"464.786633ms","remote":"127.0.0.1:55860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":338,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-05T17:34:34.635636Z","caller":"traceutil/trace.go:171","msg":"trace[259810849] transaction","detail":"{read_only:false; number_of_response:0; response_revision:14; }","duration":"464.99953ms","start":"2026-02-05T17:34:34.17063Z","end":"2026-02-05T17:34:34.635629Z","steps":["trace[259810849] 'process raft request' (duration: 463.343854ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:34.63574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:34:34.170627Z","time spent":"465.094142ms","remote":"127.0.0.1:55860","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-02-05T17:34:34.636772Z","caller":"traceutil/trace.go:171","msg":"trace[497074100] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"464.245788ms","start":"2026-02-05T17:34:34.172495Z","end":"2026-02-05T17:34:34.636741Z","steps":["trace[497074100] 'process raft request' (duration: 461.611385ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:34.637195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:34:34.17249Z","time spent":"464.482465ms","remote":"127.0.0.1:55928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3165,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:34:34.637858Z","caller":"traceutil/trace.go:171","msg":"trace[1200774589] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"466.998446ms","start":"2026-02-05T17:34:34.170845Z","end":"2026-02-05T17:34:34.637843Z","steps":["trace[1200774589] 'process raft request' (duration: 463.161989ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:34.638147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:34:34.170838Z","time spent":"467.238492ms","remote":"127.0.0.1:56286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-05T17:34:34.64269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.160689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3177"} {"level":"info","ts":"2026-02-05T17:34:34.642736Z","caller":"traceutil/trace.go:171","msg":"trace[418271094] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:19; }","duration":"472.214561ms","start":"2026-02-05T17:34:34.17051Z","end":"2026-02-05T17:34:34.642725Z","steps":["trace[418271094] 'agreement among raft nodes before linearized reading' (duration: 472.094338ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:34.642762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:34:34.170489Z","time spent":"472.268332ms","remote":"127.0.0.1:55928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3200,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-02-05T17:34:34.643189Z","caller":"traceutil/trace.go:171","msg":"trace[961322666] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"472.268073ms","start":"2026-02-05T17:34:34.170914Z","end":"2026-02-05T17:34:34.643182Z","steps":["trace[961322666] 'process raft request' (duration: 463.127348ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:34.643236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:34:34.17091Z","time spent":"472.298003ms","remote":"127.0.0.1:56286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":923,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-05T17:34:34.643286Z","caller":"traceutil/trace.go:171","msg":"trace[1144728826] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"472.338794ms","start":"2026-02-05T17:34:34.170942Z","end":"2026-02-05T17:34:34.643281Z","steps":["trace[1144728826] 'process raft request' (duration: 463.117787ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:34.643308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:34:34.170939Z","time spent":"472.358605ms","remote":"127.0.0.1:56286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-05T17:34:34.643348Z","caller":"traceutil/trace.go:171","msg":"trace[483153820] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"472.377885ms","start":"2026-02-05T17:34:34.170966Z","end":"2026-02-05T17:34:34.643344Z","steps":["trace[483153820] 'process raft request' (duration: 463.117527ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:34.643368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:34:34.170962Z","time spent":"472.397165ms","remote":"127.0.0.1:56286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-05T17:34:34.681752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.487708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-05T17:34:34.681799Z","caller":"traceutil/trace.go:171","msg":"trace[163942517] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:20; }","duration":"235.55246ms","start":"2026-02-05T17:34:34.446236Z","end":"2026-02-05T17:34:34.681788Z","steps":["trace[163942517] 'agreement among raft nodes before linearized reading' (duration: 235.457877ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:34.681889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.082985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-05T17:34:34.681908Z","caller":"traceutil/trace.go:171","msg":"trace[880948420] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:20; }","duration":"236.104376ms","start":"2026-02-05T17:34:34.445799Z","end":"2026-02-05T17:34:34.681904Z","steps":["trace[880948420] 'agreement among raft nodes before linearized reading' (duration: 236.078655ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:34.681997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.836152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-05T17:34:34.682015Z","caller":"traceutil/trace.go:171","msg":"trace[394026868] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:20; }","duration":"309.882003ms","start":"2026-02-05T17:34:34.372128Z","end":"2026-02-05T17:34:34.68201Z","steps":["trace[394026868] 'agreement among raft nodes before linearized reading' (duration: 309.854632ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:34.682029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:34:34.372108Z","time spent":"309.916624ms","remote":"127.0.0.1:56012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" "} {"level":"warn","ts":"2026-02-05T17:34:34.682099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.030815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-02-05T17:34:34.682114Z","caller":"traceutil/trace.go:171","msg":"trace[2075587590] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:20; }","duration":"330.071036ms","start":"2026-02-05T17:34:34.352039Z","end":"2026-02-05T17:34:34.68211Z","steps":["trace[2075587590] 'agreement among raft nodes before linearized reading' (duration: 330.045286ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:34.682128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:34:34.352025Z","time spent":"330.099067ms","remote":"127.0.0.1:55860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-05T17:34:34.682196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.188167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-02-05T17:34:34.68221Z","caller":"traceutil/trace.go:171","msg":"trace[693728957] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:20; }","duration":"455.230807ms","start":"2026-02-05T17:34:34.226976Z","end":"2026-02-05T17:34:34.682207Z","steps":["trace[693728957] 'agreement among raft nodes before linearized reading' (duration: 455.208057ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:34.682223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:34:34.226959Z","time spent":"455.261189ms","remote":"127.0.0.1:55860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-05T17:34:34.682281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.721123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-mqkm4\" ","response":"range_response_count:1 size:857"} {"level":"info","ts":"2026-02-05T17:34:34.682295Z","caller":"traceutil/trace.go:171","msg":"trace[968285343] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-mqkm4; range_end:; response_count:1; response_revision:20; }","duration":"511.742183ms","start":"2026-02-05T17:34:34.17055Z","end":"2026-02-05T17:34:34.682292Z","steps":["trace[968285343] 'agreement among raft nodes before linearized reading' (duration: 511.715073ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:34.682307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:34:34.170548Z","time spent":"511.756704ms","remote":"127.0.0.1:56008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":880,"request content":"key:\"/registry/certificatesigningrequests/csr-mqkm4\" "} {"level":"warn","ts":"2026-02-05T17:34:34.682373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.827636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-05T17:34:34.682388Z","caller":"traceutil/trace.go:171","msg":"trace[957345485] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20; }","duration":"511.851396ms","start":"2026-02-05T17:34:34.170533Z","end":"2026-02-05T17:34:34.682385Z","steps":["trace[957345485] 'agreement among raft nodes before linearized reading' (duration: 511.828165ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:34.682399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:34:34.17053Z","time spent":"511.866876ms","remote":"127.0.0.1:55740","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-02-05T17:34:34.769605Z","caller":"traceutil/trace.go:171","msg":"trace[1553900135] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"115.210464ms","start":"2026-02-05T17:34:34.654365Z","end":"2026-02-05T17:34:34.769576Z","steps":["trace[1553900135] 'process raft request' (duration: 64.288344ms)","trace[1553900135] 'compare' (duration: 50.508288ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:34:34.770632Z","caller":"traceutil/trace.go:171","msg":"trace[56660567] transaction","detail":"{read_only:false; response_revision:26; number_of_response:1; }","duration":"108.355851ms","start":"2026-02-05T17:34:34.662263Z","end":"2026-02-05T17:34:34.770619Z","steps":["trace[56660567] 'process raft request' (duration: 107.275741ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:34.772409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.310626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-05T17:34:34.772459Z","caller":"traceutil/trace.go:171","msg":"trace[837220348] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:0; response_revision:30; }","duration":"110.383129ms","start":"2026-02-05T17:34:34.662064Z","end":"2026-02-05T17:34:34.772447Z","steps":["trace[837220348] 'agreement among raft nodes before linearized reading' (duration: 110.258125ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:34.772543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.619008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-02-05T17:34:34.772565Z","caller":"traceutil/trace.go:171","msg":"trace[638565318] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:30; }","duration":"118.68672ms","start":"2026-02-05T17:34:34.653871Z","end":"2026-02-05T17:34:34.772558Z","steps":["trace[638565318] 'agreement among raft nodes before linearized reading' (duration: 118.643388ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:34:34.771009Z","caller":"traceutil/trace.go:171","msg":"trace[1976117940] transaction","detail":"{read_only:false; response_revision:22; number_of_response:1; }","duration":"114.930586ms","start":"2026-02-05T17:34:34.656028Z","end":"2026-02-05T17:34:34.770959Z","steps":["trace[1976117940] 'process raft request' (duration: 113.31784ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:34:34.771178Z","caller":"traceutil/trace.go:171","msg":"trace[1471727254] transaction","detail":"{read_only:false; response_revision:23; number_of_response:1; }","duration":"110.299316ms","start":"2026-02-05T17:34:34.660869Z","end":"2026-02-05T17:34:34.771169Z","steps":["trace[1471727254] 'process raft request' (duration: 108.529767ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:34:34.771206Z","caller":"traceutil/trace.go:171","msg":"trace[1826661737] transaction","detail":"{read_only:false; response_revision:24; number_of_response:1; }","duration":"108.99856ms","start":"2026-02-05T17:34:34.662201Z","end":"2026-02-05T17:34:34.771199Z","steps":["trace[1826661737] 'process raft request' (duration: 107.257721ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:34:34.771233Z","caller":"traceutil/trace.go:171","msg":"trace[458722641] transaction","detail":"{read_only:false; response_revision:25; number_of_response:1; }","duration":"108.98558ms","start":"2026-02-05T17:34:34.662241Z","end":"2026-02-05T17:34:34.771226Z","steps":["trace[458722641] 'process raft request' (duration: 107.244911ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:35.893824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.784425ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T17:34:35.894043Z","caller":"traceutil/trace.go:171","msg":"trace[1848030685] transaction","detail":"{read_only:false; response_revision:168; number_of_response:1; }","duration":"177.249719ms","start":"2026-02-05T17:34:35.716782Z","end":"2026-02-05T17:34:35.894032Z","steps":["trace[1848030685] 'compare' (duration: 168.697683ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:34:35.893904Z","caller":"traceutil/trace.go:171","msg":"trace[1217277243] linearizableReadLoop","detail":"{readStateIndex:173; appliedIndex:172; }","duration":"176.739345ms","start":"2026-02-05T17:34:35.71715Z","end":"2026-02-05T17:34:35.89389Z","steps":["trace[1217277243] 'read index received' (duration: 7.757264ms)","trace[1217277243] 'applied index is now lower than readState.Index' (duration: 168.981281ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:34:35.894649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.552359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:kube-dns\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:34:35.89468Z","caller":"traceutil/trace.go:171","msg":"trace[860488033] range","detail":"{range_begin:/registry/clusterrolebindings/system:kube-dns; range_end:; response_count:0; response_revision:168; }","duration":"177.621461ms","start":"2026-02-05T17:34:35.71705Z","end":"2026-02-05T17:34:35.894672Z","steps":["trace[860488033] 'agreement among raft nodes before linearized reading' (duration: 177.426615ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:35.89479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.771718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:34:35.894813Z","caller":"traceutil/trace.go:171","msg":"trace[2142981349] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:168; }","duration":"105.83733ms","start":"2026-02-05T17:34:35.788971Z","end":"2026-02-05T17:34:35.894808Z","steps":["trace[2142981349] 'agreement among raft nodes before linearized reading' (duration: 105.798849ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:36.361332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.335249ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T17:34:36.361433Z","caller":"traceutil/trace.go:171","msg":"trace[2029946531] transaction","detail":"{read_only:false; response_revision:170; number_of_response:1; }","duration":"393.69259ms","start":"2026-02-05T17:34:35.967728Z","end":"2026-02-05T17:34:36.36142Z","steps":["trace[2029946531] 'process raft request' (duration: 40.190356ms)","trace[2029946531] 'compare' (duration: 353.192476ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:34:36.361467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:34:35.967714Z","time spent":"393.738012ms","remote":"127.0.0.1:55820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":638,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T17:34:36.687576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.423272ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-05T17:34:36.687675Z","caller":"traceutil/trace.go:171","msg":"trace[305791939] linearizableReadLoop","detail":"{readStateIndex:176; appliedIndex:175; }","duration":"664.243702ms","start":"2026-02-05T17:34:36.023412Z","end":"2026-02-05T17:34:36.687656Z","steps":["trace[305791939] 'read index received' (duration: 446.636505ms)","trace[305791939] 'applied index is now lower than readState.Index' (duration: 217.606177ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:34:36.687758Z","caller":"traceutil/trace.go:171","msg":"trace[995241344] transaction","detail":"{read_only:false; response_revision:171; number_of_response:1; }","duration":"717.953727ms","start":"2026-02-05T17:34:35.969793Z","end":"2026-02-05T17:34:36.687747Z","steps":["trace[995241344] 'process raft request' (duration: 500.297269ms)","trace[995241344] 'compare' (duration: 217.284138ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:34:36.687807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:34:35.969774Z","time spent":"718.010548ms","remote":"127.0.0.1:56120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":694,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-05T17:34:36.687886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.78126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:34:36.687987Z","caller":"traceutil/trace.go:171","msg":"trace[1672629064] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:171; }","duration":"497.927275ms","start":"2026-02-05T17:34:36.190037Z","end":"2026-02-05T17:34:36.687964Z","steps":["trace[1672629064] 'agreement among raft nodes before linearized reading' (duration: 497.75006ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:36.688036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:34:36.190012Z","time spent":"498.013907ms","remote":"127.0.0.1:55740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T17:34:36.688214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.794637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3444"} {"level":"info","ts":"2026-02-05T17:34:36.688251Z","caller":"traceutil/trace.go:171","msg":"trace[707594064] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:171; }","duration":"664.873199ms","start":"2026-02-05T17:34:36.023366Z","end":"2026-02-05T17:34:36.688239Z","steps":["trace[707594064] 'agreement among raft nodes before linearized reading' (duration: 664.797947ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:36.688282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:34:36.023344Z","time spent":"664.93137ms","remote":"127.0.0.1:55930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":3467,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"warn","ts":"2026-02-05T17:34:36.688369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.157224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18916a2bfae9d659\" ","response":"range_response_count:1 size:651"} {"level":"info","ts":"2026-02-05T17:34:36.688407Z","caller":"traceutil/trace.go:171","msg":"trace[475717628] range","detail":"{range_begin:/registry/events/default/instance.18916a2bfae9d659; range_end:; response_count:1; response_revision:171; }","duration":"324.231076ms","start":"2026-02-05T17:34:36.364165Z","end":"2026-02-05T17:34:36.688396Z","steps":["trace[475717628] 'agreement among raft nodes before linearized reading' (duration: 324.146784ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:36.688436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:34:36.364146Z","time spent":"324.283798ms","remote":"127.0.0.1:55820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":674,"request content":"key:\"/registry/events/default/instance.18916a2bfae9d659\" "} {"level":"warn","ts":"2026-02-05T17:34:37.137079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.455282ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T17:34:37.137479Z","caller":"traceutil/trace.go:171","msg":"trace[406265999] transaction","detail":"{read_only:false; response_revision:175; number_of_response:1; }","duration":"403.372826ms","start":"2026-02-05T17:34:36.734074Z","end":"2026-02-05T17:34:37.137447Z","steps":["trace[406265999] 'process raft request' (duration: 67.452071ms)","trace[406265999] 'compare' (duration: 335.336999ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:34:37.13764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:34:36.734064Z","time spent":"403.486268ms","remote":"127.0.0.1:55820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":642,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:34:37.137629Z","caller":"traceutil/trace.go:171","msg":"trace[1088567360] linearizableReadLoop","detail":"{readStateIndex:182; appliedIndex:179; }","duration":"348.374304ms","start":"2026-02-05T17:34:36.789233Z","end":"2026-02-05T17:34:37.137607Z","steps":["trace[1088567360] 'read index received' (duration: 12.300025ms)","trace[1088567360] 'applied index is now lower than readState.Index' (duration: 336.072459ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:34:37.138004Z","caller":"traceutil/trace.go:171","msg":"trace[1899345004] transaction","detail":"{read_only:false; response_revision:177; number_of_response:1; }","duration":"399.876419ms","start":"2026-02-05T17:34:36.73807Z","end":"2026-02-05T17:34:37.137946Z","steps":["trace[1899345004] 'process raft request' (duration: 399.408887ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:37.138069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:34:36.738063Z","time spent":"399.974682ms","remote":"127.0.0.1:55930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6875,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:34:37.138206Z","caller":"traceutil/trace.go:171","msg":"trace[1586420593] transaction","detail":"{read_only:false; response_revision:176; number_of_response:1; }","duration":"403.934492ms","start":"2026-02-05T17:34:36.734262Z","end":"2026-02-05T17:34:37.138197Z","steps":["trace[1586420593] 'process raft request' (duration: 403.099898ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:37.138253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:34:36.734258Z","time spent":"403.974703ms","remote":"127.0.0.1:56120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":588,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-05T17:34:37.138318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.073593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:34:37.13843Z","caller":"traceutil/trace.go:171","msg":"trace[1146554127] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:177; }","duration":"349.212187ms","start":"2026-02-05T17:34:36.789203Z","end":"2026-02-05T17:34:37.138415Z","steps":["trace[1146554127] 'agreement among raft nodes before linearized reading' (duration: 348.93321ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:37.138527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:34:36.789185Z","time spent":"349.32947ms","remote":"127.0.0.1:55736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T17:34:37.138707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.466257ms","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-02-05T17:34:37.138749Z","caller":"traceutil/trace.go:171","msg":"trace[1852279393] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:177; }","duration":"223.544169ms","start":"2026-02-05T17:34:36.915196Z","end":"2026-02-05T17:34:37.13874Z","steps":["trace[1852279393] 'agreement among raft nodes before linearized reading' (duration: 223.464177ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:37.138882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.900252ms","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-02-05T17:34:37.138913Z","caller":"traceutil/trace.go:171","msg":"trace[1715341815] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:177; }","duration":"222.953342ms","start":"2026-02-05T17:34:36.915953Z","end":"2026-02-05T17:34:37.138906Z","steps":["trace[1715341815] 'agreement among raft nodes before linearized reading' (duration: 222.904661ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:34:37.586138Z","caller":"traceutil/trace.go:171","msg":"trace[2001630285] linearizableReadLoop","detail":"{readStateIndex:219; appliedIndex:218; }","duration":"155.876756ms","start":"2026-02-05T17:34:37.430237Z","end":"2026-02-05T17:34:37.586113Z","steps":["trace[2001630285] 'read index received' (duration: 87.47676ms)","trace[2001630285] 'applied index is now lower than readState.Index' (duration: 68.398545ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:34:37.586488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.234367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:34:37.586782Z","caller":"traceutil/trace.go:171","msg":"trace[1807505017] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:214; }","duration":"156.513783ms","start":"2026-02-05T17:34:37.4302Z","end":"2026-02-05T17:34:37.586713Z","steps":["trace[1807505017] 'agreement among raft nodes before linearized reading' (duration: 156.186705ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:34:37.5873Z","caller":"traceutil/trace.go:171","msg":"trace[14659628] transaction","detail":"{read_only:false; response_revision:214; number_of_response:1; }","duration":"277.030956ms","start":"2026-02-05T17:34:37.310254Z","end":"2026-02-05T17:34:37.587285Z","steps":["trace[14659628] 'process raft request' (duration: 207.498509ms)","trace[14659628] 'compare' (duration: 67.643975ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:34:38.232823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.907238ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-05T17:34:38.232953Z","caller":"traceutil/trace.go:171","msg":"trace[426542701] transaction","detail":"{read_only:false; response_revision:231; number_of_response:1; }","duration":"319.982017ms","start":"2026-02-05T17:34:37.91293Z","end":"2026-02-05T17:34:38.232912Z","steps":["trace[426542701] 'process raft request' (duration: 189.851083ms)","trace[426542701] 'compare' (duration: 129.791005ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:34:38.233478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:34:37.912915Z","time spent":"320.541692ms","remote":"127.0.0.1:56120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":760,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-05T17:34:38.423032Z","caller":"traceutil/trace.go:171","msg":"trace[1207971494] transaction","detail":"{read_only:false; response_revision:232; number_of_response:1; }","duration":"186.22425ms","start":"2026-02-05T17:34:38.236789Z","end":"2026-02-05T17:34:38.423013Z","steps":["trace[1207971494] 'process raft request' (duration: 182.548572ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:34:38.774875Z","caller":"traceutil/trace.go:171","msg":"trace[1269415588] transaction","detail":"{read_only:false; response_revision:242; number_of_response:1; }","duration":"140.361016ms","start":"2026-02-05T17:34:38.634475Z","end":"2026-02-05T17:34:38.774836Z","steps":["trace[1269415588] 'process raft request' (duration: 127.745449ms)","trace[1269415588] 'compare' (duration: 12.433043ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:34:39.886223Z","caller":"traceutil/trace.go:171","msg":"trace[189335378] transaction","detail":"{read_only:false; response_revision:270; number_of_response:1; }","duration":"117.154883ms","start":"2026-02-05T17:34:39.769046Z","end":"2026-02-05T17:34:39.886201Z","steps":["trace[189335378] 'process raft request' (duration: 28.163353ms)","trace[189335378] 'compare' (duration: 88.503327ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:34:40.049855Z","caller":"traceutil/trace.go:171","msg":"trace[493334637] linearizableReadLoop","detail":"{readStateIndex:277; appliedIndex:276; }","duration":"151.559939ms","start":"2026-02-05T17:34:39.898276Z","end":"2026-02-05T17:34:40.049836Z","steps":["trace[493334637] 'read index received' (duration: 116.733769ms)","trace[493334637] 'applied index is now lower than readState.Index' (duration: 34.82558ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:34:40.05009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.794595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-05T17:34:40.050159Z","caller":"traceutil/trace.go:171","msg":"trace[484281129] transaction","detail":"{read_only:false; response_revision:271; number_of_response:1; }","duration":"153.235843ms","start":"2026-02-05T17:34:39.896898Z","end":"2026-02-05T17:34:40.050134Z","steps":["trace[484281129] 'process raft request' (duration: 118.170848ms)","trace[484281129] 'compare' (duration: 34.666715ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:34:40.050196Z","caller":"traceutil/trace.go:171","msg":"trace[866367204] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:271; }","duration":"151.932769ms","start":"2026-02-05T17:34:39.898252Z","end":"2026-02-05T17:34:40.050185Z","steps":["trace[866367204] 'agreement among raft nodes before linearized reading' (duration: 151.791085ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:34:40.262054Z","caller":"traceutil/trace.go:171","msg":"trace[2027445177] linearizableReadLoop","detail":"{readStateIndex:280; appliedIndex:279; }","duration":"129.080561ms","start":"2026-02-05T17:34:40.132946Z","end":"2026-02-05T17:34:40.262027Z","steps":["trace[2027445177] 'read index received' (duration: 125.162968ms)","trace[2027445177] 'applied index is now lower than readState.Index' (duration: 3.916583ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:34:40.262359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.384859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-05T17:34:40.262408Z","caller":"traceutil/trace.go:171","msg":"trace[1803534312] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:274; }","duration":"129.459742ms","start":"2026-02-05T17:34:40.132936Z","end":"2026-02-05T17:34:40.262396Z","steps":["trace[1803534312] 'agreement among raft nodes before linearized reading' (duration: 129.183955ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:34:40.262771Z","caller":"traceutil/trace.go:171","msg":"trace[1067295656] transaction","detail":"{read_only:false; response_revision:274; number_of_response:1; }","duration":"130.015796ms","start":"2026-02-05T17:34:40.132744Z","end":"2026-02-05T17:34:40.26276Z","steps":["trace[1067295656] 'process raft request' (duration: 125.421406ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:34:40.981116Z","caller":"traceutil/trace.go:171","msg":"trace[1177261537] transaction","detail":"{read_only:false; response_revision:288; number_of_response:1; }","duration":"104.303644ms","start":"2026-02-05T17:34:40.876787Z","end":"2026-02-05T17:34:40.981091Z","steps":["trace[1177261537] 'process raft request' (duration: 25.709001ms)","trace[1177261537] 'compare' (duration: 78.413848ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:34:41.238101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.801712ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-05T17:34:41.238598Z","caller":"traceutil/trace.go:171","msg":"trace[562226982] transaction","detail":"{read_only:false; response_revision:292; number_of_response:1; }","duration":"160.528358ms","start":"2026-02-05T17:34:41.078054Z","end":"2026-02-05T17:34:41.238582Z","steps":["trace[562226982] 'process raft request' (duration: 39.133202ms)","trace[562226982] 'compare' (duration: 120.655447ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:34:41.241841Z","caller":"traceutil/trace.go:171","msg":"trace[292836998] transaction","detail":"{read_only:false; response_revision:293; number_of_response:1; }","duration":"163.383582ms","start":"2026-02-05T17:34:41.078448Z","end":"2026-02-05T17:34:41.241831Z","steps":["trace[292836998] 'process raft request' (duration: 163.088815ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:34:41.242226Z","caller":"traceutil/trace.go:171","msg":"trace[47110098] transaction","detail":"{read_only:false; number_of_response:0; response_revision:293; }","duration":"135.494492ms","start":"2026-02-05T17:34:41.106724Z","end":"2026-02-05T17:34:41.242219Z","steps":["trace[47110098] 'process raft request' (duration: 134.937777ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:34:41.241794Z","caller":"traceutil/trace.go:171","msg":"trace[1133100948] linearizableReadLoop","detail":"{readStateIndex:300; appliedIndex:297; }","duration":"134.991019ms","start":"2026-02-05T17:34:41.10679Z","end":"2026-02-05T17:34:41.241781Z","steps":["trace[1133100948] 'read index received' (duration: 10.519563ms)","trace[1133100948] 'applied index is now lower than readState.Index' (duration: 124.470746ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:34:41.242816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.012086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2898"} {"level":"info","ts":"2026-02-05T17:34:41.246124Z","caller":"traceutil/trace.go:171","msg":"trace[1739463432] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:293; }","duration":"139.328171ms","start":"2026-02-05T17:34:41.106769Z","end":"2026-02-05T17:34:41.246097Z","steps":["trace[1739463432] 'agreement among raft nodes before linearized reading' (duration: 135.977854ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:34:42.030214Z","caller":"traceutil/trace.go:171","msg":"trace[1535271767] linearizableReadLoop","detail":"{readStateIndex:319; appliedIndex:318; }","duration":"254.771685ms","start":"2026-02-05T17:34:41.775415Z","end":"2026-02-05T17:34:42.030187Z","steps":["trace[1535271767] 'read index received' (duration: 184.053566ms)","trace[1535271767] 'applied index is now lower than readState.Index' (duration: 70.717069ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:34:42.030473Z","caller":"traceutil/trace.go:171","msg":"trace[1948649726] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"261.317194ms","start":"2026-02-05T17:34:41.769102Z","end":"2026-02-05T17:34:42.030419Z","steps":["trace[1948649726] 'process raft request' (duration: 190.226215ms)","trace[1948649726] 'compare' (duration: 70.71957ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:34:42.030684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.252108ms","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-02-05T17:34:42.030708Z","caller":"traceutil/trace.go:171","msg":"trace[1363927075] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:312; }","duration":"255.322209ms","start":"2026-02-05T17:34:41.775378Z","end":"2026-02-05T17:34:42.0307Z","steps":["trace[1363927075] 'agreement among raft nodes before linearized reading' (duration: 255.234357ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:34:42.07823Z","caller":"traceutil/trace.go:171","msg":"trace[1367396902] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"292.502377ms","start":"2026-02-05T17:34:41.785701Z","end":"2026-02-05T17:34:42.078203Z","steps":["trace[1367396902] 'process raft request' (duration: 292.314532ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:34:42.078677Z","caller":"traceutil/trace.go:171","msg":"trace[391468002] transaction","detail":"{read_only:false; number_of_response:0; response_revision:313; }","duration":"241.931431ms","start":"2026-02-05T17:34:41.836736Z","end":"2026-02-05T17:34:42.078668Z","steps":["trace[391468002] 'process raft request' (duration: 241.405347ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:42.078987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.666621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-02-05T17:34:42.079257Z","caller":"traceutil/trace.go:171","msg":"trace[152367955] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:313; }","duration":"292.955598ms","start":"2026-02-05T17:34:41.786254Z","end":"2026-02-05T17:34:42.079209Z","steps":["trace[152367955] 'agreement among raft nodes before linearized reading' (duration: 292.388184ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:42.079716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.456819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3703"} {"level":"info","ts":"2026-02-05T17:34:42.080136Z","caller":"traceutil/trace.go:171","msg":"trace[1063176864] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:313; }","duration":"294.915081ms","start":"2026-02-05T17:34:41.785207Z","end":"2026-02-05T17:34:42.080122Z","steps":["trace[1063176864] 'agreement among raft nodes before linearized reading' (duration: 294.417568ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:34:42.266473Z","caller":"traceutil/trace.go:171","msg":"trace[2033629872] linearizableReadLoop","detail":"{readStateIndex:330; appliedIndex:329; }","duration":"139.983608ms","start":"2026-02-05T17:34:42.126465Z","end":"2026-02-05T17:34:42.266449Z","steps":["trace[2033629872] 'read index received' (duration: 97.755649ms)","trace[2033629872] 'applied index is now lower than readState.Index' (duration: 42.227089ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:34:42.266956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.942787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-05T17:34:42.267054Z","caller":"traceutil/trace.go:171","msg":"trace[1801423592] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:318; }","duration":"149.06749ms","start":"2026-02-05T17:34:42.117968Z","end":"2026-02-05T17:34:42.267035Z","steps":["trace[1801423592] 'agreement among raft nodes before linearized reading' (duration: 148.809064ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:42.267259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.480021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4589"} {"level":"info","ts":"2026-02-05T17:34:42.267473Z","caller":"traceutil/trace.go:171","msg":"trace[646805381] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:318; }","duration":"149.979785ms","start":"2026-02-05T17:34:42.117479Z","end":"2026-02-05T17:34:42.267458Z","steps":["trace[646805381] 'agreement among raft nodes before linearized reading' (duration: 149.42707ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:42.267955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.279174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4060"} {"level":"info","ts":"2026-02-05T17:34:42.268403Z","caller":"traceutil/trace.go:171","msg":"trace[462655638] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:318; }","duration":"152.734835ms","start":"2026-02-05T17:34:42.115653Z","end":"2026-02-05T17:34:42.268388Z","steps":["trace[462655638] 'agreement among raft nodes before linearized reading' (duration: 152.259933ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:42.268229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.615082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6890"} {"level":"info","ts":"2026-02-05T17:34:42.268959Z","caller":"traceutil/trace.go:171","msg":"trace[401866418] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:318; }","duration":"153.367991ms","start":"2026-02-05T17:34:42.115577Z","end":"2026-02-05T17:34:42.268945Z","steps":["trace[401866418] 'agreement among raft nodes before linearized reading' (duration: 152.605302ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:42.268288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.641643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5248"} {"level":"info","ts":"2026-02-05T17:34:42.270538Z","caller":"traceutil/trace.go:171","msg":"trace[1104278090] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:318; }","duration":"154.897471ms","start":"2026-02-05T17:34:42.115626Z","end":"2026-02-05T17:34:42.270523Z","steps":["trace[1104278090] 'agreement among raft nodes before linearized reading' (duration: 152.635683ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:34:42.267347Z","caller":"traceutil/trace.go:171","msg":"trace[188486972] transaction","detail":"{read_only:false; response_revision:318; number_of_response:1; }","duration":"148.433535ms","start":"2026-02-05T17:34:42.118901Z","end":"2026-02-05T17:34:42.267334Z","steps":["trace[188486972] 'process raft request' (duration: 105.208759ms)","trace[188486972] 'compare' (duration: 42.171578ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:34:42.271287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.424016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3703"} {"level":"info","ts":"2026-02-05T17:34:42.271339Z","caller":"traceutil/trace.go:171","msg":"trace[1836433480] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:319; }","duration":"141.498899ms","start":"2026-02-05T17:34:42.129829Z","end":"2026-02-05T17:34:42.271328Z","steps":["trace[1836433480] 'agreement among raft nodes before linearized reading' (duration: 141.401146ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:34:42.271486Z","caller":"traceutil/trace.go:171","msg":"trace[1476842462] transaction","detail":"{read_only:false; response_revision:319; number_of_response:1; }","duration":"139.986439ms","start":"2026-02-05T17:34:42.13149Z","end":"2026-02-05T17:34:42.271476Z","steps":["trace[1476842462] 'process raft request' (duration: 139.652061ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:34:46.492427Z","caller":"traceutil/trace.go:171","msg":"trace[1649397961] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"641.604493ms","start":"2026-02-05T17:34:45.850804Z","end":"2026-02-05T17:34:46.492408Z","steps":["trace[1649397961] 'process raft request' (duration: 641.45617ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:46.492535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:34:45.850793Z","time spent":"641.686156ms","remote":"127.0.0.1:55930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5063,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:34:51.103984Z","caller":"traceutil/trace.go:171","msg":"trace[1907157044] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"168.804505ms","start":"2026-02-05T17:34:50.935153Z","end":"2026-02-05T17:34:51.103957Z","steps":["trace[1907157044] 'process raft request' (duration: 128.449885ms)","trace[1907157044] 'compare' (duration: 39.803447ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:34:53.651324Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933263664548678554,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T17:34:54.151914Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933263664548678554,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T17:34:54.17836Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.689560341s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-05T17:34:54.336697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.123897734s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:34:54.336775Z","caller":"traceutil/trace.go:171","msg":"trace[594635477] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:418; }","duration":"3.123986125s","start":"2026-02-05T17:34:51.212778Z","end":"2026-02-05T17:34:54.336764Z","steps":["trace[594635477] 'range keys from in-memory index tree' (duration: 3.123884803s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:54.33692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.007624797s","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-02-05T17:34:54.336942Z","caller":"traceutil/trace.go:171","msg":"trace[1758385396] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:418; }","duration":"3.007679228s","start":"2026-02-05T17:34:51.329256Z","end":"2026-02-05T17:34:54.336936Z","steps":["trace[1758385396] 'range keys from in-memory index tree' (duration: 3.007559595s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:54.336963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:34:51.329223Z","time spent":"3.00773629s","remote":"127.0.0.1:56012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-05T17:34:54.337125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.99579038s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-05T17:34:54.337145Z","caller":"traceutil/trace.go:171","msg":"trace[616540228] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:418; }","duration":"2.995829512s","start":"2026-02-05T17:34:51.34131Z","end":"2026-02-05T17:34:54.33714Z","steps":["trace[616540228] 'range keys from in-memory index tree' (duration: 2.995746159s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:54.337158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:34:51.3413Z","time spent":"2.995854482s","remote":"127.0.0.1:56012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-05T17:34:54.337245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.98543494s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6890"} {"level":"info","ts":"2026-02-05T17:34:54.337262Z","caller":"traceutil/trace.go:171","msg":"trace[1384872106] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:418; }","duration":"2.98547253s","start":"2026-02-05T17:34:51.351785Z","end":"2026-02-05T17:34:54.337257Z","steps":["trace[1384872106] 'range keys from in-memory index tree' (duration: 2.985364868s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:54.337275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:34:51.351775Z","time spent":"2.985497482s","remote":"127.0.0.1:55930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6913,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-02-05T17:34:54.337352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.825708642s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:34:54.337367Z","caller":"traceutil/trace.go:171","msg":"trace[216647076] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:418; }","duration":"2.825760313s","start":"2026-02-05T17:34:51.511603Z","end":"2026-02-05T17:34:54.337364Z","steps":["trace[216647076] 'range keys from in-memory index tree' (duration: 2.82563841s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:54.337381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:34:51.511584Z","time spent":"2.825793294s","remote":"127.0.0.1:55736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T17:34:54.337431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.57777174s","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-02-05T17:34:54.337445Z","caller":"traceutil/trace.go:171","msg":"trace[784119631] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:418; }","duration":"2.577812391s","start":"2026-02-05T17:34:51.759629Z","end":"2026-02-05T17:34:54.337441Z","steps":["trace[784119631] 'range keys from in-memory index tree' (duration: 2.577727079s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:54.337456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:34:51.759614Z","time spent":"2.577839272s","remote":"127.0.0.1:56012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-05T17:34:54.33891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.352262ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T17:34:54.338949Z","caller":"traceutil/trace.go:171","msg":"trace[23721507] linearizableReadLoop","detail":"{readStateIndex:435; appliedIndex:434; }","duration":"1.187964463s","start":"2026-02-05T17:34:53.150979Z","end":"2026-02-05T17:34:54.338943Z","steps":["trace[23721507] 'read index received' (duration: 1.027467437s)","trace[23721507] 'applied index is now lower than readState.Index' (duration: 160.496406ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:34:54.339031Z","caller":"traceutil/trace.go:171","msg":"trace[404460161] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"1.850323622s","start":"2026-02-05T17:34:52.488701Z","end":"2026-02-05T17:34:54.339025Z","steps":["trace[404460161] 'process raft request' (duration: 1.689822206s)","trace[404460161] 'compare' (duration: 159.810279ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:34:54.339061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:34:52.488679Z","time spent":"1.850365582s","remote":"127.0.0.1:56012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T17:34:54.339121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.188152527s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-05T17:34:54.339132Z","caller":"traceutil/trace.go:171","msg":"trace[1523104977] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:419; }","duration":"1.188208908s","start":"2026-02-05T17:34:53.150919Z","end":"2026-02-05T17:34:54.339128Z","steps":["trace[1523104977] 'agreement among raft nodes before linearized reading' (duration: 1.188185008s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:54.339145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:34:53.150898Z","time spent":"1.188243768s","remote":"127.0.0.1:56012","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-02-05T17:34:54.839241Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933263664548678557,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T17:34:55.340268Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933263664548678557,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T17:34:55.512244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000186051s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-05T17:34:55.51339Z","caller":"traceutil/trace.go:171","msg":"trace[158486114] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.001361017s","start":"2026-02-05T17:34:53.512Z","end":"2026-02-05T17:34:55.513361Z","steps":["trace[158486114] 'agreement among raft nodes before linearized reading' (duration: 2.00020434s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:55.513483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:34:53.511987Z","time spent":"2.0014798s","remote":"127.0.0.1:55740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/05 17:34:55 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-05T17:34:55.624585Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.323597974s","expected-duration":"1s"} {"level":"info","ts":"2026-02-05T17:34:55.648337Z","caller":"traceutil/trace.go:171","msg":"trace[1920184345] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"1.301991251s","start":"2026-02-05T17:34:54.34633Z","end":"2026-02-05T17:34:55.648321Z","steps":["trace[1920184345] 'process raft request' (duration: 1.301890139s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:55.648473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:34:54.346314Z","time spent":"1.302068742s","remote":"127.0.0.1:56012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:34:55.648588Z","caller":"traceutil/trace.go:171","msg":"trace[808956477] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"1.302201566s","start":"2026-02-05T17:34:54.346381Z","end":"2026-02-05T17:34:55.648582Z","steps":["trace[808956477] 'process raft request' (duration: 1.301916879s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:55.648616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:34:54.346377Z","time spent":"1.302222926s","remote":"127.0.0.1:56012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:34:55.648776Z","caller":"traceutil/trace.go:171","msg":"trace[46339748] linearizableReadLoop","detail":"{readStateIndex:442; appliedIndex:435; }","duration":"1.309809857s","start":"2026-02-05T17:34:54.338959Z","end":"2026-02-05T17:34:55.648769Z","steps":["trace[46339748] 'read index received' (duration: 1.286140818s)","trace[46339748] 'applied index is now lower than readState.Index' (duration: 23.668099ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:34:55.648815Z","caller":"traceutil/trace.go:171","msg":"trace[1718650651] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"1.30240616s","start":"2026-02-05T17:34:54.346405Z","end":"2026-02-05T17:34:55.648811Z","steps":["trace[1718650651] 'process raft request' (duration: 1.302153294s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:55.64884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:34:54.346401Z","time spent":"1.3024226s","remote":"127.0.0.1:56012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:34:55.648879Z","caller":"traceutil/trace.go:171","msg":"trace[509777923] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"1.302097973s","start":"2026-02-05T17:34:54.346775Z","end":"2026-02-05T17:34:55.648873Z","steps":["trace[509777923] 'process raft request' (duration: 1.301896508s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:55.6489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:34:54.346769Z","time spent":"1.302118053s","remote":"127.0.0.1:56012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:34:55.649026Z","caller":"traceutil/trace.go:171","msg":"trace[441352485] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"1.300297572s","start":"2026-02-05T17:34:54.348724Z","end":"2026-02-05T17:34:55.649021Z","steps":["trace[441352485] 'process raft request' (duration: 1.299968185s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:55.649059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:34:54.348717Z","time spent":"1.300322433s","remote":"127.0.0.1:55930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6875,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:34:55.649126Z","caller":"traceutil/trace.go:171","msg":"trace[916184468] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"356.229405ms","start":"2026-02-05T17:34:55.292892Z","end":"2026-02-05T17:34:55.649122Z","steps":["trace[916184468] 'process raft request' (duration: 355.833637ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:55.64915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:34:55.292874Z","time spent":"356.262586ms","remote":"127.0.0.1:56012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:34:55.649192Z","caller":"traceutil/trace.go:171","msg":"trace[591138290] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"133.74888ms","start":"2026-02-05T17:34:55.515438Z","end":"2026-02-05T17:34:55.649187Z","steps":["trace[591138290] 'process raft request' (duration: 133.30431ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:55.649263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.489262439s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:34:55.649283Z","caller":"traceutil/trace.go:171","msg":"trace[992161869] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:426; }","duration":"1.48931302s","start":"2026-02-05T17:34:54.159963Z","end":"2026-02-05T17:34:55.649276Z","steps":["trace[992161869] 'agreement among raft nodes before linearized reading' (duration: 1.489281659s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:34:55.649297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:34:54.159946Z","time spent":"1.489347921s","remote":"127.0.0.1:55736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T17:34:55.649387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.977241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:34:55.649408Z","caller":"traceutil/trace.go:171","msg":"trace[276608347] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:426; }","duration":"132.016421ms","start":"2026-02-05T17:34:55.517386Z","end":"2026-02-05T17:34:55.649402Z","steps":["trace[276608347] 'agreement among raft nodes before linearized reading' (duration: 131.985911ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:02.04535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.50266ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T17:35:02.045493Z","caller":"traceutil/trace.go:171","msg":"trace[943688018] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"337.766405ms","start":"2026-02-05T17:35:01.707716Z","end":"2026-02-05T17:35:02.045483Z","steps":["trace[943688018] 'process raft request' (duration: 337.741584ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:02.045528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:01.70771Z","time spent":"337.803436ms","remote":"127.0.0.1:56012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:35:02.045785Z","caller":"traceutil/trace.go:171","msg":"trace[624249785] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"339.789118ms","start":"2026-02-05T17:35:01.70597Z","end":"2026-02-05T17:35:02.045759Z","steps":["trace[624249785] 'process raft request' (duration: 166.825038ms)","trace[624249785] 'compare' (duration: 172.426019ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:35:02.045812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:01.705952Z","time spent":"339.845328ms","remote":"127.0.0.1:56012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:35:02.045873Z","caller":"traceutil/trace.go:171","msg":"trace[1867414400] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"339.845439ms","start":"2026-02-05T17:35:01.706023Z","end":"2026-02-05T17:35:02.045869Z","steps":["trace[1867414400] 'process raft request' (duration: 339.39267ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:02.045902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:01.706011Z","time spent":"339.872299ms","remote":"127.0.0.1:56012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:35:15.291857Z","caller":"traceutil/trace.go:171","msg":"trace[1807833714] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"389.554921ms","start":"2026-02-05T17:35:14.902283Z","end":"2026-02-05T17:35:15.291838Z","steps":["trace[1807833714] 'process raft request' (duration: 389.443419ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:15.291975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:14.902253Z","time spent":"389.662924ms","remote":"127.0.0.1:56012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T17:35:16.381726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.343054ms","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-02-05T17:35:16.381823Z","caller":"traceutil/trace.go:171","msg":"trace[1928854007] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:535; }","duration":"212.486086ms","start":"2026-02-05T17:35:16.169314Z","end":"2026-02-05T17:35:16.3818Z","steps":["trace[1928854007] 'range keys from in-memory index tree' (duration: 212.238532ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:16.382101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.994395ms","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-02-05T17:35:16.382125Z","caller":"traceutil/trace.go:171","msg":"trace[1700628193] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:535; }","duration":"195.029365ms","start":"2026-02-05T17:35:16.187085Z","end":"2026-02-05T17:35:16.382115Z","steps":["trace[1700628193] 'range keys from in-memory index tree' (duration: 194.950563ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:16.382232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.44842ms","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-02-05T17:35:16.382254Z","caller":"traceutil/trace.go:171","msg":"trace[501288659] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:535; }","duration":"141.49604ms","start":"2026-02-05T17:35:16.240748Z","end":"2026-02-05T17:35:16.382244Z","steps":["trace[501288659] 'range keys from in-memory index tree' (duration: 141.393279ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:16.382424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"873.014731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:35:16.382499Z","caller":"traceutil/trace.go:171","msg":"trace[1823458121] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:535; }","duration":"873.129594ms","start":"2026-02-05T17:35:15.509349Z","end":"2026-02-05T17:35:16.382479Z","steps":["trace[1823458121] 'range keys from in-memory index tree' (duration: 872.901939ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:16.382532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:15.509334Z","time spent":"873.191825ms","remote":"127.0.0.1:55736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T17:35:16.382775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.50826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:35:16.38281Z","caller":"traceutil/trace.go:171","msg":"trace[1641218022] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:0; response_revision:535; }","duration":"468.57017ms","start":"2026-02-05T17:35:15.914225Z","end":"2026-02-05T17:35:16.382795Z","steps":["trace[1641218022] 'range keys from in-memory index tree' (duration: 468.449729ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:16.382844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:15.914207Z","time spent":"468.629102ms","remote":"127.0.0.1:55846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" "} {"level":"warn","ts":"2026-02-05T17:35:16.383111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.022434ms","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-02-05T17:35:16.383132Z","caller":"traceutil/trace.go:171","msg":"trace[1560767994] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:535; }","duration":"527.065764ms","start":"2026-02-05T17:35:15.856059Z","end":"2026-02-05T17:35:16.383125Z","steps":["trace[1560767994] 'range keys from in-memory index tree' (duration: 526.882441ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:16.383155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:15.856047Z","time spent":"527.098776ms","remote":"127.0.0.1:56012","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":"info","ts":"2026-02-05T17:35:22.868157Z","caller":"traceutil/trace.go:171","msg":"trace[649971433] linearizableReadLoop","detail":"{readStateIndex:595; appliedIndex:594; }","duration":"302.051628ms","start":"2026-02-05T17:35:22.566073Z","end":"2026-02-05T17:35:22.868125Z","steps":["trace[649971433] 'read index received' (duration: 301.842244ms)","trace[649971433] 'applied index is now lower than readState.Index' (duration: 208.104µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:35:22.868295Z","caller":"traceutil/trace.go:171","msg":"trace[869792246] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"342.545326ms","start":"2026-02-05T17:35:22.525741Z","end":"2026-02-05T17:35:22.868286Z","steps":["trace[869792246] 'process raft request' (duration: 342.20258ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:22.868429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:22.525727Z","time spent":"342.590967ms","remote":"127.0.0.1:56012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T17:35:22.868524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.613377ms","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-02-05T17:35:22.868599Z","caller":"traceutil/trace.go:171","msg":"trace[293168194] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:572; }","duration":"294.73033ms","start":"2026-02-05T17:35:22.573846Z","end":"2026-02-05T17:35:22.868577Z","steps":["trace[293168194] 'agreement among raft nodes before linearized reading' (duration: 294.550116ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:22.868767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.689629ms","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-02-05T17:35:22.868792Z","caller":"traceutil/trace.go:171","msg":"trace[1285496753] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:572; }","duration":"302.713079ms","start":"2026-02-05T17:35:22.56607Z","end":"2026-02-05T17:35:22.868783Z","steps":["trace[1285496753] 'agreement among raft nodes before linearized reading' (duration: 302.633408ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:22.868818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:22.566039Z","time spent":"302.772361ms","remote":"127.0.0.1:56012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-05T17:35:22.868957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.680269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-05T17:35:22.868985Z","caller":"traceutil/trace.go:171","msg":"trace[704232976] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:572; }","duration":"294.716709ms","start":"2026-02-05T17:35:22.57426Z","end":"2026-02-05T17:35:22.868977Z","steps":["trace[704232976] 'agreement among raft nodes before linearized reading' (duration: 294.593607ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:35:23.046155Z","caller":"traceutil/trace.go:171","msg":"trace[8910745] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"169.292011ms","start":"2026-02-05T17:35:22.87684Z","end":"2026-02-05T17:35:23.046132Z","steps":["trace[8910745] 'process raft request' (duration: 169.26058ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:35:23.046317Z","caller":"traceutil/trace.go:171","msg":"trace[1513456544] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"171.714713ms","start":"2026-02-05T17:35:22.874595Z","end":"2026-02-05T17:35:23.04631Z","steps":["trace[1513456544] 'process raft request' (duration: 171.377647ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:35:25.25489Z","caller":"traceutil/trace.go:171","msg":"trace[23829549] linearizableReadLoop","detail":"{readStateIndex:599; appliedIndex:598; }","duration":"378.193135ms","start":"2026-02-05T17:35:24.876679Z","end":"2026-02-05T17:35:25.254872Z","steps":["trace[23829549] 'read index received' (duration: 336.089597ms)","trace[23829549] 'applied index is now lower than readState.Index' (duration: 42.102628ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:35:25.255161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.47149ms","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-02-05T17:35:25.25518Z","caller":"traceutil/trace.go:171","msg":"trace[1025520379] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:575; }","duration":"378.52709ms","start":"2026-02-05T17:35:24.876648Z","end":"2026-02-05T17:35:25.255175Z","steps":["trace[1025520379] 'agreement among raft nodes before linearized reading' (duration: 378.428688ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:25.255197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:24.876634Z","time spent":"378.5583ms","remote":"127.0.0.1:56012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-05T17:35:25.255333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.243997ms","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-02-05T17:35:25.255345Z","caller":"traceutil/trace.go:171","msg":"trace[578377824] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:575; }","duration":"296.280337ms","start":"2026-02-05T17:35:24.959061Z","end":"2026-02-05T17:35:25.255341Z","steps":["trace[578377824] 'agreement among raft nodes before linearized reading' (duration: 296.242396ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:25.255439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.238868ms","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-02-05T17:35:25.255451Z","caller":"traceutil/trace.go:171","msg":"trace[764640719] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:575; }","duration":"205.256359ms","start":"2026-02-05T17:35:25.050191Z","end":"2026-02-05T17:35:25.255447Z","steps":["trace[764640719] 'agreement among raft nodes before linearized reading' (duration: 205.221418ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:25.255518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.33518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-05T17:35:25.255532Z","caller":"traceutil/trace.go:171","msg":"trace[535615140] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:575; }","duration":"205.372511ms","start":"2026-02-05T17:35:25.050155Z","end":"2026-02-05T17:35:25.255527Z","steps":["trace[535615140] 'agreement among raft nodes before linearized reading' (duration: 205.34049ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:35:25.493828Z","caller":"traceutil/trace.go:171","msg":"trace[689216557] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"233.353818ms","start":"2026-02-05T17:35:25.260459Z","end":"2026-02-05T17:35:25.493813Z","steps":["trace[689216557] 'process raft request' (duration: 233.331858ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:35:25.493925Z","caller":"traceutil/trace.go:171","msg":"trace[1271531553] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"234.523759ms","start":"2026-02-05T17:35:25.259396Z","end":"2026-02-05T17:35:25.49392Z","steps":["trace[1271531553] 'process raft request' (duration: 193.809525ms)","trace[1271531553] 'compare' (duration: 40.449159ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:35:25.493985Z","caller":"traceutil/trace.go:171","msg":"trace[1428240315] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"234.553609ms","start":"2026-02-05T17:35:25.259428Z","end":"2026-02-05T17:35:25.493981Z","steps":["trace[1428240315] 'process raft request' (duration: 234.340995ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:35:26.872513Z","caller":"traceutil/trace.go:171","msg":"trace[300164966] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"142.432339ms","start":"2026-02-05T17:35:26.73005Z","end":"2026-02-05T17:35:26.872483Z","steps":["trace[300164966] 'process raft request' (duration: 142.299056ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:28.25941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"911.929112ms","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-02-05T17:35:28.2595Z","caller":"traceutil/trace.go:171","msg":"trace[62227678] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:582; }","duration":"912.077255ms","start":"2026-02-05T17:35:27.347401Z","end":"2026-02-05T17:35:28.259478Z","steps":["trace[62227678] 'range keys from in-memory index tree' (duration: 911.739709ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:28.259551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:27.347354Z","time spent":"912.183117ms","remote":"127.0.0.1:56012","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-02-05T17:35:28.260486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"762.890943ms","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-02-05T17:35:28.260522Z","caller":"traceutil/trace.go:171","msg":"trace[2071484143] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:582; }","duration":"762.955144ms","start":"2026-02-05T17:35:27.497556Z","end":"2026-02-05T17:35:28.260511Z","steps":["trace[2071484143] 'range keys from in-memory index tree' (duration: 762.794361ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:28.26055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:27.497541Z","time spent":"763.003105ms","remote":"127.0.0.1:56012","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-02-05T17:35:28.260694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"762.221021ms","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-02-05T17:35:28.260719Z","caller":"traceutil/trace.go:171","msg":"trace[1851495776] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:582; }","duration":"762.257152ms","start":"2026-02-05T17:35:27.498454Z","end":"2026-02-05T17:35:28.260711Z","steps":["trace[1851495776] 'range keys from in-memory index tree' (duration: 762.1561ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:28.260743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:27.498448Z","time spent":"762.289152ms","remote":"127.0.0.1:56012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-05T17:35:28.260874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"760.709686ms","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-02-05T17:35:28.260897Z","caller":"traceutil/trace.go:171","msg":"trace[1614907805] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:582; }","duration":"760.744607ms","start":"2026-02-05T17:35:27.500146Z","end":"2026-02-05T17:35:28.26089Z","steps":["trace[1614907805] 'range keys from in-memory index tree' (duration: 760.648936ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:28.260925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:27.500139Z","time spent":"760.780898ms","remote":"127.0.0.1:56012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-05T17:35:28.609176Z","caller":"traceutil/trace.go:171","msg":"trace[741365720] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"343.272372ms","start":"2026-02-05T17:35:28.265879Z","end":"2026-02-05T17:35:28.609151Z","steps":["trace[741365720] 'process raft request' (duration: 343.141ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:28.610131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:28.265861Z","time spent":"343.391594ms","remote":"127.0.0.1:56012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:35:29.482038Z","caller":"traceutil/trace.go:171","msg":"trace[1449365011] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:607; }","duration":"969.281456ms","start":"2026-02-05T17:35:28.512734Z","end":"2026-02-05T17:35:29.482016Z","steps":["trace[1449365011] 'read index received' (duration: 96.412734ms)","trace[1449365011] 'applied index is now lower than readState.Index' (duration: 872.867862ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:35:29.482305Z","caller":"traceutil/trace.go:171","msg":"trace[2117873563] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"1.215440443s","start":"2026-02-05T17:35:28.266852Z","end":"2026-02-05T17:35:29.482292Z","steps":["trace[2117873563] 'process raft request' (duration: 1.214939015s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:29.482436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:28.266845Z","time spent":"1.215489313s","remote":"127.0.0.1:56012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:35:29.482652Z","caller":"traceutil/trace.go:171","msg":"trace[750758983] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"1.214791782s","start":"2026-02-05T17:35:28.267848Z","end":"2026-02-05T17:35:29.48264Z","steps":["trace[750758983] 'process raft request' (duration: 1.21405385s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:29.482714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:28.267842Z","time spent":"1.214838323s","remote":"127.0.0.1:56012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:35:29.482835Z","caller":"traceutil/trace.go:171","msg":"trace[1727258304] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"1.213892866s","start":"2026-02-05T17:35:28.268933Z","end":"2026-02-05T17:35:29.482826Z","steps":["trace[1727258304] 'process raft request' (duration: 1.213047923s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:29.482883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:28.268926Z","time spent":"1.213930537s","remote":"127.0.0.1:56012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T17:35:29.48301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"970.276032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:35:29.483039Z","caller":"traceutil/trace.go:171","msg":"trace[492841217] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:586; }","duration":"970.356774ms","start":"2026-02-05T17:35:28.512673Z","end":"2026-02-05T17:35:29.48303Z","steps":["trace[492841217] 'agreement among raft nodes before linearized reading' (duration: 970.309783ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:29.483071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:28.512641Z","time spent":"970.423525ms","remote":"127.0.0.1:55736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T17:35:29.483256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.112369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.3\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-02-05T17:35:29.483283Z","caller":"traceutil/trace.go:171","msg":"trace[1240545649] range","detail":"{range_begin:/registry/masterleases/199.204.45.3; range_end:; response_count:1; response_revision:586; }","duration":"491.161069ms","start":"2026-02-05T17:35:28.992114Z","end":"2026-02-05T17:35:29.483275Z","steps":["trace[1240545649] 'agreement among raft nodes before linearized reading' (duration: 491.065468ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:29.483309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:28.992101Z","time spent":"491.20155ms","remote":"127.0.0.1:55784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":154,"request content":"key:\"/registry/masterleases/199.204.45.3\" "} {"level":"warn","ts":"2026-02-05T17:35:30.781504Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.293052548s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-05T17:35:32.510083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000030567s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-05T17:35:32.510154Z","caller":"traceutil/trace.go:171","msg":"trace[2102136632] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000143588s","start":"2026-02-05T17:35:30.509996Z","end":"2026-02-05T17:35:32.510139Z","steps":["trace[2102136632] 'agreement among raft nodes before linearized reading' (duration: 2.000056877s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:32.510184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:30.509981Z","time spent":"2.000197179s","remote":"127.0.0.1:55736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/05 17:35:32 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-05T17:35:33.079339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.851857305s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:35:33.079432Z","caller":"traceutil/trace.go:171","msg":"trace[379417238] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:586; }","duration":"1.851956587s","start":"2026-02-05T17:35:31.22746Z","end":"2026-02-05T17:35:33.079416Z","steps":["trace[379417238] 'range keys from in-memory index tree' (duration: 1.851844595s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:33.079671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.297938612s","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-02-05T17:35:33.079765Z","caller":"traceutil/trace.go:171","msg":"trace[1596345355] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:610; }","duration":"2.569695384s","start":"2026-02-05T17:35:30.51003Z","end":"2026-02-05T17:35:33.079726Z","steps":["trace[1596345355] 'read index received' (duration: 271.62244ms)","trace[1596345355] 'applied index is now lower than readState.Index' (duration: 2.298071834s)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:35:33.07983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:29.488354Z","time spent":"3.591473368s","remote":"127.0.0.1:55784","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-05T17:35:33.080025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.466326751s","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-02-05T17:35:33.080046Z","caller":"traceutil/trace.go:171","msg":"trace[1368118624] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:586; }","duration":"2.466385501s","start":"2026-02-05T17:35:30.613653Z","end":"2026-02-05T17:35:33.080039Z","steps":["trace[1368118624] 'agreement among raft nodes before linearized reading' (duration: 2.46628576s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:33.080067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:30.613635Z","time spent":"2.466427573s","remote":"127.0.0.1:56012","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-02-05T17:35:33.080178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.47709ms","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-02-05T17:35:33.080211Z","caller":"traceutil/trace.go:171","msg":"trace[1469754749] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:586; }","duration":"676.512101ms","start":"2026-02-05T17:35:32.403689Z","end":"2026-02-05T17:35:33.080201Z","steps":["trace[1469754749] 'agreement among raft nodes before linearized reading' (duration: 676.43619ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:33.080232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.587935344s","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-02-05T17:35:33.080235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:32.403673Z","time spent":"676.555861ms","remote":"127.0.0.1:55954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":42,"response size":30,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"info","ts":"2026-02-05T17:35:33.080256Z","caller":"traceutil/trace.go:171","msg":"trace[2097991966] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:586; }","duration":"1.587967995s","start":"2026-02-05T17:35:31.492281Z","end":"2026-02-05T17:35:33.080249Z","steps":["trace[2097991966] 'agreement among raft nodes before linearized reading' (duration: 1.587916934s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:33.080276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:31.492126Z","time spent":"1.588145607s","remote":"127.0.0.1:56012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-05T17:35:33.08036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.591151236s","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-02-05T17:35:33.080377Z","caller":"traceutil/trace.go:171","msg":"trace[843902332] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:586; }","duration":"1.591167867s","start":"2026-02-05T17:35:31.489204Z","end":"2026-02-05T17:35:33.080371Z","steps":["trace[843902332] 'agreement among raft nodes before linearized reading' (duration: 1.591136856s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:33.080395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:31.489163Z","time spent":"1.591227637s","remote":"127.0.0.1:56012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-05T17:35:33.080434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.023004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:35:33.080454Z","caller":"traceutil/trace.go:171","msg":"trace[2079968676] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:586; }","duration":"570.060394ms","start":"2026-02-05T17:35:32.510387Z","end":"2026-02-05T17:35:33.080448Z","steps":["trace[2079968676] 'agreement among raft nodes before linearized reading' (duration: 570.027683ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:33.08047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:32.510378Z","time spent":"570.089154ms","remote":"127.0.0.1:55740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T17:35:33.080183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.570692505s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-05T17:35:33.080549Z","caller":"traceutil/trace.go:171","msg":"trace[261793200] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:586; }","duration":"1.57107353s","start":"2026-02-05T17:35:31.50947Z","end":"2026-02-05T17:35:33.080543Z","steps":["trace[261793200] 'agreement among raft nodes before linearized reading' (duration: 1.570668514s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:33.080568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:31.509461Z","time spent":"1.571101012s","remote":"127.0.0.1:56012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-05T17:35:33.60246Z","caller":"traceutil/trace.go:171","msg":"trace[731298694] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:611; }","duration":"507.425102ms","start":"2026-02-05T17:35:33.095021Z","end":"2026-02-05T17:35:33.602446Z","steps":["trace[731298694] 'read index received' (duration: 421.808801ms)","trace[731298694] 'applied index is now lower than readState.Index' (duration: 85.615821ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:35:33.602536Z","caller":"traceutil/trace.go:171","msg":"trace[1828878151] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"517.25093ms","start":"2026-02-05T17:35:33.085279Z","end":"2026-02-05T17:35:33.60253Z","steps":["trace[1828878151] 'process raft request' (duration: 431.543637ms)","trace[1828878151] 'compare' (duration: 85.224774ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:35:33.602593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:33.085266Z","time spent":"517.28313ms","remote":"127.0.0.1:55784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":116,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:35:33.602741Z","caller":"traceutil/trace.go:171","msg":"trace[1957096234] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"509.337572ms","start":"2026-02-05T17:35:33.093399Z","end":"2026-02-05T17:35:33.602737Z","steps":["trace[1957096234] 'process raft request' (duration: 508.975976ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:33.602766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:33.093384Z","time spent":"509.368393ms","remote":"127.0.0.1:56012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:35:33.602825Z","caller":"traceutil/trace.go:171","msg":"trace[650754899] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"509.353282ms","start":"2026-02-05T17:35:33.093466Z","end":"2026-02-05T17:35:33.602819Z","steps":["trace[650754899] 'process raft request' (duration: 508.935646ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:33.602845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:33.093448Z","time spent":"509.385853ms","remote":"127.0.0.1:56012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:35:33.602892Z","caller":"traceutil/trace.go:171","msg":"trace[1253963687] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"509.398193ms","start":"2026-02-05T17:35:33.09349Z","end":"2026-02-05T17:35:33.602888Z","steps":["trace[1253963687] 'process raft request' (duration: 508.926556ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:33.602912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:33.093487Z","time spent":"509.414493ms","remote":"127.0.0.1:56012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:35:33.602959Z","caller":"traceutil/trace.go:171","msg":"trace[649304860] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"507.903969ms","start":"2026-02-05T17:35:33.095051Z","end":"2026-02-05T17:35:33.602955Z","steps":["trace[649304860] 'process raft request' (duration: 507.381161ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:33.602979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:33.095046Z","time spent":"507.92171ms","remote":"127.0.0.1:56012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T17:35:33.603041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.019412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18916a32ee551d2a\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-02-05T17:35:33.603056Z","caller":"traceutil/trace.go:171","msg":"trace[447593453] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18916a32ee551d2a; range_end:; response_count:1; response_revision:591; }","duration":"508.049912ms","start":"2026-02-05T17:35:33.095001Z","end":"2026-02-05T17:35:33.603051Z","steps":["trace[447593453] 'agreement among raft nodes before linearized reading' (duration: 508.022201ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:33.603071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:33.094995Z","time spent":"508.071602ms","remote":"127.0.0.1:55820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":797,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.18916a32ee551d2a\" "} {"level":"warn","ts":"2026-02-05T17:35:33.603153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.56384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:35:33.603166Z","caller":"traceutil/trace.go:171","msg":"trace[1192069415] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:591; }","duration":"503.586271ms","start":"2026-02-05T17:35:33.099576Z","end":"2026-02-05T17:35:33.603162Z","steps":["trace[1192069415] 'agreement among raft nodes before linearized reading' (duration: 503.56564ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:33.603178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:33.099555Z","time spent":"503.620451ms","remote":"127.0.0.1:55736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-05T17:35:34.626114Z","caller":"traceutil/trace.go:171","msg":"trace[454443876] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"636.775426ms","start":"2026-02-05T17:35:33.989323Z","end":"2026-02-05T17:35:34.626098Z","steps":["trace[454443876] 'process raft request' (duration: 636.596723ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:34.626224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:33.989298Z","time spent":"636.859257ms","remote":"127.0.0.1:55930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16795,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:35:34.638152Z","caller":"traceutil/trace.go:171","msg":"trace[384146844] linearizableReadLoop","detail":"{readStateIndex:621; appliedIndex:620; }","duration":"477.175449ms","start":"2026-02-05T17:35:34.160964Z","end":"2026-02-05T17:35:34.63814Z","steps":["trace[384146844] 'read index received' (duration: 465.788008ms)","trace[384146844] 'applied index is now lower than readState.Index' (duration: 11.386791ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:35:34.638216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.23683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:35:34.638236Z","caller":"traceutil/trace.go:171","msg":"trace[1416065999] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:595; }","duration":"477.271811ms","start":"2026-02-05T17:35:34.160959Z","end":"2026-02-05T17:35:34.638231Z","steps":["trace[1416065999] 'agreement among raft nodes before linearized reading' (duration: 477.22316ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:34.638253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:34.160942Z","time spent":"477.307472ms","remote":"127.0.0.1:55736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T17:35:36.843142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.869558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:35:36.843481Z","caller":"traceutil/trace.go:171","msg":"trace[1611039104] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"333.105341ms","start":"2026-02-05T17:35:36.510365Z","end":"2026-02-05T17:35:36.84347Z","steps":["trace[1611039104] 'process raft request' (duration: 332.527122ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:36.843528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:36.510351Z","time spent":"333.149512ms","remote":"127.0.0.1:55820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":722,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-05T17:35:36.843672Z","caller":"traceutil/trace.go:171","msg":"trace[2121509622] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:607; }","duration":"333.02814ms","start":"2026-02-05T17:35:36.510148Z","end":"2026-02-05T17:35:36.843177Z","steps":["trace[2121509622] 'range keys from in-memory index tree' (duration: 332.547093ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:36.843689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:36.51013Z","time spent":"333.554631ms","remote":"127.0.0.1:55736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T17:35:37.517826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.861416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-jwxsh\" ","response":"range_response_count:1 size:17163"} {"level":"info","ts":"2026-02-05T17:35:37.517889Z","caller":"traceutil/trace.go:171","msg":"trace[1579580585] range","detail":"{range_begin:/registry/pods/kube-system/cilium-jwxsh; range_end:; response_count:1; response_revision:608; }","duration":"521.962588ms","start":"2026-02-05T17:35:36.995911Z","end":"2026-02-05T17:35:37.517873Z","steps":["trace[1579580585] 'range keys from in-memory index tree' (duration: 521.732304ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:37.517921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:36.99589Z","time spent":"522.023988ms","remote":"127.0.0.1:55930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17186,"request content":"key:\"/registry/pods/kube-system/cilium-jwxsh\" "} {"level":"info","ts":"2026-02-05T17:35:37.519268Z","caller":"traceutil/trace.go:171","msg":"trace[139415111] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"360.943847ms","start":"2026-02-05T17:35:37.158311Z","end":"2026-02-05T17:35:37.519255Z","steps":["trace[139415111] 'process raft request' (duration: 354.844652ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:37.51936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:37.158292Z","time spent":"361.003117ms","remote":"127.0.0.1:56012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:35:39.020852Z","caller":"traceutil/trace.go:171","msg":"trace[1844994864] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"138.292751ms","start":"2026-02-05T17:35:38.882545Z","end":"2026-02-05T17:35:39.020837Z","steps":["trace[1844994864] 'process raft request' (duration: 138.204859ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:35:39.215261Z","caller":"traceutil/trace.go:171","msg":"trace[2108320350] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"253.720428ms","start":"2026-02-05T17:35:38.961521Z","end":"2026-02-05T17:35:39.215242Z","steps":["trace[2108320350] 'process raft request' (duration: 250.908405ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:35:39.215272Z","caller":"traceutil/trace.go:171","msg":"trace[292913968] linearizableReadLoop","detail":"{readStateIndex:670; appliedIndex:669; }","duration":"222.553297ms","start":"2026-02-05T17:35:38.992698Z","end":"2026-02-05T17:35:39.215252Z","steps":["trace[292913968] 'read index received' (duration: 28.572808ms)","trace[292913968] 'applied index is now lower than readState.Index' (duration: 193.962989ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:35:39.215369Z","caller":"traceutil/trace.go:171","msg":"trace[1862300914] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"202.637542ms","start":"2026-02-05T17:35:39.012724Z","end":"2026-02-05T17:35:39.215362Z","steps":["trace[1862300914] 'process raft request' (duration: 202.451419ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:39.215594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.882482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.3\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-02-05T17:35:39.215624Z","caller":"traceutil/trace.go:171","msg":"trace[1037177972] range","detail":"{range_begin:/registry/masterleases/199.204.45.3; range_end:; response_count:1; response_revision:644; }","duration":"222.943052ms","start":"2026-02-05T17:35:38.992673Z","end":"2026-02-05T17:35:39.215616Z","steps":["trace[1037177972] 'agreement among raft nodes before linearized reading' (duration: 222.79564ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:35:39.280738Z","caller":"traceutil/trace.go:171","msg":"trace[2013310701] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"243.306585ms","start":"2026-02-05T17:35:39.03741Z","end":"2026-02-05T17:35:39.280717Z","steps":["trace[2013310701] 'process raft request' (duration: 242.875737ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:35:39.28133Z","caller":"traceutil/trace.go:171","msg":"trace[693340627] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"119.731902ms","start":"2026-02-05T17:35:39.161577Z","end":"2026-02-05T17:35:39.281309Z","steps":["trace[693340627] 'process raft request' (duration: 118.889799ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:39.28565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.785965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-jwxsh\" ","response":"range_response_count:1 size:17407"} {"level":"info","ts":"2026-02-05T17:35:39.285724Z","caller":"traceutil/trace.go:171","msg":"trace[484952882] range","detail":"{range_begin:/registry/pods/kube-system/cilium-jwxsh; range_end:; response_count:1; response_revision:646; }","duration":"272.900937ms","start":"2026-02-05T17:35:39.012789Z","end":"2026-02-05T17:35:39.28569Z","steps":["trace[484952882] 'agreement among raft nodes before linearized reading' (duration: 268.177065ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:35:39.373153Z","caller":"traceutil/trace.go:171","msg":"trace[1368702043] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"149.804093ms","start":"2026-02-05T17:35:39.22333Z","end":"2026-02-05T17:35:39.373134Z","steps":["trace[1368702043] 'process raft request' (duration: 149.709271ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:35:39.524581Z","caller":"traceutil/trace.go:171","msg":"trace[1234769402] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"124.506126ms","start":"2026-02-05T17:35:39.400058Z","end":"2026-02-05T17:35:39.524564Z","steps":["trace[1234769402] 'process raft request' (duration: 124.202351ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:35:42.621524Z","caller":"traceutil/trace.go:171","msg":"trace[617017400] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"240.207999ms","start":"2026-02-05T17:35:42.381274Z","end":"2026-02-05T17:35:42.621482Z","steps":["trace[617017400] 'process raft request' (duration: 200.038648ms)","trace[617017400] 'compare' (duration: 39.962338ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:35:42.666198Z","caller":"traceutil/trace.go:171","msg":"trace[188732453] linearizableReadLoop","detail":"{readStateIndex:727; appliedIndex:725; }","duration":"184.823699ms","start":"2026-02-05T17:35:42.481358Z","end":"2026-02-05T17:35:42.666182Z","steps":["trace[188732453] 'read index received' (duration: 99.907637ms)","trace[188732453] 'applied index is now lower than readState.Index' (duration: 84.915062ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:35:42.666234Z","caller":"traceutil/trace.go:171","msg":"trace[2062226967] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"279.856582ms","start":"2026-02-05T17:35:42.386346Z","end":"2026-02-05T17:35:42.666203Z","steps":["trace[2062226967] 'process raft request' (duration: 279.764881ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:42.666312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.93731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-02-05T17:35:42.666333Z","caller":"traceutil/trace.go:171","msg":"trace[724992954] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:699; }","duration":"184.995831ms","start":"2026-02-05T17:35:42.481332Z","end":"2026-02-05T17:35:42.666328Z","steps":["trace[724992954] 'agreement among raft nodes before linearized reading' (duration: 184.92236ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:42.666476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.087693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-02-05T17:35:42.666489Z","caller":"traceutil/trace.go:171","msg":"trace[477652908] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:699; }","duration":"185.106453ms","start":"2026-02-05T17:35:42.481378Z","end":"2026-02-05T17:35:42.666485Z","steps":["trace[477652908] 'agreement among raft nodes before linearized reading' (duration: 185.085333ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:42.666555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.135253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:897"} {"level":"info","ts":"2026-02-05T17:35:42.666568Z","caller":"traceutil/trace.go:171","msg":"trace[1337454419] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:699; }","duration":"185.153153ms","start":"2026-02-05T17:35:42.48141Z","end":"2026-02-05T17:35:42.666563Z","steps":["trace[1337454419] 'agreement among raft nodes before linearized reading' (duration: 185.127503ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:42.666632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.232095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-02-05T17:35:42.666643Z","caller":"traceutil/trace.go:171","msg":"trace[1727635873] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:699; }","duration":"185.248965ms","start":"2026-02-05T17:35:42.481391Z","end":"2026-02-05T17:35:42.66664Z","steps":["trace[1727635873] 'agreement among raft nodes before linearized reading' (duration: 185.227565ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:43.312404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.721723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:35:43.312485Z","caller":"traceutil/trace.go:171","msg":"trace[1108649920] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:702; }","duration":"154.813594ms","start":"2026-02-05T17:35:43.157658Z","end":"2026-02-05T17:35:43.312471Z","steps":["trace[1108649920] 'range keys from in-memory index tree' (duration: 154.667731ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:43.312661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.098378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:35:43.312689Z","caller":"traceutil/trace.go:171","msg":"trace[1736709481] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:702; }","duration":"155.175539ms","start":"2026-02-05T17:35:43.157507Z","end":"2026-02-05T17:35:43.312682Z","steps":["trace[1736709481] 'range keys from in-memory index tree' (duration: 154.900085ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:43.312858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.082778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:35:43.314227Z","caller":"traceutil/trace.go:171","msg":"trace[1237554947] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:702; }","duration":"156.482878ms","start":"2026-02-05T17:35:43.157724Z","end":"2026-02-05T17:35:43.314207Z","steps":["trace[1237554947] 'count revisions from in-memory index tree' (duration: 154.891134ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:35:47.711506Z","caller":"traceutil/trace.go:171","msg":"trace[673142837] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"107.984523ms","start":"2026-02-05T17:35:47.603474Z","end":"2026-02-05T17:35:47.711458Z","steps":["trace[673142837] 'process raft request' (duration: 107.293313ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:35:47.840467Z","caller":"traceutil/trace.go:171","msg":"trace[1688590893] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"118.87537ms","start":"2026-02-05T17:35:47.721571Z","end":"2026-02-05T17:35:47.840446Z","steps":["trace[1688590893] 'process raft request' (duration: 65.408806ms)","trace[1688590893] 'compare' (duration: 53.165119ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:35:48.110853Z","caller":"traceutil/trace.go:171","msg":"trace[1472819897] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"204.159964ms","start":"2026-02-05T17:35:47.906671Z","end":"2026-02-05T17:35:48.110831Z","steps":["trace[1472819897] 'process raft request' (duration: 203.533634ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:35:48.458955Z","caller":"traceutil/trace.go:171","msg":"trace[587117599] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"336.991303ms","start":"2026-02-05T17:35:48.121946Z","end":"2026-02-05T17:35:48.458937Z","steps":["trace[587117599] 'process raft request' (duration: 304.320323ms)","trace[587117599] 'compare' (duration: 32.590929ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:35:48.459053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:35:48.121924Z","time spent":"337.084124ms","remote":"127.0.0.1:55930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5009,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:35:48.622959Z","caller":"traceutil/trace.go:171","msg":"trace[82025999] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"102.699095ms","start":"2026-02-05T17:35:48.520245Z","end":"2026-02-05T17:35:48.622944Z","steps":["trace[82025999] 'process raft request' (duration: 63.231418ms)","trace[82025999] 'compare' (duration: 39.104462ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:35:48.623667Z","caller":"traceutil/trace.go:171","msg":"trace[734778579] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"101.646792ms","start":"2026-02-05T17:35:48.522013Z","end":"2026-02-05T17:35:48.62366Z","steps":["trace[734778579] 'process raft request' (duration: 100.643007ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:35:48.837845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.161056ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T17:35:48.838322Z","caller":"traceutil/trace.go:171","msg":"trace[1755319413] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"146.586927ms","start":"2026-02-05T17:35:48.691714Z","end":"2026-02-05T17:35:48.838301Z","steps":["trace[1755319413] 'process raft request' (duration: 146.229222ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:35:48.838796Z","caller":"traceutil/trace.go:171","msg":"trace[1607746131] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"201.556246ms","start":"2026-02-05T17:35:48.637224Z","end":"2026-02-05T17:35:48.83878Z","steps":["trace[1607746131] 'process raft request' (duration: 87.371296ms)","trace[1607746131] 'compare' (duration: 113.058615ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:35:48.969285Z","caller":"traceutil/trace.go:171","msg":"trace[433360985] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"123.585246ms","start":"2026-02-05T17:35:48.845665Z","end":"2026-02-05T17:35:48.969251Z","steps":["trace[433360985] 'process raft request' (duration: 119.170752ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:35:50.225666Z","caller":"traceutil/trace.go:171","msg":"trace[360789732] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"103.380866ms","start":"2026-02-05T17:35:50.122263Z","end":"2026-02-05T17:35:50.225644Z","steps":["trace[360789732] 'process raft request' (duration: 103.0412ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:35:50.22803Z","caller":"traceutil/trace.go:171","msg":"trace[1063568887] linearizableReadLoop","detail":"{readStateIndex:801; appliedIndex:801; }","duration":"103.271764ms","start":"2026-02-05T17:35:50.124744Z","end":"2026-02-05T17:35:50.228016Z","steps":["trace[1063568887] 'read index received' (duration: 103.266564ms)","trace[1063568887] 'applied index is now lower than readState.Index' (duration: 4.08µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:35:50.228378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.614629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-05T17:35:50.228622Z","caller":"traceutil/trace.go:171","msg":"trace[1414950828] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:770; }","duration":"103.870243ms","start":"2026-02-05T17:35:50.124727Z","end":"2026-02-05T17:35:50.228598Z","steps":["trace[1414950828] 'agreement among raft nodes before linearized reading' (duration: 103.518207ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:35:50.328215Z","caller":"traceutil/trace.go:171","msg":"trace[1459133489] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"195.26869ms","start":"2026-02-05T17:35:50.132928Z","end":"2026-02-05T17:35:50.328197Z","steps":["trace[1459133489] 'process raft request' (duration: 188.318102ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:36:05.980971Z","caller":"traceutil/trace.go:171","msg":"trace[1527951709] transaction","detail":"{read_only:false; response_revision:831; number_of_response:1; }","duration":"117.902764ms","start":"2026-02-05T17:36:05.863045Z","end":"2026-02-05T17:36:05.980947Z","steps":["trace[1527951709] 'process raft request' (duration: 117.711072ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:36:14.115562Z","caller":"traceutil/trace.go:171","msg":"trace[491553900] transaction","detail":"{read_only:false; response_revision:854; number_of_response:1; }","duration":"100.828782ms","start":"2026-02-05T17:36:14.01471Z","end":"2026-02-05T17:36:14.115539Z","steps":["trace[491553900] 'process raft request' (duration: 100.69491ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:36:14.625091Z","caller":"traceutil/trace.go:171","msg":"trace[6325720] transaction","detail":"{read_only:false; response_revision:858; number_of_response:1; }","duration":"108.546215ms","start":"2026-02-05T17:36:14.516522Z","end":"2026-02-05T17:36:14.625068Z","steps":["trace[6325720] 'process raft request' (duration: 108.408764ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:36:18.750243Z","caller":"traceutil/trace.go:171","msg":"trace[713204847] transaction","detail":"{read_only:false; response_revision:871; number_of_response:1; }","duration":"108.993166ms","start":"2026-02-05T17:36:18.641205Z","end":"2026-02-05T17:36:18.750198Z","steps":["trace[713204847] 'process raft request' (duration: 108.844504ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:36:31.450689Z","caller":"traceutil/trace.go:171","msg":"trace[2050802950] linearizableReadLoop","detail":"{readStateIndex:944; appliedIndex:943; }","duration":"435.277477ms","start":"2026-02-05T17:36:31.015396Z","end":"2026-02-05T17:36:31.450673Z","steps":["trace[2050802950] 'read index received' (duration: 435.157246ms)","trace[2050802950] 'applied index is now lower than readState.Index' (duration: 119.691µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:36:31.450808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.368168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:36:31.450823Z","caller":"traceutil/trace.go:171","msg":"trace[727704079] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:905; }","duration":"435.450799ms","start":"2026-02-05T17:36:31.015367Z","end":"2026-02-05T17:36:31.450818Z","steps":["trace[727704079] 'agreement among raft nodes before linearized reading' (duration: 435.366509ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:36:31.450852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:36:31.015353Z","time spent":"435.49487ms","remote":"127.0.0.1:55866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"info","ts":"2026-02-05T17:36:31.451066Z","caller":"traceutil/trace.go:171","msg":"trace[1205324236] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"590.784491ms","start":"2026-02-05T17:36:30.860276Z","end":"2026-02-05T17:36:31.451061Z","steps":["trace[1205324236] 'process raft request' (duration: 590.316746ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:36:31.451135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:36:30.860264Z","time spent":"590.835452ms","remote":"127.0.0.1:56012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T17:36:31.674896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.766542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:36:31.675017Z","caller":"traceutil/trace.go:171","msg":"trace[1251187470] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:905; }","duration":"165.920233ms","start":"2026-02-05T17:36:31.509079Z","end":"2026-02-05T17:36:31.674999Z","steps":["trace[1251187470] 'range keys from in-memory index tree' (duration: 165.711441ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:36:38.826851Z","caller":"traceutil/trace.go:171","msg":"trace[1366160069] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"159.397519ms","start":"2026-02-05T17:36:38.667406Z","end":"2026-02-05T17:36:38.826803Z","steps":["trace[1366160069] 'process raft request' (duration: 130.269157ms)","trace[1366160069] 'compare' (duration: 28.979801ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:36:38.868468Z","caller":"traceutil/trace.go:171","msg":"trace[861191791] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"157.65938ms","start":"2026-02-05T17:36:38.710791Z","end":"2026-02-05T17:36:38.86845Z","steps":["trace[861191791] 'process raft request' (duration: 157.370237ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:36:39.134016Z","caller":"traceutil/trace.go:171","msg":"trace[84615444] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"102.098032ms","start":"2026-02-05T17:36:39.031893Z","end":"2026-02-05T17:36:39.133991Z","steps":["trace[84615444] 'process raft request' (duration: 90.231371ms)","trace[84615444] 'compare' (duration: 11.742599ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:36:40.980313Z","caller":"traceutil/trace.go:171","msg":"trace[71403680] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"137.84273ms","start":"2026-02-05T17:36:40.842454Z","end":"2026-02-05T17:36:40.980297Z","steps":["trace[71403680] 'process raft request' (duration: 137.739758ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:36:41.327123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.465327ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:36:41.327213Z","caller":"traceutil/trace.go:171","msg":"trace[907970834] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:934; }","duration":"113.573188ms","start":"2026-02-05T17:36:41.213627Z","end":"2026-02-05T17:36:41.327201Z","steps":["trace[907970834] 'range keys from in-memory index tree' (duration: 113.445657ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:36:41.327465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.946644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:36:41.328047Z","caller":"traceutil/trace.go:171","msg":"trace[190990953] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:0; response_revision:934; }","duration":"258.566131ms","start":"2026-02-05T17:36:41.069468Z","end":"2026-02-05T17:36:41.328034Z","steps":["trace[190990953] 'range keys from in-memory index tree' (duration: 257.891913ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:36:41.465289Z","caller":"traceutil/trace.go:171","msg":"trace[19227893] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"132.941321ms","start":"2026-02-05T17:36:41.332324Z","end":"2026-02-05T17:36:41.465265Z","steps":["trace[19227893] 'process raft request' (duration: 132.81816ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:36:41.719233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.522203ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T17:36:41.720791Z","caller":"traceutil/trace.go:171","msg":"trace[921641985] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"197.455725ms","start":"2026-02-05T17:36:41.523304Z","end":"2026-02-05T17:36:41.72076Z","steps":["trace[921641985] 'process raft request' (duration: 197.363364ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:36:41.730399Z","caller":"traceutil/trace.go:171","msg":"trace[1099160312] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"207.441393ms","start":"2026-02-05T17:36:41.522938Z","end":"2026-02-05T17:36:41.73038Z","steps":["trace[1099160312] 'process raft request' (duration: 88.307694ms)","trace[1099160312] 'compare' (duration: 107.27753ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:36:57.694736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.576873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:36:57.694824Z","caller":"traceutil/trace.go:171","msg":"trace[1163906547] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1013; }","duration":"183.717124ms","start":"2026-02-05T17:36:57.511093Z","end":"2026-02-05T17:36:57.69481Z","steps":["trace[1163906547] 'range keys from in-memory index tree' (duration: 183.501452ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:36:58.208241Z","caller":"traceutil/trace.go:171","msg":"trace[1277250593] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"316.970203ms","start":"2026-02-05T17:36:57.891246Z","end":"2026-02-05T17:36:58.208216Z","steps":["trace[1277250593] 'process raft request' (duration: 316.835972ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:36:58.208394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:36:57.891235Z","time spent":"317.087494ms","remote":"127.0.0.1:56012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T17:36:58.696611Z","caller":"traceutil/trace.go:171","msg":"trace[1583241770] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"104.409293ms","start":"2026-02-05T17:36:58.590046Z","end":"2026-02-05T17:36:58.694455Z","steps":["trace[1583241770] 'process raft request' (duration: 104.224362ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:00.018811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.131511443s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T17:37:00.020212Z","caller":"traceutil/trace.go:171","msg":"trace[976171461] linearizableReadLoop","detail":"{readStateIndex:1062; appliedIndex:1061; }","duration":"1.241436512s","start":"2026-02-05T17:36:58.778756Z","end":"2026-02-05T17:37:00.020193Z","steps":["trace[976171461] 'read index received' (duration: 108.417485ms)","trace[976171461] 'applied index is now lower than readState.Index' (duration: 1.133016767s)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:37:00.020438Z","caller":"traceutil/trace.go:171","msg":"trace[1860671097] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"1.298173226s","start":"2026-02-05T17:36:58.72225Z","end":"2026-02-05T17:37:00.020423Z","steps":["trace[1860671097] 'process raft request' (duration: 164.975776ms)","trace[1860671097] 'compare' (duration: 1.131050399s)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:37:00.020668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:36:58.722233Z","time spent":"1.298390128s","remote":"127.0.0.1:56012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T17:37:00.021019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.242265431s","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-02-05T17:37:00.021192Z","caller":"traceutil/trace.go:171","msg":"trace[1238802029] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1016; }","duration":"1.242472883s","start":"2026-02-05T17:36:58.778704Z","end":"2026-02-05T17:37:00.021177Z","steps":["trace[1238802029] 'agreement among raft nodes before linearized reading' (duration: 1.24221516s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:00.021414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:36:58.778694Z","time spent":"1.242705266s","remote":"127.0.0.1:56012","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":"info","ts":"2026-02-05T17:37:00.118424Z","caller":"traceutil/trace.go:171","msg":"trace[197439819] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"768.277542ms","start":"2026-02-05T17:36:59.35012Z","end":"2026-02-05T17:37:00.118397Z","steps":["trace[197439819] 'process raft request' (duration: 767.972849ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:00.118617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:36:59.350092Z","time spent":"768.448083ms","remote":"127.0.0.1:56012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T17:37:00.508749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.349639659s","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-02-05T17:37:00.50883Z","caller":"traceutil/trace.go:171","msg":"trace[1143177739] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1017; }","duration":"1.34975939s","start":"2026-02-05T17:36:59.159049Z","end":"2026-02-05T17:37:00.508808Z","steps":["trace[1143177739] 'agreement among raft nodes before linearized reading' (duration: 961.044387ms)","trace[1143177739] 'range keys from in-memory index tree' (duration: 388.476621ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:37:00.508876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:36:59.159038Z","time spent":"1.349829041s","remote":"127.0.0.1:56012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-05T17:37:00.508875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.512042074s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.3\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-02-05T17:37:00.508947Z","caller":"traceutil/trace.go:171","msg":"trace[15082372] range","detail":"{range_begin:/registry/masterleases/199.204.45.3; range_end:; response_count:1; response_revision:1017; }","duration":"1.512166665s","start":"2026-02-05T17:36:58.996758Z","end":"2026-02-05T17:37:00.508925Z","steps":["trace[15082372] 'agreement among raft nodes before linearized reading' (duration: 1.123338351s)","trace[15082372] 'range keys from in-memory index tree' (duration: 388.641111ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:37:00.508989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:36:58.996743Z","time spent":"1.512236986s","remote":"127.0.0.1:55784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":154,"request content":"key:\"/registry/masterleases/199.204.45.3\" "} {"level":"warn","ts":"2026-02-05T17:37:00.509223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.63409282s","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-02-05T17:37:00.509257Z","caller":"traceutil/trace.go:171","msg":"trace[476527535] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1017; }","duration":"1.63416302s","start":"2026-02-05T17:36:58.875082Z","end":"2026-02-05T17:37:00.509245Z","steps":["trace[476527535] 'agreement among raft nodes before linearized reading' (duration: 1.244995783s)","trace[476527535] 'range keys from in-memory index tree' (duration: 389.084187ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:37:00.509286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:36:58.87506Z","time spent":"1.634217391s","remote":"127.0.0.1:56012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-05T17:37:00.509408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"998.633012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:37:00.509433Z","caller":"traceutil/trace.go:171","msg":"trace[1389806873] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1017; }","duration":"998.772533ms","start":"2026-02-05T17:36:59.510652Z","end":"2026-02-05T17:37:00.509424Z","steps":["trace[1389806873] 'agreement among raft nodes before linearized reading' (duration: 609.435814ms)","trace[1389806873] 'range keys from in-memory index tree' (duration: 389.307079ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:37:00.509461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:36:59.510632Z","time spent":"998.823943ms","remote":"127.0.0.1:55740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T17:37:00.509712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.227413ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T17:37:00.509761Z","caller":"traceutil/trace.go:171","msg":"trace[641147414] linearizableReadLoop","detail":"{readStateIndex:1064; appliedIndex:1063; }","duration":"297.943704ms","start":"2026-02-05T17:37:00.211808Z","end":"2026-02-05T17:37:00.509752Z","steps":["trace[641147414] 'read index received' (duration: 26.51µs)","trace[641147414] 'applied index is now lower than readState.Index' (duration: 297.916304ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:37:00.509862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.051545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-05T17:37:00.509884Z","caller":"traceutil/trace.go:171","msg":"trace[70889585] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1018; }","duration":"298.098246ms","start":"2026-02-05T17:37:00.211779Z","end":"2026-02-05T17:37:00.509877Z","steps":["trace[70889585] 'agreement among raft nodes before linearized reading' (duration: 298.003325ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:00.51356Z","caller":"traceutil/trace.go:171","msg":"trace[1852410470] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"484.250028ms","start":"2026-02-05T17:37:00.02569Z","end":"2026-02-05T17:37:00.50994Z","steps":["trace[1852410470] 'process raft request' (duration: 165.691282ms)","trace[1852410470] 'compare' (duration: 317.89689ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:37:00.517268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:00.025673Z","time spent":"491.430353ms","remote":"127.0.0.1:56012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T17:37:00.706557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.158441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:37:00.706635Z","caller":"traceutil/trace.go:171","msg":"trace[1476336228] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1018; }","duration":"188.256522ms","start":"2026-02-05T17:37:00.51836Z","end":"2026-02-05T17:37:00.706616Z","steps":["trace[1476336228] 'agreement among raft nodes before linearized reading' (duration: 90.892078ms)","trace[1476336228] 'range keys from in-memory index tree' (duration: 97.260953ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:37:00.789736Z","caller":"traceutil/trace.go:171","msg":"trace[340053722] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"269.723396ms","start":"2026-02-05T17:37:00.519988Z","end":"2026-02-05T17:37:00.789712Z","steps":["trace[340053722] 'process raft request' (duration: 269.643855ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:00.790221Z","caller":"traceutil/trace.go:171","msg":"trace[1250300473] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"270.22495ms","start":"2026-02-05T17:37:00.519983Z","end":"2026-02-05T17:37:00.790208Z","steps":["trace[1250300473] 'process raft request' (duration: 269.486483ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:00.79172Z","caller":"traceutil/trace.go:171","msg":"trace[414289525] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"270.183621ms","start":"2026-02-05T17:37:00.52149Z","end":"2026-02-05T17:37:00.791674Z","steps":["trace[414289525] 'process raft request' (duration: 268.18772ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:00.83078Z","caller":"traceutil/trace.go:171","msg":"trace[1364223040] linearizableReadLoop","detail":"{readStateIndex:1069; appliedIndex:1065; }","duration":"129.189411ms","start":"2026-02-05T17:37:00.701572Z","end":"2026-02-05T17:37:00.830761Z","steps":["trace[1364223040] 'read index received' (duration: 88.01103ms)","trace[1364223040] 'applied index is now lower than readState.Index' (duration: 41.177701ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:37:00.83087Z","caller":"traceutil/trace.go:171","msg":"trace[103121915] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"220.588193ms","start":"2026-02-05T17:37:00.610272Z","end":"2026-02-05T17:37:00.83086Z","steps":["trace[103121915] 'process raft request' (duration: 199.83678ms)","trace[103121915] 'compare' (duration: 20.576401ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:37:00.831171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.596095ms","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-02-05T17:37:00.831196Z","caller":"traceutil/trace.go:171","msg":"trace[1628943680] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1022; }","duration":"129.670335ms","start":"2026-02-05T17:37:00.701519Z","end":"2026-02-05T17:37:00.831189Z","steps":["trace[1628943680] 'agreement among raft nodes before linearized reading' (duration: 129.550634ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:00.831377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.931886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:37:00.831396Z","caller":"traceutil/trace.go:171","msg":"trace[514695013] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1022; }","duration":"122.990946ms","start":"2026-02-05T17:37:00.7084Z","end":"2026-02-05T17:37:00.831391Z","steps":["trace[514695013] 'agreement among raft nodes before linearized reading' (duration: 122.957046ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:01.546217Z","caller":"traceutil/trace.go:171","msg":"trace[121993523] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"143.466891ms","start":"2026-02-05T17:37:01.402727Z","end":"2026-02-05T17:37:01.546194Z","steps":["trace[121993523] 'process raft request' (duration: 143.288859ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:05.024942Z","caller":"traceutil/trace.go:171","msg":"trace[783460960] linearizableReadLoop","detail":"{readStateIndex:1082; appliedIndex:1081; }","duration":"188.118598ms","start":"2026-02-05T17:37:04.836787Z","end":"2026-02-05T17:37:05.024905Z","steps":["trace[783460960] 'read index received' (duration: 187.802605ms)","trace[783460960] 'applied index is now lower than readState.Index' (duration: 314.753µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:37:05.02543Z","caller":"traceutil/trace.go:171","msg":"trace[707007997] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"189.478813ms","start":"2026-02-05T17:37:04.835936Z","end":"2026-02-05T17:37:05.025415Z","steps":["trace[707007997] 'process raft request' (duration: 188.754426ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:05.025794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.977937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-05T17:37:05.02583Z","caller":"traceutil/trace.go:171","msg":"trace[1962339589] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1034; }","duration":"189.047887ms","start":"2026-02-05T17:37:04.836771Z","end":"2026-02-05T17:37:05.025819Z","steps":["trace[1962339589] 'agreement among raft nodes before linearized reading' (duration: 188.876936ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:05.028913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.518863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-05T17:37:05.028958Z","caller":"traceutil/trace.go:171","msg":"trace[846585009] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1034; }","duration":"191.575974ms","start":"2026-02-05T17:37:04.837369Z","end":"2026-02-05T17:37:05.028945Z","steps":["trace[846585009] 'agreement among raft nodes before linearized reading' (duration: 191.435123ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:05.029176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.03047ms","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-02-05T17:37:05.029204Z","caller":"traceutil/trace.go:171","msg":"trace[217008940] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1034; }","duration":"101.06137ms","start":"2026-02-05T17:37:04.928134Z","end":"2026-02-05T17:37:05.029195Z","steps":["trace[217008940] 'agreement among raft nodes before linearized reading' (duration: 100.972759ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:05.032894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.773472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/kubeadm:get-nodes\" ","response":"range_response_count:1 size:305"} {"level":"info","ts":"2026-02-05T17:37:05.033014Z","caller":"traceutil/trace.go:171","msg":"trace[809806766] range","detail":"{range_begin:/registry/clusterroles/kubeadm:get-nodes; range_end:; response_count:1; response_revision:1034; }","duration":"145.932483ms","start":"2026-02-05T17:37:04.887064Z","end":"2026-02-05T17:37:05.032996Z","steps":["trace[809806766] 'agreement among raft nodes before linearized reading' (duration: 145.713961ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:05.03349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.148922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/local-path-provisioner\" ","response":"range_response_count:1 size:1106"} {"level":"info","ts":"2026-02-05T17:37:05.033565Z","caller":"traceutil/trace.go:171","msg":"trace[1144329838] range","detail":"{range_begin:/registry/clusterroles/local-path-provisioner; range_end:; response_count:1; response_revision:1034; }","duration":"121.420206ms","start":"2026-02-05T17:37:04.912139Z","end":"2026-02-05T17:37:05.033559Z","steps":["trace[1144329838] 'agreement among raft nodes before linearized reading' (duration: 121.086833ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:05.231618Z","caller":"traceutil/trace.go:171","msg":"trace[913479124] linearizableReadLoop","detail":"{readStateIndex:1085; appliedIndex:1082; }","duration":"125.491625ms","start":"2026-02-05T17:37:05.106103Z","end":"2026-02-05T17:37:05.231594Z","steps":["trace[913479124] 'read index received' (duration: 44.279927ms)","trace[913479124] 'applied index is now lower than readState.Index' (duration: 81.210898ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:37:05.231745Z","caller":"traceutil/trace.go:171","msg":"trace[80208912] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"194.143886ms","start":"2026-02-05T17:37:05.037575Z","end":"2026-02-05T17:37:05.231719Z","steps":["trace[80208912] 'process raft request' (duration: 112.767406ms)","trace[80208912] 'compare' (duration: 81.083306ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:37:05.232398Z","caller":"traceutil/trace.go:171","msg":"trace[1675870651] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"194.777032ms","start":"2026-02-05T17:37:05.037604Z","end":"2026-02-05T17:37:05.232381Z","steps":["trace[1675870651] 'process raft request' (duration: 193.920444ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:05.2326Z","caller":"traceutil/trace.go:171","msg":"trace[924980557] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"189.396828ms","start":"2026-02-05T17:37:05.043191Z","end":"2026-02-05T17:37:05.232588Z","steps":["trace[924980557] 'process raft request' (duration: 188.372417ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:05.232856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.740387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/kubeadm:get-nodes\" ","response":"range_response_count:1 size:305"} {"level":"info","ts":"2026-02-05T17:37:05.232905Z","caller":"traceutil/trace.go:171","msg":"trace[1837790937] range","detail":"{range_begin:/registry/clusterroles/kubeadm:get-nodes; range_end:; response_count:1; response_revision:1037; }","duration":"126.858437ms","start":"2026-02-05T17:37:05.106035Z","end":"2026-02-05T17:37:05.232893Z","steps":["trace[1837790937] 'agreement among raft nodes before linearized reading' (duration: 126.744596ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:11.40528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.675947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:service-controller\" ","response":"range_response_count:1 size:724"} {"level":"info","ts":"2026-02-05T17:37:11.405684Z","caller":"traceutil/trace.go:171","msg":"trace[881548650] range","detail":"{range_begin:/registry/clusterroles/system:controller:service-controller; range_end:; response_count:1; response_revision:1052; }","duration":"214.107772ms","start":"2026-02-05T17:37:11.191561Z","end":"2026-02-05T17:37:11.405669Z","steps":["trace[881548650] 'range keys from in-memory index tree' (duration: 213.610306ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:11.40591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.406077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:statefulset-controller\" ","response":"range_response_count:1 size:990"} {"level":"info","ts":"2026-02-05T17:37:11.406112Z","caller":"traceutil/trace.go:171","msg":"trace[1234895282] range","detail":"{range_begin:/registry/clusterroles/system:controller:statefulset-controller; range_end:; response_count:1; response_revision:1052; }","duration":"194.622029ms","start":"2026-02-05T17:37:11.211472Z","end":"2026-02-05T17:37:11.406094Z","steps":["trace[1234895282] 'range keys from in-memory index tree' (duration: 194.342156ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:11.406282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.297264ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:37:11.406441Z","caller":"traceutil/trace.go:171","msg":"trace[1846436426] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1052; }","duration":"193.457316ms","start":"2026-02-05T17:37:11.212976Z","end":"2026-02-05T17:37:11.406433Z","steps":["trace[1846436426] 'range keys from in-memory index tree' (duration: 193.292544ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:11.40686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.634406ms","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-02-05T17:37:11.406955Z","caller":"traceutil/trace.go:171","msg":"trace[1216277104] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1052; }","duration":"111.748247ms","start":"2026-02-05T17:37:11.295198Z","end":"2026-02-05T17:37:11.406947Z","steps":["trace[1216277104] 'range keys from in-memory index tree' (duration: 111.557025ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:11.410621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.811868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-05T17:37:11.410781Z","caller":"traceutil/trace.go:171","msg":"trace[262340606] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1052; }","duration":"109.912209ms","start":"2026-02-05T17:37:11.300786Z","end":"2026-02-05T17:37:11.410698Z","steps":["trace[262340606] 'range keys from in-memory index tree' (duration: 109.684957ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:11.411178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.804057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-05T17:37:11.411312Z","caller":"traceutil/trace.go:171","msg":"trace[1715751714] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1052; }","duration":"108.872777ms","start":"2026-02-05T17:37:11.302336Z","end":"2026-02-05T17:37:11.411209Z","steps":["trace[1715751714] 'range keys from in-memory index tree' (duration: 108.670686ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:11.539868Z","caller":"traceutil/trace.go:171","msg":"trace[1444672891] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"111.909708ms","start":"2026-02-05T17:37:11.427945Z","end":"2026-02-05T17:37:11.539855Z","steps":["trace[1444672891] 'process raft request' (duration: 80.960272ms)","trace[1444672891] 'compare' (duration: 30.712514ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:37:11.540221Z","caller":"traceutil/trace.go:171","msg":"trace[1029960197] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"112.217413ms","start":"2026-02-05T17:37:11.427987Z","end":"2026-02-05T17:37:11.540205Z","steps":["trace[1029960197] 'process raft request' (duration: 111.738867ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:13.706991Z","caller":"traceutil/trace.go:171","msg":"trace[190721330] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"127.703139ms","start":"2026-02-05T17:37:13.579265Z","end":"2026-02-05T17:37:13.706968Z","steps":["trace[190721330] 'process raft request' (duration: 127.661968ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:13.707205Z","caller":"traceutil/trace.go:171","msg":"trace[840088468] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"128.404855ms","start":"2026-02-05T17:37:13.578768Z","end":"2026-02-05T17:37:13.707173Z","steps":["trace[840088468] 'process raft request' (duration: 70.421524ms)","trace[840088468] 'compare' (duration: 57.605248ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:37:13.70745Z","caller":"traceutil/trace.go:171","msg":"trace[661145815] linearizableReadLoop","detail":"{readStateIndex:1108; appliedIndex:1107; }","duration":"128.401425ms","start":"2026-02-05T17:37:13.579039Z","end":"2026-02-05T17:37:13.70744Z","steps":["trace[661145815] 'read index received' (duration: 70.11017ms)","trace[661145815] 'applied index is now lower than readState.Index' (duration: 58.290355ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:37:13.707519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.463096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:persistent-volume-provisioner\" ","response":"range_response_count:1 size:812"} {"level":"info","ts":"2026-02-05T17:37:13.708067Z","caller":"traceutil/trace.go:171","msg":"trace[610929057] range","detail":"{range_begin:/registry/clusterroles/system:persistent-volume-provisioner; range_end:; response_count:1; response_revision:1060; }","duration":"129.037312ms","start":"2026-02-05T17:37:13.579016Z","end":"2026-02-05T17:37:13.708054Z","steps":["trace[610929057] 'agreement among raft nodes before linearized reading' (duration: 128.455736ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:13.708382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.184814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:37:13.708463Z","caller":"traceutil/trace.go:171","msg":"trace[2024009413] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1060; }","duration":"129.276365ms","start":"2026-02-05T17:37:13.579176Z","end":"2026-02-05T17:37:13.708453Z","steps":["trace[2024009413] 'agreement among raft nodes before linearized reading' (duration: 129.178104ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:13.964048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.809704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:pvc-protection-controller\" ","response":"range_response_count:1 size:775"} {"level":"info","ts":"2026-02-05T17:37:13.964519Z","caller":"traceutil/trace.go:171","msg":"trace[1537615095] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:pvc-protection-controller; range_end:; response_count:1; response_revision:1060; }","duration":"157.31334ms","start":"2026-02-05T17:37:13.807188Z","end":"2026-02-05T17:37:13.964501Z","steps":["trace[1537615095] 'range keys from in-memory index tree' (duration: 156.738604ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:13.965046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.392168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:public-info-viewer\" ","response":"range_response_count:1 size:613"} {"level":"info","ts":"2026-02-05T17:37:13.965145Z","caller":"traceutil/trace.go:171","msg":"trace[239092482] range","detail":"{range_begin:/registry/clusterroles/system:public-info-viewer; range_end:; response_count:1; response_revision:1060; }","duration":"104.517459ms","start":"2026-02-05T17:37:13.860617Z","end":"2026-02-05T17:37:13.965135Z","steps":["trace[239092482] 'range keys from in-memory index tree' (duration: 104.332607ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:15.695196Z","caller":"traceutil/trace.go:171","msg":"trace[1327758146] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"118.476913ms","start":"2026-02-05T17:37:15.576704Z","end":"2026-02-05T17:37:15.695181Z","steps":["trace[1327758146] 'process raft request' (duration: 73.387191ms)","trace[1327758146] 'compare' (duration: 44.89249ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:37:19.199968Z","caller":"traceutil/trace.go:171","msg":"trace[1142767299] linearizableReadLoop","detail":"{readStateIndex:1124; appliedIndex:1123; }","duration":"152.040966ms","start":"2026-02-05T17:37:19.047907Z","end":"2026-02-05T17:37:19.199948Z","steps":["trace[1142767299] 'read index received' (duration: 117.664623ms)","trace[1142767299] 'applied index is now lower than readState.Index' (duration: 34.375303ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:37:19.200147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.224728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-node-lease/default\" ","response":"range_response_count:1 size:187"} {"level":"info","ts":"2026-02-05T17:37:19.200171Z","caller":"traceutil/trace.go:171","msg":"trace[1369429981] range","detail":"{range_begin:/registry/serviceaccounts/kube-node-lease/default; range_end:; response_count:1; response_revision:1073; }","duration":"152.283188ms","start":"2026-02-05T17:37:19.047881Z","end":"2026-02-05T17:37:19.200164Z","steps":["trace[1369429981] 'agreement among raft nodes before linearized reading' (duration: 152.174197ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:19.200423Z","caller":"traceutil/trace.go:171","msg":"trace[2136865193] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"153.843245ms","start":"2026-02-05T17:37:19.046156Z","end":"2026-02-05T17:37:19.199999Z","steps":["trace[2136865193] 'process raft request' (duration: 119.469431ms)","trace[2136865193] 'compare' (duration: 34.214802ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:37:31.478392Z","caller":"traceutil/trace.go:171","msg":"trace[1314049789] linearizableReadLoop","detail":"{readStateIndex:1162; appliedIndex:1161; }","duration":"185.011491ms","start":"2026-02-05T17:37:31.293346Z","end":"2026-02-05T17:37:31.478357Z","steps":["trace[1314049789] 'read index received' (duration: 184.830789ms)","trace[1314049789] 'applied index is now lower than readState.Index' (duration: 180.072µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:37:31.478839Z","caller":"traceutil/trace.go:171","msg":"trace[263801852] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"191.549522ms","start":"2026-02-05T17:37:31.28728Z","end":"2026-02-05T17:37:31.478829Z","steps":["trace[263801852] 'process raft request' (duration: 190.960587ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:31.479201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.839248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/local-path-provisioner-6f56467975-xbdz7\" ","response":"range_response_count:1 size:3885"} {"level":"info","ts":"2026-02-05T17:37:31.479605Z","caller":"traceutil/trace.go:171","msg":"trace[568357718] range","detail":"{range_begin:/registry/pods/local-path-storage/local-path-provisioner-6f56467975-xbdz7; range_end:; response_count:1; response_revision:1109; }","duration":"186.265042ms","start":"2026-02-05T17:37:31.293331Z","end":"2026-02-05T17:37:31.479596Z","steps":["trace[568357718] 'agreement among raft nodes before linearized reading' (duration: 185.772228ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:31.479451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.33209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/default\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-02-05T17:37:31.479773Z","caller":"traceutil/trace.go:171","msg":"trace[998679413] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/default; range_end:; response_count:1; response_revision:1109; }","duration":"174.672873ms","start":"2026-02-05T17:37:31.305091Z","end":"2026-02-05T17:37:31.479764Z","steps":["trace[998679413] 'agreement among raft nodes before linearized reading' (duration: 174.33102ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:31.91749Z","caller":"traceutil/trace.go:171","msg":"trace[621531788] transaction","detail":"{read_only:false; response_revision:1110; number_of_response:1; }","duration":"130.289433ms","start":"2026-02-05T17:37:31.787181Z","end":"2026-02-05T17:37:31.917471Z","steps":["trace[621531788] 'process raft request' (duration: 129.9819ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T17:37:32.344848Z","caller":"traceutil/trace.go:171","msg":"trace[2086090566] linearizableReadLoop","detail":"{readStateIndex:1164; appliedIndex:1163; }","duration":"547.519512ms","start":"2026-02-05T17:37:31.797313Z","end":"2026-02-05T17:37:32.344833Z","steps":["trace[2086090566] 'read index received' (duration: 120.080996ms)","trace[2086090566] 'applied index is now lower than readState.Index' (duration: 427.437866ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T17:37:32.345138Z","caller":"traceutil/trace.go:171","msg":"trace[1722834605] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"556.663468ms","start":"2026-02-05T17:37:31.788443Z","end":"2026-02-05T17:37:32.345107Z","steps":["trace[1722834605] 'process raft request' (duration: 537.570398ms)","trace[1722834605] 'compare' (duration: 18.765957ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T17:37:32.345247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:31.788434Z","time spent":"556.742219ms","remote":"127.0.0.1:56012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T17:37:32.345482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.187758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-05T17:37:32.34554Z","caller":"traceutil/trace.go:171","msg":"trace[2054676645] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1111; }","duration":"548.272588ms","start":"2026-02-05T17:37:31.797261Z","end":"2026-02-05T17:37:32.345534Z","steps":["trace[2054676645] 'agreement among raft nodes before linearized reading' (duration: 548.140217ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:32.345575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:31.797248Z","time spent":"548.321529ms","remote":"127.0.0.1:56012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-05T17:37:32.347594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.050596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:37:32.347641Z","caller":"traceutil/trace.go:171","msg":"trace[1969960586] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1111; }","duration":"513.123066ms","start":"2026-02-05T17:37:31.834511Z","end":"2026-02-05T17:37:32.347634Z","steps":["trace[1969960586] 'agreement among raft nodes before linearized reading' (duration: 513.035875ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:32.347659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.635655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T17:37:32.347726Z","caller":"traceutil/trace.go:171","msg":"trace[756094719] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1111; }","duration":"341.844297ms","start":"2026-02-05T17:37:32.005865Z","end":"2026-02-05T17:37:32.34771Z","steps":["trace[756094719] 'agreement among raft nodes before linearized reading' (duration: 341.712076ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:32.347764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:32.005847Z","time spent":"341.909947ms","remote":"127.0.0.1:41148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-02-05T17:37:32.347914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.275432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-05T17:37:32.347937Z","caller":"traceutil/trace.go:171","msg":"trace[1884177693] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1111; }","duration":"268.324622ms","start":"2026-02-05T17:37:32.079607Z","end":"2026-02-05T17:37:32.347931Z","steps":["trace[1884177693] 'agreement among raft nodes before linearized reading' (duration: 268.284771ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:32.347685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:31.834498Z","time spent":"513.180336ms","remote":"127.0.0.1:41216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-05T17:37:32.348548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.622905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/sh.helm.release.v1.local-path-provisioner.v1\" ","response":"range_response_count:1 size:12727"} {"level":"info","ts":"2026-02-05T17:37:32.348602Z","caller":"traceutil/trace.go:171","msg":"trace[713849800] range","detail":"{range_begin:/registry/secrets/local-path-storage/sh.helm.release.v1.local-path-provisioner.v1; range_end:; response_count:1; response_revision:1111; }","duration":"488.701336ms","start":"2026-02-05T17:37:31.859892Z","end":"2026-02-05T17:37:32.348593Z","steps":["trace[713849800] 'agreement among raft nodes before linearized reading' (duration: 487.806507ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T17:37:32.348636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T17:37:31.859879Z","time spent":"488.750356ms","remote":"127.0.0.1:55846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":12750,"request content":"key:\"/registry/secrets/local-path-storage/sh.helm.release.v1.local-path-provisioner.v1\" "}