{"level":"warn","ts":"2026-02-02T17:41:30.107605Z","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-02T17:41:30.10768Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.72: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.72:2380","--initial-cluster=instance=https://199.204.45.72:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.72:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.72: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-02T17:41:30.107766Z","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-02T17:41:30.107799Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-02-02T17:41:30.107827Z","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-02T17:41:30.108477Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"]} {"level":"info","ts":"2026-02-02T17:41:30.108595Z","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.72:2380"],"listen-peer-urls":["https://199.204.45.72:2380"],"advertise-client-urls":["https://199.204.45.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.72: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-02T17:41:30.115557Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.723445ms"} {"level":"info","ts":"2026-02-02T17:41:30.127527Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"50e03cebfd94cbba","cluster-id":"120c03c1d5b80f94"} {"level":"info","ts":"2026-02-02T17:41:30.127652Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=()"} {"level":"info","ts":"2026-02-02T17:41:30.127722Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 0"} {"level":"info","ts":"2026-02-02T17:41:30.127762Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 50e03cebfd94cbba [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-02T17:41:30.127799Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 1"} {"level":"info","ts":"2026-02-02T17:41:30.127885Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"warn","ts":"2026-02-02T17:41:30.13783Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-02T17:41:30.144183Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-02T17:41:30.154324Z","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-02T17:41:30.164308Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"50e03cebfd94cbba","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-02T17:41:30.165798Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"50e03cebfd94cbba","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-02T17:41:30.16599Z","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-02T17:41:30.166016Z","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-02T17:41:30.166061Z","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-02T17:41:30.166303Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"info","ts":"2026-02-02T17:41:30.16642Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"120c03c1d5b80f94","local-member-id":"50e03cebfd94cbba","added-peer-id":"50e03cebfd94cbba","added-peer-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-02-02T17:41:30.173017Z","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-02T17:41:30.183619Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"50e03cebfd94cbba","initial-advertise-peer-urls":["https://199.204.45.72:2380"],"listen-peer-urls":["https://199.204.45.72:2380"],"advertise-client-urls":["https://199.204.45.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-02T17:41:30.183708Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-02T17:41:30.173177Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-02-02T17:41:30.185561Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-02-02T17:41:30.32829Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba is starting a new election at term 1"} {"level":"info","ts":"2026-02-02T17:41:30.328351Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became pre-candidate at term 1"} {"level":"info","ts":"2026-02-02T17:41:30.328374Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgPreVoteResp from 50e03cebfd94cbba at term 1"} {"level":"info","ts":"2026-02-02T17:41:30.328432Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became candidate at term 2"} {"level":"info","ts":"2026-02-02T17:41:30.328442Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgVoteResp from 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-02-02T17:41:30.328459Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became leader at term 2"} {"level":"info","ts":"2026-02-02T17:41:30.32847Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 50e03cebfd94cbba elected leader 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-02-02T17:41:30.329336Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-02T17:41:30.329974Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"50e03cebfd94cbba","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.72:2379]}","request-path":"/0/members/50e03cebfd94cbba/attributes","cluster-id":"120c03c1d5b80f94","publish-timeout":"7s"} {"level":"info","ts":"2026-02-02T17:41:30.329999Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-02T17:41:30.330347Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"120c03c1d5b80f94","local-member-id":"50e03cebfd94cbba","cluster-version":"3.5"} {"level":"info","ts":"2026-02-02T17:41:30.330421Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-02T17:41:30.330446Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-02T17:41:30.330457Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-02T17:41:30.339691Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-02T17:41:30.342146Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-02T17:41:30.342199Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-02T17:41:30.348986Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.72:2379"} {"level":"info","ts":"2026-02-02T17:41:46.198236Z","caller":"traceutil/trace.go:171","msg":"trace[1391019036] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"226.708572ms","start":"2026-02-02T17:41:45.9715Z","end":"2026-02-02T17:41:46.198209Z","steps":["trace[1391019036] 'process raft request' (duration: 135.840269ms)","trace[1391019036] 'compare' (duration: 90.747922ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:41:46.198541Z","caller":"traceutil/trace.go:171","msg":"trace[318280377] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"217.342925ms","start":"2026-02-02T17:41:45.981182Z","end":"2026-02-02T17:41:46.198525Z","steps":["trace[318280377] 'process raft request' (duration: 217.317855ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:41:46.198622Z","caller":"traceutil/trace.go:171","msg":"trace[2023906965] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"217.792998ms","start":"2026-02-02T17:41:45.980818Z","end":"2026-02-02T17:41:46.198611Z","steps":["trace[2023906965] 'process raft request' (duration: 217.643807ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:41:46.198681Z","caller":"traceutil/trace.go:171","msg":"trace[116768167] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"217.693968ms","start":"2026-02-02T17:41:45.980981Z","end":"2026-02-02T17:41:46.198675Z","steps":["trace[116768167] 'process raft request' (duration: 217.505586ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:41:46.198762Z","caller":"traceutil/trace.go:171","msg":"trace[410095873] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"220.745083ms","start":"2026-02-02T17:41:45.978007Z","end":"2026-02-02T17:41:46.198752Z","steps":["trace[410095873] 'process raft request' (duration: 220.4007ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:41:54.068471Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680217594237915800,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-02T17:41:54.137719Z","caller":"traceutil/trace.go:171","msg":"trace[104842109] linearizableReadLoop","detail":"{readStateIndex:430; appliedIndex:429; }","duration":"570.021463ms","start":"2026-02-02T17:41:53.567681Z","end":"2026-02-02T17:41:54.137703Z","steps":["trace[104842109] 'read index received' (duration: 569.908502ms)","trace[104842109] 'applied index is now lower than readState.Index' (duration: 112.431µs)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:41:54.137912Z","caller":"traceutil/trace.go:171","msg":"trace[1299328086] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"617.450866ms","start":"2026-02-02T17:41:53.520455Z","end":"2026-02-02T17:41:54.137906Z","steps":["trace[1299328086] 'process raft request' (duration: 617.173283ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:41:54.138181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:41:53.520445Z","time spent":"617.487046ms","remote":"127.0.0.1:55482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T17:41:54.138285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.602658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.72\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-02T17:41:54.138305Z","caller":"traceutil/trace.go:171","msg":"trace[1594372187] range","detail":"{range_begin:/registry/masterleases/199.204.45.72; range_end:; response_count:1; response_revision:413; }","duration":"570.646568ms","start":"2026-02-02T17:41:53.567653Z","end":"2026-02-02T17:41:54.1383Z","steps":["trace[1594372187] 'agreement among raft nodes before linearized reading' (duration: 570.610677ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:41:54.138321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:41:53.567642Z","time spent":"570.676728ms","remote":"127.0.0.1:43736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.204.45.72\" "} {"level":"warn","ts":"2026-02-02T17:41:54.138397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.062579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-02T17:41:54.138414Z","caller":"traceutil/trace.go:171","msg":"trace[967184614] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:413; }","duration":"502.10182ms","start":"2026-02-02T17:41:53.636308Z","end":"2026-02-02T17:41:54.13841Z","steps":["trace[967184614] 'agreement among raft nodes before linearized reading' (duration: 502.075419ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:41:54.138426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:41:53.636295Z","time spent":"502.12772ms","remote":"127.0.0.1:55482","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-02T17:41:54.138512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.067149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:41:54.138528Z","caller":"traceutil/trace.go:171","msg":"trace[7338914] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:413; }","duration":"385.105669ms","start":"2026-02-02T17:41:53.753419Z","end":"2026-02-02T17:41:54.138525Z","steps":["trace[7338914] 'agreement among raft nodes before linearized reading' (duration: 385.079169ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:41:54.13854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:41:53.753405Z","time spent":"385.132741ms","remote":"127.0.0.1:43720","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-02T17:41:54.279661Z","caller":"traceutil/trace.go:171","msg":"trace[807863246] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"137.342882ms","start":"2026-02-02T17:41:54.142303Z","end":"2026-02-02T17:41:54.279646Z","steps":["trace[807863246] 'process raft request' (duration: 137.262201ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:41:54.279979Z","caller":"traceutil/trace.go:171","msg":"trace[2006573765] linearizableReadLoop","detail":"{readStateIndex:431; appliedIndex:430; }","duration":"139.276477ms","start":"2026-02-02T17:41:54.140696Z","end":"2026-02-02T17:41:54.279973Z","steps":["trace[2006573765] 'read index received' (duration: 136.816038ms)","trace[2006573765] 'applied index is now lower than readState.Index' (duration: 2.459319ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:41:54.28004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.327858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:41:54.28009Z","caller":"traceutil/trace.go:171","msg":"trace[620103877] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:414; }","duration":"139.398068ms","start":"2026-02-02T17:41:54.140684Z","end":"2026-02-02T17:41:54.280082Z","steps":["trace[620103877] 'agreement among raft nodes before linearized reading' (duration: 139.325998ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:41:57.166611Z","caller":"traceutil/trace.go:171","msg":"trace[423763609] linearizableReadLoop","detail":"{readStateIndex:440; appliedIndex:439; }","duration":"414.344293ms","start":"2026-02-02T17:41:56.752246Z","end":"2026-02-02T17:41:57.16659Z","steps":["trace[423763609] 'read index received' (duration: 414.248572ms)","trace[423763609] 'applied index is now lower than readState.Index' (duration: 94.951µs)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:41:57.166692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.435454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:41:57.166715Z","caller":"traceutil/trace.go:171","msg":"trace[1138175246] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:422; }","duration":"414.496844ms","start":"2026-02-02T17:41:56.752211Z","end":"2026-02-02T17:41:57.166708Z","steps":["trace[1138175246] 'agreement among raft nodes before linearized reading' (duration: 414.446383ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:41:57.166735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:41:56.752198Z","time spent":"414.532605ms","remote":"127.0.0.1:43720","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-02T17:41:57.166781Z","caller":"traceutil/trace.go:171","msg":"trace[429564142] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"674.990789ms","start":"2026-02-02T17:41:56.491772Z","end":"2026-02-02T17:41:57.166763Z","steps":["trace[429564142] 'process raft request' (duration: 674.722737ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:41:57.166874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:41:56.491761Z","time spent":"675.0585ms","remote":"127.0.0.1:55482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T17:42:00.321061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.381258ms","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-02T17:42:00.321131Z","caller":"traceutil/trace.go:171","msg":"trace[376652635] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:466; }","duration":"557.481309ms","start":"2026-02-02T17:41:59.763638Z","end":"2026-02-02T17:42:00.321119Z","steps":["trace[376652635] 'range keys from in-memory index tree' (duration: 557.302268ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:00.321174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:41:59.763625Z","time spent":"557.542819ms","remote":"127.0.0.1:55320","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-02T17:42:00.321284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.405994ms","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-02T17:42:00.321308Z","caller":"traceutil/trace.go:171","msg":"trace[14215626] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:466; }","duration":"164.470074ms","start":"2026-02-02T17:42:00.156832Z","end":"2026-02-02T17:42:00.321302Z","steps":["trace[14215626] 'range keys from in-memory index tree' (duration: 164.331993ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:01.563267Z","caller":"traceutil/trace.go:171","msg":"trace[849815805] linearizableReadLoop","detail":"{readStateIndex:513; appliedIndex:512; }","duration":"305.134148ms","start":"2026-02-02T17:42:01.258108Z","end":"2026-02-02T17:42:01.563243Z","steps":["trace[849815805] 'read index received' (duration: 304.920086ms)","trace[849815805] 'applied index is now lower than readState.Index' (duration: 213.032µs)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:42:01.563488Z","caller":"traceutil/trace.go:171","msg":"trace[72618821] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"369.81691ms","start":"2026-02-02T17:42:01.193665Z","end":"2026-02-02T17:42:01.563482Z","steps":["trace[72618821] 'process raft request' (duration: 369.382827ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:01.563616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:01.193649Z","time spent":"369.860121ms","remote":"127.0.0.1:43762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-02T17:42:01.563866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.760133ms","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-02T17:42:01.563884Z","caller":"traceutil/trace.go:171","msg":"trace[360025552] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:493; }","duration":"305.830353ms","start":"2026-02-02T17:42:01.258049Z","end":"2026-02-02T17:42:01.563879Z","steps":["trace[360025552] 'agreement among raft nodes before linearized reading' (duration: 305.678592ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:01.5639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:01.258024Z","time spent":"305.872953ms","remote":"127.0.0.1:55482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-02T17:42:01.563975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.795433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:42:01.56399Z","caller":"traceutil/trace.go:171","msg":"trace[293186058] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:493; }","duration":"171.860934ms","start":"2026-02-02T17:42:01.392123Z","end":"2026-02-02T17:42:01.563984Z","steps":["trace[293186058] 'agreement among raft nodes before linearized reading' (duration: 171.840753ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:02.840202Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680217594237915967,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-02T17:42:03.273202Z","caller":"traceutil/trace.go:171","msg":"trace[1727328285] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"934.48352ms","start":"2026-02-02T17:42:02.338702Z","end":"2026-02-02T17:42:03.273185Z","steps":["trace[1727328285] 'process raft request' (duration: 934.375399ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:03.273323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:02.338687Z","time spent":"934.57837ms","remote":"127.0.0.1:55482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T17:42:03.331529Z","caller":"traceutil/trace.go:171","msg":"trace[1280913363] linearizableReadLoop","detail":"{readStateIndex:521; appliedIndex:520; }","duration":"992.221215ms","start":"2026-02-02T17:42:02.339292Z","end":"2026-02-02T17:42:03.331513Z","steps":["trace[1280913363] 'read index received' (duration: 934.017026ms)","trace[1280913363] 'applied index is now lower than readState.Index' (duration: 58.203349ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:42:03.331716Z","caller":"traceutil/trace.go:171","msg":"trace[1235946757] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"939.232583ms","start":"2026-02-02T17:42:02.392476Z","end":"2026-02-02T17:42:03.331708Z","steps":["trace[1235946757] 'process raft request' (duration: 938.83142ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:03.331771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:02.39245Z","time spent":"939.282803ms","remote":"127.0.0.1:43762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-02T17:42:03.331941Z","caller":"traceutil/trace.go:171","msg":"trace[341417723] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"489.565882ms","start":"2026-02-02T17:42:02.84237Z","end":"2026-02-02T17:42:03.331936Z","steps":["trace[341417723] 'process raft request' (duration: 489.100249ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:03.331974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:02.842351Z","time spent":"489.601143ms","remote":"127.0.0.1:55482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T17:42:03.332082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"992.79271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-02T17:42:03.332098Z","caller":"traceutil/trace.go:171","msg":"trace[1120262014] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:502; }","duration":"992.819331ms","start":"2026-02-02T17:42:02.339274Z","end":"2026-02-02T17:42:03.332093Z","steps":["trace[1120262014] 'agreement among raft nodes before linearized reading' (duration: 992.75392ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:03.332113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:02.339267Z","time spent":"992.842371ms","remote":"127.0.0.1:55482","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-02T17:42:03.332192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"891.568618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-02T17:42:03.332206Z","caller":"traceutil/trace.go:171","msg":"trace[1583515111] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:502; }","duration":"891.613608ms","start":"2026-02-02T17:42:02.440588Z","end":"2026-02-02T17:42:03.332201Z","steps":["trace[1583515111] 'agreement among raft nodes before linearized reading' (duration: 891.576798ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:03.332219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:02.440555Z","time spent":"891.661609ms","remote":"127.0.0.1:55482","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-02T17:42:03.33233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.862171ms","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-02T17:42:03.332345Z","caller":"traceutil/trace.go:171","msg":"trace[799174946] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:0; response_revision:502; }","duration":"109.916491ms","start":"2026-02-02T17:42:03.222423Z","end":"2026-02-02T17:42:03.33234Z","steps":["trace[799174946] 'agreement among raft nodes before linearized reading' (duration: 109.892741ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:03.332437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.90316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-02-02T17:42:03.33245Z","caller":"traceutil/trace.go:171","msg":"trace[853043301] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:502; }","duration":"140.91859ms","start":"2026-02-02T17:42:03.191528Z","end":"2026-02-02T17:42:03.332446Z","steps":["trace[853043301] 'agreement among raft nodes before linearized reading' (duration: 140.87014ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:03.332529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.171136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-02-02T17:42:03.332541Z","caller":"traceutil/trace.go:171","msg":"trace[1450841622] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:502; }","duration":"339.215416ms","start":"2026-02-02T17:42:02.993322Z","end":"2026-02-02T17:42:03.332537Z","steps":["trace[1450841622] 'agreement among raft nodes before linearized reading' (duration: 339.180206ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:03.332553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:02.993301Z","time spent":"339.248446ms","remote":"127.0.0.1:43762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4105,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"warn","ts":"2026-02-02T17:42:03.332632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.191286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-02-02T17:42:03.332646Z","caller":"traceutil/trace.go:171","msg":"trace[1677388702] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:502; }","duration":"541.238656ms","start":"2026-02-02T17:42:02.791404Z","end":"2026-02-02T17:42:03.332642Z","steps":["trace[1677388702] 'agreement among raft nodes before linearized reading' (duration: 541.199185ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:03.332659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:02.791386Z","time spent":"541.270876ms","remote":"127.0.0.1:43762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":1,"response size":13251,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"warn","ts":"2026-02-02T17:42:03.332718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.578271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:42:03.332731Z","caller":"traceutil/trace.go:171","msg":"trace[97293377] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:502; }","duration":"579.626481ms","start":"2026-02-02T17:42:02.753099Z","end":"2026-02-02T17:42:03.332725Z","steps":["trace[97293377] 'agreement among raft nodes before linearized reading' (duration: 579.608941ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:03.332741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:02.75308Z","time spent":"579.658531ms","remote":"127.0.0.1:43720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-02T17:42:03.332829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.156271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-02-02T17:42:03.33284Z","caller":"traceutil/trace.go:171","msg":"trace[763032847] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:502; }","duration":"741.194221ms","start":"2026-02-02T17:42:02.591642Z","end":"2026-02-02T17:42:03.332837Z","steps":["trace[763032847] 'agreement among raft nodes before linearized reading' (duration: 741.157591ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:03.332852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:02.591629Z","time spent":"741.220791ms","remote":"127.0.0.1:43762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":9342,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"warn","ts":"2026-02-02T17:42:03.332922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"864.733709ms","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-02T17:42:03.332933Z","caller":"traceutil/trace.go:171","msg":"trace[1104270593] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:502; }","duration":"864.758939ms","start":"2026-02-02T17:42:02.468171Z","end":"2026-02-02T17:42:03.33293Z","steps":["trace[1104270593] 'agreement among raft nodes before linearized reading' (duration: 864.725279ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:03.332945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:02.468163Z","time spent":"864.779869ms","remote":"127.0.0.1:55482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-02T17:42:03.647387Z","caller":"traceutil/trace.go:171","msg":"trace[1712659112] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"171.042113ms","start":"2026-02-02T17:42:03.476324Z","end":"2026-02-02T17:42:03.647366Z","steps":["trace[1712659112] 'process raft request' (duration: 163.86046ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:03.651942Z","caller":"traceutil/trace.go:171","msg":"trace[509715764] linearizableReadLoop","detail":"{readStateIndex:528; appliedIndex:526; }","duration":"134.973727ms","start":"2026-02-02T17:42:03.516849Z","end":"2026-02-02T17:42:03.651822Z","steps":["trace[509715764] 'read index received' (duration: 11.422895ms)","trace[509715764] 'applied index is now lower than readState.Index' (duration: 123.546832ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:03.652707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.822613ms","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-02T17:42:03.652803Z","caller":"traceutil/trace.go:171","msg":"trace[93471868] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:0; response_revision:508; }","duration":"135.934414ms","start":"2026-02-02T17:42:03.516844Z","end":"2026-02-02T17:42:03.652779Z","steps":["trace[93471868] 'agreement among raft nodes before linearized reading' (duration: 135.680392ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:04.679228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.900229ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-02T17:42:04.679285Z","caller":"traceutil/trace.go:171","msg":"trace[2134915681] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"591.044143ms","start":"2026-02-02T17:42:04.08823Z","end":"2026-02-02T17:42:04.679274Z","steps":["trace[2134915681] 'process raft request' (duration: 66.957072ms)","trace[2134915681] 'compare' (duration: 523.670748ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:04.679311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:04.088207Z","time spent":"591.094803ms","remote":"127.0.0.1:43762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187455,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-02T17:42:04.716504Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680217594237915992,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-02T17:42:04.727227Z","caller":"traceutil/trace.go:171","msg":"trace[1952492558] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"541.614609ms","start":"2026-02-02T17:42:04.185585Z","end":"2026-02-02T17:42:04.7272Z","steps":["trace[1952492558] 'process raft request' (duration: 541.457288ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:04.727393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:04.18556Z","time spent":"541.7403ms","remote":"127.0.0.1:43762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":24220,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T17:42:04.774281Z","caller":"traceutil/trace.go:171","msg":"trace[1355494546] linearizableReadLoop","detail":"{readStateIndex:536; appliedIndex:535; }","duration":"559.113308ms","start":"2026-02-02T17:42:04.215141Z","end":"2026-02-02T17:42:04.774255Z","steps":["trace[1355494546] 'read index received' (duration: 512.708077ms)","trace[1355494546] 'applied index is now lower than readState.Index' (duration: 46.404281ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:04.774868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.702852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-02-02T17:42:04.774918Z","caller":"traceutil/trace.go:171","msg":"trace[30333949] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:515; }","duration":"559.810664ms","start":"2026-02-02T17:42:04.215095Z","end":"2026-02-02T17:42:04.774905Z","steps":["trace[30333949] 'agreement among raft nodes before linearized reading' (duration: 559.614952ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:04.774951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:04.215069Z","time spent":"559.875694ms","remote":"127.0.0.1:43762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4355,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"warn","ts":"2026-02-02T17:42:04.775052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.407698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-02-02T17:42:04.775083Z","caller":"traceutil/trace.go:171","msg":"trace[862049550] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"234.954306ms","start":"2026-02-02T17:42:04.540121Z","end":"2026-02-02T17:42:04.775076Z","steps":["trace[862049550] 'process raft request' (duration: 233.808727ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:04.775086Z","caller":"traceutil/trace.go:171","msg":"trace[450339565] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:515; }","duration":"251.476218ms","start":"2026-02-02T17:42:04.523597Z","end":"2026-02-02T17:42:04.775074Z","steps":["trace[450339565] 'agreement among raft nodes before linearized reading' (duration: 251.347307ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:04.775345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.503162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188252"} {"level":"info","ts":"2026-02-02T17:42:04.775364Z","caller":"traceutil/trace.go:171","msg":"trace[671628599] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:515; }","duration":"140.543952ms","start":"2026-02-02T17:42:04.634815Z","end":"2026-02-02T17:42:04.775359Z","steps":["trace[671628599] 'agreement among raft nodes before linearized reading' (duration: 140.396381ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:05.987934Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680217594237916005,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:06.48875Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680217594237916005,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:06.663063Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.21021045s","expected-duration":"1s"} {"level":"info","ts":"2026-02-02T17:42:06.663965Z","caller":"traceutil/trace.go:171","msg":"trace[1945179635] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"1.211184728s","start":"2026-02-02T17:42:05.452754Z","end":"2026-02-02T17:42:06.663939Z","steps":["trace[1945179635] 'process raft request' (duration: 1.210860975s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:06.6655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:05.452733Z","time spent":"1.211334969s","remote":"127.0.0.1:55482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T17:42:08.758662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000496523s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-02T17:42:08.759646Z","caller":"traceutil/trace.go:171","msg":"trace[30546819] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00156735s","start":"2026-02-02T17:42:06.75806Z","end":"2026-02-02T17:42:08.759627Z","steps":["trace[30546819] 'agreement among raft nodes before linearized reading' (duration: 2.000547713s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:08.759899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:06.758033Z","time spent":"2.001849353s","remote":"127.0.0.1:43720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/02 17:42:08 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-02T17:42:10.427364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.914020711s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-02T17:42:10.427489Z","caller":"traceutil/trace.go:171","msg":"trace[1073203436] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"4.914174912s","start":"2026-02-02T17:42:05.513269Z","end":"2026-02-02T17:42:10.427444Z","steps":["trace[1073203436] 'agreement among raft nodes before linearized reading' (duration: 4.914024561s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:10.427824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:05.513265Z","time spent":"4.914543425s","remote":"127.0.0.1:55482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} 2026/02/02 17:42:10 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-02T17:42:10.505527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.965786495s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-02T17:42:10.505899Z","caller":"traceutil/trace.go:171","msg":"trace[2051879621] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"4.966020837s","start":"2026-02-02T17:42:05.53962Z","end":"2026-02-02T17:42:10.505641Z","steps":["trace[2051879621] 'agreement among raft nodes before linearized reading' (duration: 4.965782355s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:10.506003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:05.539605Z","time spent":"4.966382439s","remote":"127.0.0.1:55482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} 2026/02/02 17:42:10 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-02T17:42:10.759542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999883641s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-02T17:42:10.759859Z","caller":"traceutil/trace.go:171","msg":"trace[1962011420] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000260434s","start":"2026-02-02T17:42:08.759578Z","end":"2026-02-02T17:42:10.759838Z","steps":["trace[1962011420] 'agreement among raft nodes before linearized reading' (duration: 1.999916901s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:10.759963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:08.759552Z","time spent":"2.000396704s","remote":"127.0.0.1:43724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-02T17:42:11.682669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000399724s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-02T17:42:11.6828Z","caller":"traceutil/trace.go:171","msg":"trace[246626982] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000590535s","start":"2026-02-02T17:42:09.682183Z","end":"2026-02-02T17:42:11.682774Z","steps":["trace[246626982] 'agreement among raft nodes before linearized reading' (duration: 2.000419954s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:11.682867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:09.68216Z","time spent":"2.000694706s","remote":"127.0.0.1:43720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-02T17:42:12.536839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:05.536031Z","time spent":"7.000800846s","remote":"127.0.0.1:43762","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-02T17:42:12.582174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:05.581401Z","time spent":"7.000749625s","remote":"127.0.0.1:43762","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-02T17:42:12.622566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:05.622164Z","time spent":"7.000395232s","remote":"127.0.0.1:55482","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-02T17:42:12.7696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999898685s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-02T17:42:12.769972Z","caller":"traceutil/trace.go:171","msg":"trace[1350297787] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000321717s","start":"2026-02-02T17:42:10.769627Z","end":"2026-02-02T17:42:12.769948Z","steps":["trace[1350297787] 'agreement among raft nodes before linearized reading' (duration: 1.999914964s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:12.770182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:10.769595Z","time spent":"2.000570609s","remote":"127.0.0.1:43720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-02T17:42:13.036249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:06.036096Z","time spent":"7.00014413s","remote":"127.0.0.1:55386","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-02T17:42:13.670678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.997825863s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-02T17:42:13.670843Z","caller":"traceutil/trace.go:171","msg":"trace[244400585] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"4.998069085s","start":"2026-02-02T17:42:08.672755Z","end":"2026-02-02T17:42:13.670824Z","steps":["trace[244400585] 'agreement among raft nodes before linearized reading' (duration: 4.997857723s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:13.670923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:08.672735Z","time spent":"4.998171725s","remote":"127.0.0.1:55482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} 2026/02/02 17:42:13 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-02T17:42:14.771131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000098062s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-02T17:42:14.771241Z","caller":"traceutil/trace.go:171","msg":"trace[1411749445] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000262624s","start":"2026-02-02T17:42:12.770953Z","end":"2026-02-02T17:42:14.771215Z","steps":["trace[1411749445] 'agreement among raft nodes before linearized reading' (duration: 2.000114833s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:14.771306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:12.770936Z","time spent":"2.000358144s","remote":"127.0.0.1:43724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-02T17:42:15.426008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.995275106s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-02T17:42:15.426106Z","caller":"traceutil/trace.go:171","msg":"trace[1428989175] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"2.995462608s","start":"2026-02-02T17:42:12.430623Z","end":"2026-02-02T17:42:15.426086Z","steps":["trace[1428989175] 'agreement among raft nodes before linearized reading' (duration: 2.995306807s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:15.426175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:12.430586Z","time spent":"2.995577969s","remote":"127.0.0.1:55482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} 2026/02/02 17:42:15 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-02T17:42:15.442844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.929578857s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-02T17:42:15.442948Z","caller":"traceutil/trace.go:171","msg":"trace[1908705851] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; }","duration":"9.929717627s","start":"2026-02-02T17:42:05.513221Z","end":"2026-02-02T17:42:15.442939Z","steps":["trace[1908705851] 'agreement among raft nodes before linearized reading' (duration: 9.929610207s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:15.443086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:05.513206Z","time spent":"9.929859778s","remote":"127.0.0.1:55482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} 2026/02/02 17:42:15 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-02T17:42:15.506244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.998642255s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-02T17:42:15.506378Z","caller":"traceutil/trace.go:171","msg":"trace[1954336281] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"2.998826716s","start":"2026-02-02T17:42:12.50751Z","end":"2026-02-02T17:42:15.506337Z","steps":["trace[1954336281] 'agreement among raft nodes before linearized reading' (duration: 2.998667485s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:15.506452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:12.507489Z","time spent":"2.998930406s","remote":"127.0.0.1:55482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} 2026/02/02 17:42:15 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-02T17:42:15.816275Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"9.151739509s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-02T17:42:15.82465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.999900544s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-02T17:42:15.82472Z","caller":"traceutil/trace.go:171","msg":"trace[440304204] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; }","duration":"9.999990285s","start":"2026-02-02T17:42:05.824712Z","end":"2026-02-02T17:42:15.824703Z","steps":["trace[440304204] 'agreement among raft nodes before linearized reading' (duration: 9.999897604s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:15.824759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:05.824671Z","time spent":"10.000080145s","remote":"127.0.0.1:55482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} 2026/02/02 17:42:15 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-02T17:42:16.078407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.702101ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:5"} {"level":"warn","ts":"2026-02-02T17:42:16.080875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:09.328543Z","time spent":"6.752327153s","remote":"127.0.0.1:50024","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-02T17:42:16.081196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.457557903s","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-02T17:42:16.081263Z","caller":"traceutil/trace.go:171","msg":"trace[1909693726] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:518; }","duration":"5.457629456s","start":"2026-02-02T17:42:10.623621Z","end":"2026-02-02T17:42:16.08125Z","steps":["trace[1909693726] 'range keys from in-memory index tree' (duration: 5.457545262s)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:16.086873Z","caller":"traceutil/trace.go:171","msg":"trace[1371108912] linearizableReadLoop","detail":"{readStateIndex:543; appliedIndex:538; }","duration":"10.59910789s","start":"2026-02-02T17:42:05.487753Z","end":"2026-02-02T17:42:16.086861Z","steps":["trace[1371108912] 'read index received' (duration: 1.176094741s)","trace[1371108912] 'applied index is now lower than readState.Index' (duration: 9.423012219s)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:16.09263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"10.604860262s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:42:16.092695Z","caller":"traceutil/trace.go:171","msg":"trace[1658134231] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:0; response_revision:521; }","duration":"10.604938125s","start":"2026-02-02T17:42:05.487725Z","end":"2026-02-02T17:42:16.092663Z","steps":["trace[1658134231] 'agreement among raft nodes before linearized reading' (duration: 10.599233485s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:16.092731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:05.487709Z","time spent":"10.605015589s","remote":"127.0.0.1:55408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":0,"response size":27,"request content":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" "} {"level":"info","ts":"2026-02-02T17:42:16.093296Z","caller":"traceutil/trace.go:171","msg":"trace[414508805] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"5.3266722s","start":"2026-02-02T17:42:10.766614Z","end":"2026-02-02T17:42:16.093286Z","steps":["trace[414508805] 'process raft request' (duration: 5.325791334s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:16.096962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"10.56108585s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188495"} {"level":"warn","ts":"2026-02-02T17:42:16.097081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:10.766592Z","time spent":"5.326733472s","remote":"127.0.0.1:43800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-02T17:42:16.097383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.783982619s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:42:16.097532Z","caller":"traceutil/trace.go:171","msg":"trace[1315287945] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:527; }","duration":"10.561174343s","start":"2026-02-02T17:42:05.535826Z","end":"2026-02-02T17:42:16.097001Z","steps":["trace[1315287945] 'agreement among raft nodes before linearized reading' (duration: 10.560992173s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:16.097567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:05.535811Z","time spent":"10.561747892s","remote":"127.0.0.1:43762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":1,"response size":188517,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-02-02T17:42:16.097507Z","caller":"traceutil/trace.go:171","msg":"trace[362026750] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:527; }","duration":"5.784118885s","start":"2026-02-02T17:42:10.31338Z","end":"2026-02-02T17:42:16.097499Z","steps":["trace[362026750] 'agreement among raft nodes before linearized reading' (duration: 5.783980258s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:16.097634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:10.313372Z","time spent":"5.784254442s","remote":"127.0.0.1:50106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 "} {"level":"info","ts":"2026-02-02T17:42:16.098014Z","caller":"traceutil/trace.go:171","msg":"trace[2015798846] transaction","detail":"{read_only:false; number_of_response:1; response_revision:522; }","duration":"3.469949222s","start":"2026-02-02T17:42:12.628015Z","end":"2026-02-02T17:42:16.097965Z","steps":["trace[2015798846] 'process raft request' (duration: 3.464867614s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:16.098208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.798683111s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:42:16.09834Z","caller":"traceutil/trace.go:171","msg":"trace[1232678332] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:527; }","duration":"5.798824188s","start":"2026-02-02T17:42:10.299509Z","end":"2026-02-02T17:42:16.098333Z","steps":["trace[1232678332] 'agreement among raft nodes before linearized reading' (duration: 5.798380305s)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:16.098296Z","caller":"traceutil/trace.go:171","msg":"trace[1788314180] transaction","detail":"{read_only:false; number_of_response:1; response_revision:522; }","duration":"3.456637992s","start":"2026-02-02T17:42:12.641652Z","end":"2026-02-02T17:42:16.09829Z","steps":["trace[1788314180] 'process raft request' (duration: 3.45129869s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:16.09838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:10.299497Z","time spent":"5.798858399s","remote":"127.0.0.1:50098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 "} {"level":"warn","ts":"2026-02-02T17:42:16.09852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:12.641633Z","time spent":"3.456778809s","remote":"127.0.0.1:43762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":103,"response count":0,"response size":188530,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T17:42:16.098961Z","caller":"traceutil/trace.go:171","msg":"trace[536658071] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"2.580824187s","start":"2026-02-02T17:42:13.518129Z","end":"2026-02-02T17:42:16.098954Z","steps":["trace[536658071] 'process raft request' (duration: 2.578517675s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:16.099075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.799576297s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:42:16.099145Z","caller":"traceutil/trace.go:171","msg":"trace[350493584] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:527; }","duration":"5.799636499s","start":"2026-02-02T17:42:10.299483Z","end":"2026-02-02T17:42:16.099119Z","steps":["trace[350493584] 'agreement among raft nodes before linearized reading' (duration: 5.799570446s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:16.099174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:10.299477Z","time spent":"5.799689062s","remote":"127.0.0.1:50098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-02-02T17:42:16.099288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.799844549s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-02T17:42:16.099356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:13.518083Z","time spent":"2.580905999s","remote":"127.0.0.1:55482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T17:42:16.09949Z","caller":"traceutil/trace.go:171","msg":"trace[2025935751] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"670.293128ms","start":"2026-02-02T17:42:15.42919Z","end":"2026-02-02T17:42:16.099483Z","steps":["trace[2025935751] 'process raft request' (duration: 667.522092ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:16.099316Z","caller":"traceutil/trace.go:171","msg":"trace[2014264047] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:527; }","duration":"5.79988843s","start":"2026-02-02T17:42:10.299421Z","end":"2026-02-02T17:42:16.099309Z","steps":["trace[2014264047] 'agreement among raft nodes before linearized reading' (duration: 5.799847758s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:16.099627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:10.299412Z","time spent":"5.800187196s","remote":"127.0.0.1:50098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 "} {"level":"warn","ts":"2026-02-02T17:42:16.099776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:15.429162Z","time spent":"670.351529ms","remote":"127.0.0.1:55482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T17:42:16.099839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.808646386s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:42:16.099864Z","caller":"traceutil/trace.go:171","msg":"trace[611150215] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:527; }","duration":"5.808677227s","start":"2026-02-02T17:42:10.29118Z","end":"2026-02-02T17:42:16.099858Z","steps":["trace[611150215] 'agreement among raft nodes before linearized reading' (duration: 5.80854718s)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:16.099965Z","caller":"traceutil/trace.go:171","msg":"trace[301579274] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"654.208837ms","start":"2026-02-02T17:42:15.44575Z","end":"2026-02-02T17:42:16.099959Z","steps":["trace[301579274] 'process raft request' (duration: 650.986327ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:16.099883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:10.291177Z","time spent":"5.808700618s","remote":"127.0.0.1:50086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 "} {"level":"warn","ts":"2026-02-02T17:42:16.100112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:15.445739Z","time spent":"654.249458ms","remote":"127.0.0.1:55482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":411,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T17:42:16.100304Z","caller":"traceutil/trace.go:171","msg":"trace[345099165] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"590.736618ms","start":"2026-02-02T17:42:15.50956Z","end":"2026-02-02T17:42:16.100297Z","steps":["trace[345099165] 'process raft request' (duration: 587.199112ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:16.100226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.809046336s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:42:16.100385Z","caller":"traceutil/trace.go:171","msg":"trace[421601205] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:527; }","duration":"5.809211335s","start":"2026-02-02T17:42:10.291165Z","end":"2026-02-02T17:42:16.100376Z","steps":["trace[421601205] 'agreement among raft nodes before linearized reading' (duration: 5.809040786s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:16.100455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:15.509529Z","time spent":"590.79848ms","remote":"127.0.0.1:55482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T17:42:16.100514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:10.291161Z","time spent":"5.809242576s","remote":"127.0.0.1:50086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"info","ts":"2026-02-02T17:42:16.100687Z","caller":"traceutil/trace.go:171","msg":"trace[1863518604] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"271.080931ms","start":"2026-02-02T17:42:15.829599Z","end":"2026-02-02T17:42:16.10068Z","steps":["trace[1863518604] 'process raft request' (duration: 267.185145ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:16.100972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.809826026s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:42:16.101003Z","caller":"traceutil/trace.go:171","msg":"trace[186255644] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:527; }","duration":"5.809868378s","start":"2026-02-02T17:42:10.291127Z","end":"2026-02-02T17:42:16.100996Z","steps":["trace[186255644] 'agreement among raft nodes before linearized reading' (duration: 5.809818906s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:16.101058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:10.291119Z","time spent":"5.809927141s","remote":"127.0.0.1:50086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:500 "} {"level":"warn","ts":"2026-02-02T17:42:16.101198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.355622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-02T17:42:16.101356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.824075866s","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-02T17:42:16.10124Z","caller":"traceutil/trace.go:171","msg":"trace[1026320696] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:527; }","duration":"428.430315ms","start":"2026-02-02T17:42:15.672801Z","end":"2026-02-02T17:42:16.101232Z","steps":["trace[1026320696] 'agreement among raft nodes before linearized reading' (duration: 428.34898ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:16.101428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:15.672712Z","time spent":"428.710106ms","remote":"127.0.0.1:55482","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":"info","ts":"2026-02-02T17:42:16.101381Z","caller":"traceutil/trace.go:171","msg":"trace[1726141248] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:527; }","duration":"5.824111677s","start":"2026-02-02T17:42:10.277263Z","end":"2026-02-02T17:42:16.101375Z","steps":["trace[1726141248] 'agreement among raft nodes before linearized reading' (duration: 5.824052914s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:16.101503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:10.27726Z","time spent":"5.824236903s","remote":"127.0.0.1:50072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-02-02T17:42:16.101708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.321909359s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-02T17:42:16.102074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.824834463s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-02T17:42:16.102426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.5332759s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.72\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-02T17:42:16.102491Z","caller":"traceutil/trace.go:171","msg":"trace[670720778] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:527; }","duration":"5.824915615s","start":"2026-02-02T17:42:10.277187Z","end":"2026-02-02T17:42:16.102103Z","steps":["trace[670720778] 'agreement among raft nodes before linearized reading' (duration: 5.824657442s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:16.102523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:10.277163Z","time spent":"5.825350978s","remote":"127.0.0.1:50072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:500 "} {"level":"warn","ts":"2026-02-02T17:42:16.102737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.763128573s","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-02T17:42:16.103096Z","caller":"traceutil/trace.go:171","msg":"trace[846137152] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:527; }","duration":"5.76345005s","start":"2026-02-02T17:42:10.339595Z","end":"2026-02-02T17:42:16.103045Z","steps":["trace[846137152] 'agreement among raft nodes before linearized reading' (duration: 5.763123233s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:16.103404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.826183113s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-02T17:42:16.103479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:10.33959Z","time spent":"5.763532854s","remote":"127.0.0.1:50122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"info","ts":"2026-02-02T17:42:16.103746Z","caller":"traceutil/trace.go:171","msg":"trace[565473862] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:527; }","duration":"5.826232605s","start":"2026-02-02T17:42:10.277194Z","end":"2026-02-02T17:42:16.103426Z","steps":["trace[565473862] 'agreement among raft nodes before linearized reading' (duration: 5.826107358s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:16.103781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:10.277185Z","time spent":"5.826586873s","remote":"127.0.0.1:50072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:10000 "} {"level":"warn","ts":"2026-02-02T17:42:16.104686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.843774367s","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":"warn","ts":"2026-02-02T17:42:16.105116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.7656847s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:42:16.105173Z","caller":"traceutil/trace.go:171","msg":"trace[1645133681] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:527; }","duration":"5.843835509s","start":"2026-02-02T17:42:10.260881Z","end":"2026-02-02T17:42:16.104716Z","steps":["trace[1645133681] 'agreement among raft nodes before linearized reading' (duration: 5.843646009s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:16.105231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:10.260865Z","time spent":"5.844348956s","remote":"127.0.0.1:50062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:500 "} {"level":"info","ts":"2026-02-02T17:42:16.105383Z","caller":"traceutil/trace.go:171","msg":"trace[1560371637] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:527; }","duration":"5.765730112s","start":"2026-02-02T17:42:10.339411Z","end":"2026-02-02T17:42:16.105141Z","steps":["trace[1560371637] 'agreement among raft nodes before linearized reading' (duration: 5.765384534s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:16.106102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:10.339404Z","time spent":"5.766001756s","remote":"127.0.0.1:50122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-02-02T17:42:16.106711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.779191238s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:42:16.107188Z","caller":"traceutil/trace.go:171","msg":"trace[707594549] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:527; }","duration":"5.779675464s","start":"2026-02-02T17:42:10.327502Z","end":"2026-02-02T17:42:16.107177Z","steps":["trace[707594549] 'agreement among raft nodes before linearized reading' (duration: 5.779108554s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:16.107278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:10.327498Z","time spent":"5.779717346s","remote":"127.0.0.1:50112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-02-02T17:42:16.108012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.780516108s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-02T17:42:16.10827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.847218074s","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-02T17:42:16.108344Z","caller":"traceutil/trace.go:171","msg":"trace[1065775694] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:527; }","duration":"5.847261426s","start":"2026-02-02T17:42:10.261034Z","end":"2026-02-02T17:42:16.108295Z","steps":["trace[1065775694] 'agreement among raft nodes before linearized reading' (duration: 5.847163761s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:16.108376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:10.261031Z","time spent":"5.84733735s","remote":"127.0.0.1:50062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-02-02T17:42:16.10887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.847864167s","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-02T17:42:16.109018Z","caller":"traceutil/trace.go:171","msg":"trace[250757992] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:527; }","duration":"5.847917699s","start":"2026-02-02T17:42:10.260976Z","end":"2026-02-02T17:42:16.108894Z","steps":["trace[250757992] 'agreement among raft nodes before linearized reading' (duration: 5.84775118s)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:16.108975Z","caller":"traceutil/trace.go:171","msg":"trace[872201062] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:527; }","duration":"5.78055914s","start":"2026-02-02T17:42:10.327478Z","end":"2026-02-02T17:42:16.108037Z","steps":["trace[872201062] 'agreement among raft nodes before linearized reading' (duration: 5.780504017s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:16.10917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:10.327473Z","time spent":"5.781688799s","remote":"127.0.0.1:50112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 "} {"level":"warn","ts":"2026-02-02T17:42:16.109125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:10.260972Z","time spent":"5.848076167s","remote":"127.0.0.1:50062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:10000 "} {"level":"warn","ts":"2026-02-02T17:42:16.109938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.243197568s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:42:16.109997Z","caller":"traceutil/trace.go:171","msg":"trace[223671930] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:527; }","duration":"6.243231099s","start":"2026-02-02T17:42:09.866725Z","end":"2026-02-02T17:42:16.109956Z","steps":["trace[223671930] 'agreement among raft nodes before linearized reading' (duration: 6.243190717s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:16.110021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:09.866722Z","time spent":"6.243292823s","remote":"127.0.0.1:50046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-02-02T17:42:16.110819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.244090775s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-02T17:42:16.110961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.783630325s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:42:16.111023Z","caller":"traceutil/trace.go:171","msg":"trace[723239287] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:527; }","duration":"5.783665376s","start":"2026-02-02T17:42:10.327311Z","end":"2026-02-02T17:42:16.110976Z","steps":["trace[723239287] 'agreement among raft nodes before linearized reading' (duration: 5.78299269s)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:16.110845Z","caller":"traceutil/trace.go:171","msg":"trace[1940740021] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:527; }","duration":"6.244145886s","start":"2026-02-02T17:42:09.866692Z","end":"2026-02-02T17:42:16.110838Z","steps":["trace[1940740021] 'agreement among raft nodes before linearized reading' (duration: 6.244097574s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:16.111097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:09.866689Z","time spent":"6.24440138s","remote":"127.0.0.1:50046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":79,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:500 "} {"level":"warn","ts":"2026-02-02T17:42:16.111278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:10.327305Z","time spent":"5.78375883s","remote":"127.0.0.1:50112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 "} {"level":"warn","ts":"2026-02-02T17:42:16.11198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.798356915s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:42:16.112015Z","caller":"traceutil/trace.go:171","msg":"trace[1721889507] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:527; }","duration":"5.798396357s","start":"2026-02-02T17:42:10.313608Z","end":"2026-02-02T17:42:16.112004Z","steps":["trace[1721889507] 'agreement among raft nodes before linearized reading' (duration: 5.798342634s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:16.112079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:10.313603Z","time spent":"5.79846938s","remote":"127.0.0.1:50106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-02-02T17:42:16.112603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.245926089s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:42:16.112641Z","caller":"traceutil/trace.go:171","msg":"trace[205212298] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:527; }","duration":"6.245973041s","start":"2026-02-02T17:42:09.866658Z","end":"2026-02-02T17:42:16.112631Z","steps":["trace[205212298] 'agreement among raft nodes before linearized reading' (duration: 6.245903997s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:16.112704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:09.866649Z","time spent":"6.246048474s","remote":"127.0.0.1:50046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":79,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:10000 "} {"level":"warn","ts":"2026-02-02T17:42:16.113193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.79960567s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-02T17:42:16.113396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.260742872s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:42:16.113495Z","caller":"traceutil/trace.go:171","msg":"trace[468711891] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:527; }","duration":"6.260777993s","start":"2026-02-02T17:42:09.852635Z","end":"2026-02-02T17:42:16.113413Z","steps":["trace[468711891] 'agreement among raft nodes before linearized reading' (duration: 6.260739341s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:16.113535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:09.852631Z","time spent":"6.260896349s","remote":"127.0.0.1:50044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 "} {"level":"info","ts":"2026-02-02T17:42:16.113219Z","caller":"traceutil/trace.go:171","msg":"trace[1119666990] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:527; }","duration":"5.799639921s","start":"2026-02-02T17:42:10.313574Z","end":"2026-02-02T17:42:16.113214Z","steps":["trace[1119666990] 'agreement among raft nodes before linearized reading' (duration: 5.799595059s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:16.114027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:10.313557Z","time spent":"5.800424552s","remote":"127.0.0.1:50106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 "} {"level":"warn","ts":"2026-02-02T17:42:16.114459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.261829757s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:42:16.114485Z","caller":"traceutil/trace.go:171","msg":"trace[1589234408] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:527; }","duration":"6.261870869s","start":"2026-02-02T17:42:09.852609Z","end":"2026-02-02T17:42:16.11448Z","steps":["trace[1589234408] 'agreement among raft nodes before linearized reading' (duration: 6.261831297s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:16.114605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:09.852604Z","time spent":"6.261987145s","remote":"127.0.0.1:50044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"info","ts":"2026-02-02T17:42:16.101734Z","caller":"traceutil/trace.go:171","msg":"trace[1281123825] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:527; }","duration":"1.322100717s","start":"2026-02-02T17:42:14.779626Z","end":"2026-02-02T17:42:16.101727Z","steps":["trace[1281123825] 'agreement among raft nodes before linearized reading' (duration: 1.321941728s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:16.11548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.262880901s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:42:16.115504Z","caller":"traceutil/trace.go:171","msg":"trace[963494748] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:527; }","duration":"6.262912252s","start":"2026-02-02T17:42:09.852586Z","end":"2026-02-02T17:42:16.115498Z","steps":["trace[963494748] 'agreement among raft nodes before linearized reading' (duration: 6.262773255s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:16.115072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:14.779602Z","time spent":"1.335463799s","remote":"127.0.0.1:43720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-02T17:42:16.115705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:09.852551Z","time spent":"6.263036967s","remote":"127.0.0.1:50044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 "} {"level":"warn","ts":"2026-02-02T17:42:16.116941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.280575854s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:42:16.116968Z","caller":"traceutil/trace.go:171","msg":"trace[174151149] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:527; }","duration":"6.280610035s","start":"2026-02-02T17:42:09.836349Z","end":"2026-02-02T17:42:16.116959Z","steps":["trace[174151149] 'agreement among raft nodes before linearized reading' (duration: 6.280571543s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:16.117088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:09.836344Z","time spent":"6.280735152s","remote":"127.0.0.1:50038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":91,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:500 "} {"level":"warn","ts":"2026-02-02T17:42:16.117247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.281146102s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:42:16.11728Z","caller":"traceutil/trace.go:171","msg":"trace[300453554] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:527; }","duration":"6.281190184s","start":"2026-02-02T17:42:09.83608Z","end":"2026-02-02T17:42:16.11727Z","steps":["trace[300453554] 'agreement among raft nodes before linearized reading' (duration: 6.281150272s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:16.117478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:09.836074Z","time spent":"6.281217685s","remote":"127.0.0.1:50038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-02T17:42:16.117763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.281698009s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:42:16.117789Z","caller":"traceutil/trace.go:171","msg":"trace[398684915] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:527; }","duration":"6.281763081s","start":"2026-02-02T17:42:09.836019Z","end":"2026-02-02T17:42:16.117782Z","steps":["trace[398684915] 'agreement among raft nodes before linearized reading' (duration: 6.281622433s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:16.117857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:09.835994Z","time spent":"6.281851455s","remote":"127.0.0.1:50038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":91,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-02-02T17:42:16.098269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:12.627993Z","time spent":"3.470051866s","remote":"127.0.0.1:55482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":45,"response count":0,"response size":569,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T17:42:16.102567Z","caller":"traceutil/trace.go:171","msg":"trace[597294505] range","detail":"{range_begin:/registry/masterleases/199.204.45.72; range_end:; response_count:1; response_revision:527; }","duration":"2.533355192s","start":"2026-02-02T17:42:13.569093Z","end":"2026-02-02T17:42:16.102448Z","steps":["trace[597294505] 'agreement among raft nodes before linearized reading' (duration: 2.533274857s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:16.120975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:13.56908Z","time spent":"2.551886097s","remote":"127.0.0.1:43736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.204.45.72\" "} {"level":"warn","ts":"2026-02-02T17:42:16.10267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.060489213s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4421"} {"level":"info","ts":"2026-02-02T17:42:16.12236Z","caller":"traceutil/trace.go:171","msg":"trace[1433147849] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:527; }","duration":"3.080219399s","start":"2026-02-02T17:42:13.04213Z","end":"2026-02-02T17:42:16.12235Z","steps":["trace[1433147849] 'agreement among raft nodes before linearized reading' (duration: 3.06048014s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:16.122381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:13.042085Z","time spent":"3.080290282s","remote":"127.0.0.1:55386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4443,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-02T17:42:16.102706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.762339887s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:42:16.123041Z","caller":"traceutil/trace.go:171","msg":"trace[2075879530] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:527; }","duration":"5.782679457s","start":"2026-02-02T17:42:10.340352Z","end":"2026-02-02T17:42:16.123031Z","steps":["trace[2075879530] 'agreement among raft nodes before linearized reading' (duration: 5.762329336s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:16.123089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:10.340346Z","time spent":"5.78273346s","remote":"127.0.0.1:50122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 "} {"level":"warn","ts":"2026-02-02T17:42:16.699535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.657927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/local-path-storage/\" range_end:\"/registry/resourcequotas/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:42:16.700036Z","caller":"traceutil/trace.go:171","msg":"trace[1449970350] range","detail":"{range_begin:/registry/resourcequotas/local-path-storage/; range_end:/registry/resourcequotas/local-path-storage0; response_count:0; response_revision:532; }","duration":"380.25673ms","start":"2026-02-02T17:42:16.319732Z","end":"2026-02-02T17:42:16.699988Z","steps":["trace[1449970350] 'range keys from in-memory index tree' (duration: 379.594014ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:16.700106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:16.319663Z","time spent":"380.415837ms","remote":"127.0.0.1:55298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/local-path-storage/\" range_end:\"/registry/resourcequotas/local-path-storage0\" "} {"level":"warn","ts":"2026-02-02T17:42:16.699615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.66771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:42:16.700264Z","caller":"traceutil/trace.go:171","msg":"trace[1260887576] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:532; }","duration":"135.446001ms","start":"2026-02-02T17:42:16.564793Z","end":"2026-02-02T17:42:16.700239Z","steps":["trace[1260887576] 'range keys from in-memory index tree' (duration: 134.592476ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:16.747929Z","caller":"traceutil/trace.go:171","msg":"trace[266797484] linearizableReadLoop","detail":"{readStateIndex:559; appliedIndex:558; }","duration":"158.890895ms","start":"2026-02-02T17:42:16.589019Z","end":"2026-02-02T17:42:16.74791Z","steps":["trace[266797484] 'read index received' (duration: 158.803051ms)","trace[266797484] 'applied index is now lower than readState.Index' (duration: 87.224µs)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:42:16.748036Z","caller":"traceutil/trace.go:171","msg":"trace[797701799] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"174.294127ms","start":"2026-02-02T17:42:16.573735Z","end":"2026-02-02T17:42:16.748029Z","steps":["trace[797701799] 'process raft request' (duration: 174.017742ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:16.748323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.272925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:42:16.750765Z","caller":"traceutil/trace.go:171","msg":"trace[1541364136] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:533; }","duration":"161.762547ms","start":"2026-02-02T17:42:16.58899Z","end":"2026-02-02T17:42:16.750753Z","steps":["trace[1541364136] 'agreement among raft nodes before linearized reading' (duration: 159.225634ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:16.767586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.404103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10 ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-02T17:42:16.767689Z","caller":"traceutil/trace.go:171","msg":"trace[411821930] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; }","duration":"178.567651ms","start":"2026-02-02T17:42:16.589095Z","end":"2026-02-02T17:42:16.767663Z","steps":["trace[411821930] 'agreement among raft nodes before linearized reading' (duration: 178.421294ms)"],"step_count":1} 2026/02/02 17:42:16 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-02T17:42:16.790165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.029904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:42:16.790356Z","caller":"traceutil/trace.go:171","msg":"trace[1647570860] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:534; }","duration":"201.267166ms","start":"2026-02-02T17:42:16.589069Z","end":"2026-02-02T17:42:16.790336Z","steps":["trace[1647570860] 'agreement among raft nodes before linearized reading' (duration: 200.878045ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:16.944214Z","caller":"traceutil/trace.go:171","msg":"trace[86864975] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"139.459813ms","start":"2026-02-02T17:42:16.804705Z","end":"2026-02-02T17:42:16.944164Z","steps":["trace[86864975] 'process raft request' (duration: 139.41118ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:16.94431Z","caller":"traceutil/trace.go:171","msg":"trace[1070133478] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"139.658813ms","start":"2026-02-02T17:42:16.804616Z","end":"2026-02-02T17:42:16.944275Z","steps":["trace[1070133478] 'process raft request' (duration: 139.309775ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:17.489876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.735186ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-02T17:42:17.490358Z","caller":"traceutil/trace.go:171","msg":"trace[1690447644] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"537.470967ms","start":"2026-02-02T17:42:16.952865Z","end":"2026-02-02T17:42:17.490336Z","steps":["trace[1690447644] 'process raft request' (duration: 398.195404ms)","trace[1690447644] 'compare' (duration: 137.821447ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:17.492016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:16.952847Z","time spent":"539.137474ms","remote":"127.0.0.1:55408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":882,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-02T17:42:17.673209Z","caller":"traceutil/trace.go:171","msg":"trace[927679221] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"144.088904ms","start":"2026-02-02T17:42:17.529103Z","end":"2026-02-02T17:42:17.673192Z","steps":["trace[927679221] 'process raft request' (duration: 143.991309ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:22.849225Z","caller":"traceutil/trace.go:171","msg":"trace[1613376316] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:623; }","duration":"289.270171ms","start":"2026-02-02T17:42:22.559942Z","end":"2026-02-02T17:42:22.849212Z","steps":["trace[1613376316] 'read index received' (duration: 289.103102ms)","trace[1613376316] 'applied index is now lower than readState.Index' (duration: 166.209µs)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:42:22.849387Z","caller":"traceutil/trace.go:171","msg":"trace[1538923654] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"583.906715ms","start":"2026-02-02T17:42:22.265474Z","end":"2026-02-02T17:42:22.849381Z","steps":["trace[1538923654] 'process raft request' (duration: 583.613621ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:22.849463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:22.265452Z","time spent":"583.949457ms","remote":"127.0.0.1:55482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T17:42:22.849601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.66083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-6l2h8\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-02-02T17:42:22.849624Z","caller":"traceutil/trace.go:171","msg":"trace[1007074466] range","detail":"{range_begin:/registry/pods/kube-system/cilium-6l2h8; range_end:; response_count:1; response_revision:597; }","duration":"289.714312ms","start":"2026-02-02T17:42:22.559903Z","end":"2026-02-02T17:42:22.849618Z","steps":["trace[1007074466] 'agreement among raft nodes before linearized reading' (duration: 289.647209ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:23.543566Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680217594237916173,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-02T17:42:23.760496Z","caller":"traceutil/trace.go:171","msg":"trace[292843722] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"896.565996ms","start":"2026-02-02T17:42:22.863913Z","end":"2026-02-02T17:42:23.760479Z","steps":["trace[292843722] 'process raft request' (duration: 896.413389ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:23.760612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:22.863905Z","time spent":"896.639869ms","remote":"127.0.0.1:55482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T17:42:23.767232Z","caller":"traceutil/trace.go:171","msg":"trace[830246398] linearizableReadLoop","detail":"{readStateIndex:627; appliedIndex:624; }","duration":"723.925017ms","start":"2026-02-02T17:42:23.043296Z","end":"2026-02-02T17:42:23.767221Z","steps":["trace[830246398] 'read index received' (duration: 716.991542ms)","trace[830246398] 'applied index is now lower than readState.Index' (duration: 6.932625ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:42:23.7673Z","caller":"traceutil/trace.go:171","msg":"trace[1661951141] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"900.521586ms","start":"2026-02-02T17:42:22.866756Z","end":"2026-02-02T17:42:23.767277Z","steps":["trace[1661951141] 'process raft request' (duration: 900.368158ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:23.767405Z","caller":"traceutil/trace.go:171","msg":"trace[1800087650] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"898.455684ms","start":"2026-02-02T17:42:22.868937Z","end":"2026-02-02T17:42:23.767393Z","steps":["trace[1800087650] 'process raft request' (duration: 898.251734ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:23.767428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:22.866741Z","time spent":"900.609439ms","remote":"127.0.0.1:55388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T17:42:23.767505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:22.868929Z","time spent":"898.498306ms","remote":"127.0.0.1:55482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T17:42:23.767703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.412061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:476"} {"level":"info","ts":"2026-02-02T17:42:23.767726Z","caller":"traceutil/trace.go:171","msg":"trace[2146930830] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:600; }","duration":"724.466153ms","start":"2026-02-02T17:42:23.043252Z","end":"2026-02-02T17:42:23.767718Z","steps":["trace[2146930830] 'agreement among raft nodes before linearized reading' (duration: 724.369318ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:23.767747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:23.043232Z","time spent":"724.510335ms","remote":"127.0.0.1:55482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":498,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-02T17:42:23.767871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.732766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.72\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-02T17:42:23.767891Z","caller":"traceutil/trace.go:171","msg":"trace[174955741] range","detail":"{range_begin:/registry/masterleases/199.204.45.72; range_end:; response_count:1; response_revision:600; }","duration":"197.779348ms","start":"2026-02-02T17:42:23.570106Z","end":"2026-02-02T17:42:23.767885Z","steps":["trace[174955741] 'agreement among raft nodes before linearized reading' (duration: 197.724195ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:23.768079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.203951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-02T17:42:23.768108Z","caller":"traceutil/trace.go:171","msg":"trace[180067351] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:600; }","duration":"173.256673ms","start":"2026-02-02T17:42:23.594843Z","end":"2026-02-02T17:42:23.7681Z","steps":["trace[180067351] 'agreement among raft nodes before linearized reading' (duration: 173.171379ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:42.409773Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680217594237916463,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:42.909895Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680217594237916463,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:43.410556Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680217594237916463,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:43.91116Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680217594237916463,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:44.412137Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680217594237916463,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:44.753528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000595207s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-02T17:42:44.753589Z","caller":"traceutil/trace.go:171","msg":"trace[1804041595] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000712112s","start":"2026-02-02T17:42:42.752862Z","end":"2026-02-02T17:42:44.753574Z","steps":["trace[1804041595] 'agreement among raft nodes before linearized reading' (duration: 2.000614908s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:44.753623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:42.752847Z","time spent":"2.000770223s","remote":"127.0.0.1:43720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-02T17:42:44.912629Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680217594237916463,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:45.413044Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680217594237916463,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:45.91429Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680217594237916463,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:46.41529Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680217594237916463,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:46.754192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000072748s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-02T17:42:46.75428Z","caller":"traceutil/trace.go:171","msg":"trace[1042680014] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000207972s","start":"2026-02-02T17:42:44.754053Z","end":"2026-02-02T17:42:46.754261Z","steps":["trace[1042680014] 'agreement among raft nodes before linearized reading' (duration: 2.000085708s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:46.754338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:44.754043Z","time spent":"2.000272905s","remote":"127.0.0.1:43724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-02T17:42:46.915781Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680217594237916463,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:46.918165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999899017s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-02T17:42:46.918317Z","caller":"traceutil/trace.go:171","msg":"trace[465998199] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"5.000107775s","start":"2026-02-02T17:42:41.918187Z","end":"2026-02-02T17:42:46.918295Z","steps":["trace[465998199] 'agreement among raft nodes before linearized reading' (duration: 4.999940429s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:46.918457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:41.918162Z","time spent":"5.000280342s","remote":"127.0.0.1:55482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-02T17:42:46.940644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.000251964s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-02T17:42:46.94075Z","caller":"traceutil/trace.go:171","msg":"trace[1181475088] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"5.000385028s","start":"2026-02-02T17:42:41.940351Z","end":"2026-02-02T17:42:46.940737Z","steps":["trace[1181475088] 'agreement among raft nodes before linearized reading' (duration: 5.000270914s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:46.940887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:41.94032Z","time spent":"5.000532224s","remote":"127.0.0.1:55482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-02T17:42:47.416518Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680217594237916463,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:47.917212Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680217594237916463,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:47.960468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999531573s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-02T17:42:47.960544Z","caller":"traceutil/trace.go:171","msg":"trace[799904380] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"4.999670497s","start":"2026-02-02T17:42:42.960856Z","end":"2026-02-02T17:42:47.960527Z","steps":["trace[799904380] 'agreement among raft nodes before linearized reading' (duration: 4.999573334s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:47.96059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:42.960829Z","time spent":"4.99975228s","remote":"127.0.0.1:55482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-02T17:42:48.417619Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680217594237916463,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:48.766271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.004478883s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-02T17:42:48.766356Z","caller":"traceutil/trace.go:171","msg":"trace[544545069] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.004592436s","start":"2026-02-02T17:42:46.761745Z","end":"2026-02-02T17:42:48.766337Z","steps":["trace[544545069] 'agreement among raft nodes before linearized reading' (duration: 2.004489033s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:48.766463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:46.761738Z","time spent":"2.00471337s","remote":"127.0.0.1:43720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/02 17:42:48 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-02T17:42:48.886356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:41.885973Z","time spent":"7.000375584s","remote":"127.0.0.1:55482","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-02T17:42:48.910324Z","caller":"etcdserver/v3_server.go:909","msg":"timed out waiting for read index response (local node might have slow network)","timeout":"7s"} {"level":"warn","ts":"2026-02-02T17:42:48.910679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.001451706s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-02-02T17:42:48.910855Z","caller":"traceutil/trace.go:171","msg":"trace[208568692] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; }","duration":"7.001652623s","start":"2026-02-02T17:42:41.909151Z","end":"2026-02-02T17:42:48.910804Z","steps":["trace[208568692] 'agreement among raft nodes before linearized reading' (duration: 7.001484707s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:48.911029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:41.909136Z","time spent":"7.001877781s","remote":"127.0.0.1:55482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-02T17:42:48.934958Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"7.048838312s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-02T17:42:50.430561Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.495438935s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-02T17:42:50.766881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999920244s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-02T17:42:50.766937Z","caller":"traceutil/trace.go:171","msg":"trace[898469846] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000023418s","start":"2026-02-02T17:42:48.766902Z","end":"2026-02-02T17:42:50.766926Z","steps":["trace[898469846] 'agreement among raft nodes before linearized reading' (duration: 1.999950765s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:50.766964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:48.766886Z","time spent":"2.00007293s","remote":"127.0.0.1:43724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-02T17:42:51.186986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000531432s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-02T17:42:51.187087Z","caller":"traceutil/trace.go:171","msg":"trace[1570596000] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000673497s","start":"2026-02-02T17:42:49.186398Z","end":"2026-02-02T17:42:51.187071Z","steps":["trace[1570596000] 'agreement among raft nodes before linearized reading' (duration: 2.000556213s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:51.187117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:49.186381Z","time spent":"2.00073076s","remote":"127.0.0.1:43720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-02T17:42:51.396105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"772.735143ms","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-02T17:42:51.396188Z","caller":"traceutil/trace.go:171","msg":"trace[2072759023] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:753; }","duration":"772.826187ms","start":"2026-02-02T17:42:50.623349Z","end":"2026-02-02T17:42:51.396175Z","steps":["trace[2072759023] 'range keys from in-memory index tree' (duration: 772.723183ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:51.396268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"965.476256ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-02T17:42:51.398622Z","caller":"traceutil/trace.go:171","msg":"trace[635446193] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"4.847811041s","start":"2026-02-02T17:42:46.550798Z","end":"2026-02-02T17:42:51.398609Z","steps":["trace[635446193] 'process raft request' (duration: 3.87989952s)","trace[635446193] 'compare' (duration: 965.305799ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:51.398698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:46.550782Z","time spent":"4.847869764s","remote":"127.0.0.1:55482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T17:42:51.398858Z","caller":"traceutil/trace.go:171","msg":"trace[1717362173] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"4.62552971s","start":"2026-02-02T17:42:46.773323Z","end":"2026-02-02T17:42:51.398853Z","steps":["trace[1717362173] 'process raft request' (duration: 4.625221449s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:51.398884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:46.773299Z","time spent":"4.625570401s","remote":"127.0.0.1:55482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T17:42:51.880247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"990.327406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-02T17:42:51.880481Z","caller":"traceutil/trace.go:171","msg":"trace[1464807696] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; }","duration":"990.596035ms","start":"2026-02-02T17:42:50.889871Z","end":"2026-02-02T17:42:51.880467Z","steps":["trace[1464807696] 'agreement among raft nodes before linearized reading' (duration: 990.347467ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:51.880556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:50.889857Z","time spent":"990.691698ms","remote":"127.0.0.1:55482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} 2026/02/02 17:42:51 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-02T17:42:51.898363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.734762ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-02T17:42:51.898428Z","caller":"traceutil/trace.go:171","msg":"trace[1441765831] linearizableReadLoop","detail":"{readStateIndex:789; appliedIndex:785; }","duration":"2.987814134s","start":"2026-02-02T17:42:48.910601Z","end":"2026-02-02T17:42:51.898415Z","steps":["trace[1441765831] 'read index received' (duration: 24.622763ms)","trace[1441765831] 'applied index is now lower than readState.Index' (duration: 2.963190491s)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:51.898856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.285087219s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-02T17:42:51.898876Z","caller":"traceutil/trace.go:171","msg":"trace[1286784902] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:756; }","duration":"9.285143542s","start":"2026-02-02T17:42:42.613726Z","end":"2026-02-02T17:42:51.898869Z","steps":["trace[1286784902] 'agreement among raft nodes before linearized reading' (duration: 9.2850917s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:51.898896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:42.61371Z","time spent":"9.285180333s","remote":"127.0.0.1:55712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":29,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-02-02T17:42:51.898913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.97861085s","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-02T17:42:51.898953Z","caller":"traceutil/trace.go:171","msg":"trace[807677245] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:756; }","duration":"2.978672512s","start":"2026-02-02T17:42:48.920268Z","end":"2026-02-02T17:42:51.89894Z","steps":["trace[807677245] 'agreement among raft nodes before linearized reading' (duration: 2.978548338s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:51.898984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:48.920256Z","time spent":"2.978720154s","remote":"127.0.0.1:55482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-02T17:42:51.899037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.140802536s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18907eda913886e3\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-02-02T17:42:51.899054Z","caller":"traceutil/trace.go:171","msg":"trace[19880464] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18907eda913886e3; range_end:; response_count:1; response_revision:756; }","duration":"5.140846868s","start":"2026-02-02T17:42:46.758201Z","end":"2026-02-02T17:42:51.899047Z","steps":["trace[19880464] 'agreement among raft nodes before linearized reading' (duration: 5.140795266s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:51.899067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:46.758184Z","time spent":"5.140879779s","remote":"127.0.0.1:43800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":796,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.18907eda913886e3\" "} {"level":"warn","ts":"2026-02-02T17:42:51.899163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.695287453s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-02T17:42:51.899178Z","caller":"traceutil/trace.go:171","msg":"trace[955943574] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:756; }","duration":"5.695334995s","start":"2026-02-02T17:42:46.203838Z","end":"2026-02-02T17:42:51.899173Z","steps":["trace[955943574] 'agreement among raft nodes before linearized reading' (duration: 5.695306384s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:51.899192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:46.203824Z","time spent":"5.695364406s","remote":"127.0.0.1:55386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":29,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-02-02T17:42:51.899274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.119584493s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:42:51.899288Z","caller":"traceutil/trace.go:171","msg":"trace[1296799273] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:756; }","duration":"7.119598493s","start":"2026-02-02T17:42:44.779685Z","end":"2026-02-02T17:42:51.899284Z","steps":["trace[1296799273] 'agreement among raft nodes before linearized reading' (duration: 7.119575123s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:51.899292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"983.891299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-02T17:42:51.899327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.328146409s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.72\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:42:51.899328Z","caller":"traceutil/trace.go:171","msg":"trace[1439975849] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:756; }","duration":"983.926031ms","start":"2026-02-02T17:42:50.915393Z","end":"2026-02-02T17:42:51.899319Z","steps":["trace[1439975849] 'agreement among raft nodes before linearized reading' (duration: 983.846728ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:51.899339Z","caller":"traceutil/trace.go:171","msg":"trace[1773710730] range","detail":"{range_begin:/registry/masterleases/199.204.45.72; range_end:; response_count:0; response_revision:756; }","duration":"8.328209971s","start":"2026-02-02T17:42:43.571125Z","end":"2026-02-02T17:42:51.899335Z","steps":["trace[1773710730] 'agreement among raft nodes before linearized reading' (duration: 8.328191111s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:51.899351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:43.571099Z","time spent":"8.328249883s","remote":"127.0.0.1:43736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":27,"request content":"key:\"/registry/masterleases/199.204.45.72\" "} {"level":"warn","ts":"2026-02-02T17:42:51.899355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:50.91537Z","time spent":"983.977953ms","remote":"127.0.0.1:55482","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-02T17:42:51.899428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.383799301s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:42:51.899441Z","caller":"traceutil/trace.go:171","msg":"trace[1343981818] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:756; }","duration":"8.383851974s","start":"2026-02-02T17:42:43.515585Z","end":"2026-02-02T17:42:51.899437Z","steps":["trace[1343981818] 'agreement among raft nodes before linearized reading' (duration: 8.383829773s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:51.899482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:43.515557Z","time spent":"8.383920646s","remote":"127.0.0.1:55454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-02-02T17:42:51.899521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.125239716s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:42:51.899549Z","caller":"traceutil/trace.go:171","msg":"trace[1056333203] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:756; }","duration":"1.125284378s","start":"2026-02-02T17:42:50.774256Z","end":"2026-02-02T17:42:51.89954Z","steps":["trace[1056333203] 'agreement among raft nodes before linearized reading' (duration: 1.125244047s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:51.899563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.758493919s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-02T17:42:51.899573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:50.774245Z","time spent":"1.12532281s","remote":"127.0.0.1:43720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-02T17:42:51.899693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.748126489s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:42:51.899718Z","caller":"traceutil/trace.go:171","msg":"trace[1141062105] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:756; }","duration":"1.748190102s","start":"2026-02-02T17:42:50.15152Z","end":"2026-02-02T17:42:51.89971Z","steps":["trace[1141062105] 'agreement among raft nodes before linearized reading' (duration: 1.748151721s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:51.899755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:50.1515Z","time spent":"1.748245034s","remote":"127.0.0.1:55622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":27,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-02-02T17:42:51.899891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.937270179s","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-02T17:42:51.899907Z","caller":"traceutil/trace.go:171","msg":"trace[1196066107] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:756; }","duration":"1.93732352s","start":"2026-02-02T17:42:49.962579Z","end":"2026-02-02T17:42:51.899903Z","steps":["trace[1196066107] 'agreement among raft nodes before linearized reading' (duration: 1.937275418s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:51.899301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:44.779667Z","time spent":"7.119630664s","remote":"127.0.0.1:55340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-02-02T17:42:51.899959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.956395133s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-02T17:42:51.899972Z","caller":"traceutil/trace.go:171","msg":"trace[1411803515] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:756; }","duration":"2.956436384s","start":"2026-02-02T17:42:48.94353Z","end":"2026-02-02T17:42:51.899967Z","steps":["trace[1411803515] 'agreement among raft nodes before linearized reading' (duration: 2.956397563s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:51.899984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:48.943518Z","time spent":"2.956463706s","remote":"127.0.0.1:55482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-02T17:42:51.899578Z","caller":"traceutil/trace.go:171","msg":"trace[785894240] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:756; }","duration":"8.758547202s","start":"2026-02-02T17:42:43.141026Z","end":"2026-02-02T17:42:51.899573Z","steps":["trace[785894240] 'agreement among raft nodes before linearized reading' (duration: 8.75851702s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:51.900113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:43.141005Z","time spent":"8.759101891s","remote":"127.0.0.1:55682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":29,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-02T17:42:51.89992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:49.962557Z","time spent":"1.937359482s","remote":"127.0.0.1:55482","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\" "} 2026/02/02 17:42:51 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" 2026/02/02 17:42:51 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" 2026/02/02 17:42:51 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-02-02T17:42:52.281521Z","caller":"traceutil/trace.go:171","msg":"trace[1620058492] linearizableReadLoop","detail":"{readStateIndex:793; appliedIndex:790; }","duration":"368.855922ms","start":"2026-02-02T17:42:51.912652Z","end":"2026-02-02T17:42:52.281508Z","steps":["trace[1620058492] 'read index received' (duration: 20.228741ms)","trace[1620058492] 'applied index is now lower than readState.Index' (duration: 348.62671ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:52.281595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.932124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:42:52.281613Z","caller":"traceutil/trace.go:171","msg":"trace[2004059262] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:761; }","duration":"368.981196ms","start":"2026-02-02T17:42:51.912627Z","end":"2026-02-02T17:42:52.281608Z","steps":["trace[2004059262] 'agreement among raft nodes before linearized reading' (duration: 368.928944ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:52.28163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:51.912616Z","time spent":"369.010617ms","remote":"127.0.0.1:43720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-02T17:42:52.281721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:51.905926Z","time spent":"375.793545ms","remote":"127.0.0.1:43736","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-02T17:42:52.281846Z","caller":"traceutil/trace.go:171","msg":"trace[1911219523] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"375.454073ms","start":"2026-02-02T17:42:51.906385Z","end":"2026-02-02T17:42:52.281839Z","steps":["trace[1911219523] 'process raft request' (duration: 374.952646ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:52.281894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:51.906375Z","time spent":"375.487664ms","remote":"127.0.0.1:55482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T17:42:52.281959Z","caller":"traceutil/trace.go:171","msg":"trace[233212457] transaction","detail":"{read_only:false; number_of_response:1; response_revision:761; }","duration":"360.180457ms","start":"2026-02-02T17:42:51.921774Z","end":"2026-02-02T17:42:52.281954Z","steps":["trace[233212457] 'process raft request' (duration: 359.718321ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:52.282003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:51.921762Z","time spent":"360.209638ms","remote":"127.0.0.1:55482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":47,"response count":0,"response size":510,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T17:42:52.375118Z","caller":"traceutil/trace.go:171","msg":"trace[634601269] transaction","detail":"{read_only:false; number_of_response:1; response_revision:762; }","duration":"433.230628ms","start":"2026-02-02T17:42:51.941872Z","end":"2026-02-02T17:42:52.375103Z","steps":["trace[634601269] 'process raft request' (duration: 433.202947ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:52.37515Z","caller":"traceutil/trace.go:171","msg":"trace[2097471559] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"439.509478ms","start":"2026-02-02T17:42:51.935624Z","end":"2026-02-02T17:42:52.375133Z","steps":["trace[2097471559] 'process raft request' (duration: 439.373543ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:52.375209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:51.941855Z","time spent":"433.31106ms","remote":"127.0.0.1:55482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":62,"response count":0,"response size":524,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T17:42:52.37524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:51.93561Z","time spent":"439.595782ms","remote":"127.0.0.1:43800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":815,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-02T17:42:52.813124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:52.48532Z","time spent":"327.798891ms","remote":"127.0.0.1:50160","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-02T17:42:52.814104Z","caller":"traceutil/trace.go:171","msg":"trace[1861982181] linearizableReadLoop","detail":"{readStateIndex:799; appliedIndex:798; }","duration":"432.067124ms","start":"2026-02-02T17:42:52.382013Z","end":"2026-02-02T17:42:52.814081Z","steps":["trace[1861982181] 'read index received' (duration: 397.358168ms)","trace[1861982181] 'applied index is now lower than readState.Index' (duration: 34.707836ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:42:52.814197Z","caller":"traceutil/trace.go:171","msg":"trace[46406971] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"530.250386ms","start":"2026-02-02T17:42:52.283922Z","end":"2026-02-02T17:42:52.814173Z","steps":["trace[46406971] 'process raft request' (duration: 495.511268ms)","trace[46406971] 'compare' (duration: 34.264621ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:52.814503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.469788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18907edac84d0bb1\" ","response":"range_response_count:1 size:773"} {"level":"info","ts":"2026-02-02T17:42:52.814881Z","caller":"traceutil/trace.go:171","msg":"trace[213510871] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18907edac84d0bb1; range_end:; response_count:1; response_revision:763; }","duration":"432.885863ms","start":"2026-02-02T17:42:52.381977Z","end":"2026-02-02T17:42:52.814863Z","steps":["trace[213510871] 'agreement among raft nodes before linearized reading' (duration: 432.269131ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:52.815081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:52.38196Z","time spent":"433.100011ms","remote":"127.0.0.1:43800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":795,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.18907edac84d0bb1\" "} {"level":"warn","ts":"2026-02-02T17:42:52.814588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.694996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kubeadm-config\" ","response":"range_response_count:1 size:999"} {"level":"warn","ts":"2026-02-02T17:42:52.815594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:52.283911Z","time spent":"530.779395ms","remote":"127.0.0.1:43736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T17:42:52.815633Z","caller":"traceutil/trace.go:171","msg":"trace[1589379101] range","detail":"{range_begin:/registry/configmaps/kube-system/kubeadm-config; range_end:; response_count:1; response_revision:763; }","duration":"153.759593ms","start":"2026-02-02T17:42:52.66186Z","end":"2026-02-02T17:42:52.815619Z","steps":["trace[1589379101] 'agreement among raft nodes before linearized reading' (duration: 152.699536ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:53.016358Z","caller":"traceutil/trace.go:171","msg":"trace[1806501647] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"134.069434ms","start":"2026-02-02T17:42:52.882273Z","end":"2026-02-02T17:42:53.016342Z","steps":["trace[1806501647] 'process raft request' (duration: 45.76678ms)","trace[1806501647] 'compare' (duration: 88.067266ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:53.33152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.254918ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-02T17:42:53.33165Z","caller":"traceutil/trace.go:171","msg":"trace[145689213] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"274.55472ms","start":"2026-02-02T17:42:53.057085Z","end":"2026-02-02T17:42:53.33164Z","steps":["trace[145689213] 'process raft request' (duration: 274.510298ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:53.331777Z","caller":"traceutil/trace.go:171","msg":"trace[1339533882] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"280.578167ms","start":"2026-02-02T17:42:53.051193Z","end":"2026-02-02T17:42:53.331771Z","steps":["trace[1339533882] 'process raft request' (duration: 67.997868ms)","trace[1339533882] 'compare' (duration: 212.181086ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:54.192355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.787648ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-02T17:42:54.192453Z","caller":"traceutil/trace.go:171","msg":"trace[1532395618] linearizableReadLoop","detail":"{readStateIndex:807; appliedIndex:805; }","duration":"620.949221ms","start":"2026-02-02T17:42:53.571495Z","end":"2026-02-02T17:42:54.192445Z","steps":["trace[1532395618] 'read index received' (duration: 207.030348ms)","trace[1532395618] 'applied index is now lower than readState.Index' (duration: 413.918323ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:42:54.19274Z","caller":"traceutil/trace.go:171","msg":"trace[440978629] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"850.811164ms","start":"2026-02-02T17:42:53.341923Z","end":"2026-02-02T17:42:54.192734Z","steps":["trace[440978629] 'process raft request' (duration: 436.593281ms)","trace[440978629] 'compare' (duration: 413.627863ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:54.192773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:53.341916Z","time spent":"850.841566ms","remote":"127.0.0.1:55388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4975,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T17:42:54.192864Z","caller":"traceutil/trace.go:171","msg":"trace[1442333758] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"800.573477ms","start":"2026-02-02T17:42:53.392285Z","end":"2026-02-02T17:42:54.192858Z","steps":["trace[1442333758] 'process raft request' (duration: 800.127072ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:54.192886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:53.392273Z","time spent":"800.601027ms","remote":"127.0.0.1:43800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":787,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-02T17:42:54.192986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.49605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.72\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-02T17:42:54.192999Z","caller":"traceutil/trace.go:171","msg":"trace[1219113885] range","detail":"{range_begin:/registry/masterleases/199.204.45.72; range_end:; response_count:1; response_revision:771; }","duration":"621.545322ms","start":"2026-02-02T17:42:53.571449Z","end":"2026-02-02T17:42:54.192995Z","steps":["trace[1219113885] 'agreement among raft nodes before linearized reading' (duration: 621.48886ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:54.193012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:53.571435Z","time spent":"621.574282ms","remote":"127.0.0.1:43736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.204.45.72\" "} {"level":"warn","ts":"2026-02-02T17:42:54.193085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.099815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-02T17:42:54.193115Z","caller":"traceutil/trace.go:171","msg":"trace[1306653089] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:771; }","duration":"598.151487ms","start":"2026-02-02T17:42:53.594941Z","end":"2026-02-02T17:42:54.193093Z","steps":["trace[1306653089] 'agreement among raft nodes before linearized reading' (duration: 598.130506ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:54.193128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:53.594918Z","time spent":"598.205939ms","remote":"127.0.0.1:55592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":29,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-02T17:42:54.193249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.48034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:42:54.193262Z","caller":"traceutil/trace.go:171","msg":"trace[1710015311] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:771; }","duration":"441.519011ms","start":"2026-02-02T17:42:53.751739Z","end":"2026-02-02T17:42:54.193258Z","steps":["trace[1710015311] 'agreement among raft nodes before linearized reading' (duration: 441.498541ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:54.193272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:53.751724Z","time spent":"441.546042ms","remote":"127.0.0.1:43720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-02T17:42:55.086898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.57313ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-02T17:42:55.08702Z","caller":"traceutil/trace.go:171","msg":"trace[1587044449] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"883.23432ms","start":"2026-02-02T17:42:54.203765Z","end":"2026-02-02T17:42:55.087Z","steps":["trace[1587044449] 'process raft request' (duration: 509.498774ms)","trace[1587044449] 'compare' (duration: 373.429715ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:55.0871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:54.203758Z","time spent":"883.310652ms","remote":"127.0.0.1:43800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":874,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-02T17:42:55.142765Z","caller":"traceutil/trace.go:171","msg":"trace[1242995415] linearizableReadLoop","detail":"{readStateIndex:810; appliedIndex:808; }","duration":"857.143132ms","start":"2026-02-02T17:42:54.285607Z","end":"2026-02-02T17:42:55.14275Z","steps":["trace[1242995415] 'read index received' (duration: 427.675411ms)","trace[1242995415] 'applied index is now lower than readState.Index' (duration: 429.467021ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:42:55.142858Z","caller":"traceutil/trace.go:171","msg":"trace[2115063909] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"857.120081ms","start":"2026-02-02T17:42:54.285733Z","end":"2026-02-02T17:42:55.142853Z","steps":["trace[2115063909] 'process raft request' (duration: 856.975496ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:55.142927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:54.285727Z","time spent":"857.150033ms","remote":"127.0.0.1:55388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9121,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T17:42:55.143047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"857.438312ms","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-02T17:42:55.143062Z","caller":"traceutil/trace.go:171","msg":"trace[339576688] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:774; }","duration":"857.493934ms","start":"2026-02-02T17:42:54.285563Z","end":"2026-02-02T17:42:55.143057Z","steps":["trace[339576688] 'agreement among raft nodes before linearized reading' (duration: 857.431642ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:55.143076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:54.285556Z","time spent":"857.517165ms","remote":"127.0.0.1:55482","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":"info","ts":"2026-02-02T17:42:55.143181Z","caller":"traceutil/trace.go:171","msg":"trace[259367484] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"862.37605ms","start":"2026-02-02T17:42:54.280779Z","end":"2026-02-02T17:42:55.143155Z","steps":["trace[259367484] 'process raft request' (duration: 861.833472ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:55.14323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.911172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:42:55.143244Z","caller":"traceutil/trace.go:171","msg":"trace[895764249] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:774; }","duration":"388.948253ms","start":"2026-02-02T17:42:54.754292Z","end":"2026-02-02T17:42:55.14324Z","steps":["trace[895764249] 'agreement among raft nodes before linearized reading' (duration: 388.927482ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:55.143255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:54.754276Z","time spent":"388.976314ms","remote":"127.0.0.1:43720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-02T17:42:55.143304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:54.280764Z","time spent":"862.473773ms","remote":"127.0.0.1:43736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T17:42:55.617208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.049362ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-02T17:42:55.617293Z","caller":"traceutil/trace.go:171","msg":"trace[571145611] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"471.033579ms","start":"2026-02-02T17:42:55.146249Z","end":"2026-02-02T17:42:55.617283Z","steps":["trace[571145611] 'process raft request' (duration: 50.838522ms)","trace[571145611] 'compare' (duration: 419.955369ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:55.61733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:55.146228Z","time spent":"471.08519ms","remote":"127.0.0.1:43800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":824,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T17:42:55.671936Z","caller":"traceutil/trace.go:171","msg":"trace[1506727057] linearizableReadLoop","detail":"{readStateIndex:813; appliedIndex:811; }","duration":"517.239334ms","start":"2026-02-02T17:42:55.154677Z","end":"2026-02-02T17:42:55.671916Z","steps":["trace[1506727057] 'read index received' (duration: 42.418638ms)","trace[1506727057] 'applied index is now lower than readState.Index' (duration: 474.819866ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:42:55.673567Z","caller":"traceutil/trace.go:171","msg":"trace[1954317582] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"518.950772ms","start":"2026-02-02T17:42:55.154605Z","end":"2026-02-02T17:42:55.673556Z","steps":["trace[1954317582] 'process raft request' (duration: 517.138701ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:55.673655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:55.154588Z","time spent":"519.011184ms","remote":"127.0.0.1:55482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T17:42:55.674098Z","caller":"traceutil/trace.go:171","msg":"trace[1456437878] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"516.150997ms","start":"2026-02-02T17:42:55.157938Z","end":"2026-02-02T17:42:55.674089Z","steps":["trace[1456437878] 'process raft request' (duration: 513.906082ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:55.674176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:55.157928Z","time spent":"516.195248ms","remote":"127.0.0.1:55712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4317,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T17:42:55.674419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.737408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-02-02T17:42:55.674454Z","caller":"traceutil/trace.go:171","msg":"trace[1143412328] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:777; }","duration":"519.79209ms","start":"2026-02-02T17:42:55.154654Z","end":"2026-02-02T17:42:55.674446Z","steps":["trace[1143412328] 'agreement among raft nodes before linearized reading' (duration: 519.701157ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:55.674506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:55.154645Z","time spent":"519.853302ms","remote":"127.0.0.1:55378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":443,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-02-02T17:42:55.674822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.111041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-sfrbj\" ","response":"range_response_count:1 size:4820"} {"level":"info","ts":"2026-02-02T17:42:55.67485Z","caller":"traceutil/trace.go:171","msg":"trace[1203239733] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-sfrbj; range_end:; response_count:1; response_revision:777; }","duration":"520.149583ms","start":"2026-02-02T17:42:55.154694Z","end":"2026-02-02T17:42:55.674844Z","steps":["trace[1203239733] 'agreement among raft nodes before linearized reading' (duration: 520.021918ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:55.674899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:55.154692Z","time spent":"520.178133ms","remote":"127.0.0.1:55388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4842,"request content":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-sfrbj\" "} {"level":"warn","ts":"2026-02-02T17:42:56.310922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.172124ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-02T17:42:56.311035Z","caller":"traceutil/trace.go:171","msg":"trace[358496075] linearizableReadLoop","detail":"{readStateIndex:815; appliedIndex:814; }","duration":"623.826645ms","start":"2026-02-02T17:42:55.687192Z","end":"2026-02-02T17:42:56.311019Z","steps":["trace[358496075] 'read index received' (duration: 328.495155ms)","trace[358496075] 'applied index is now lower than readState.Index' (duration: 295.3303ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:42:56.311101Z","caller":"traceutil/trace.go:171","msg":"trace[2101592506] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"624.129125ms","start":"2026-02-02T17:42:55.686959Z","end":"2026-02-02T17:42:56.311088Z","steps":["trace[2101592506] 'process raft request' (duration: 328.719613ms)","trace[2101592506] 'compare' (duration: 295.04078ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:56.311152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:55.686936Z","time spent":"624.187696ms","remote":"127.0.0.1:55388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4993,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T17:42:56.311281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.084194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-02T17:42:56.311312Z","caller":"traceutil/trace.go:171","msg":"trace[1313795949] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:778; }","duration":"624.119564ms","start":"2026-02-02T17:42:55.687179Z","end":"2026-02-02T17:42:56.311299Z","steps":["trace[1313795949] 'agreement among raft nodes before linearized reading' (duration: 624.065713ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:56.311338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:55.687173Z","time spent":"624.158896ms","remote":"127.0.0.1:55494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"info","ts":"2026-02-02T17:42:56.318137Z","caller":"traceutil/trace.go:171","msg":"trace[878168753] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"627.714953ms","start":"2026-02-02T17:42:55.690407Z","end":"2026-02-02T17:42:56.318122Z","steps":["trace[878168753] 'process raft request' (duration: 627.533047ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:56.318305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:55.690399Z","time spent":"627.785456ms","remote":"127.0.0.1:55690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4653,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T17:42:56.31859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.366101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:42:56.318622Z","caller":"traceutil/trace.go:171","msg":"trace[1743116268] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:779; }","duration":"567.447974ms","start":"2026-02-02T17:42:55.751165Z","end":"2026-02-02T17:42:56.318613Z","steps":["trace[1743116268] 'agreement among raft nodes before linearized reading' (duration: 567.376542ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:56.318781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:55.751145Z","time spent":"567.62765ms","remote":"127.0.0.1:43724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-02T17:42:57.079704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.870957ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-02T17:42:57.079799Z","caller":"traceutil/trace.go:171","msg":"trace[863196454] linearizableReadLoop","detail":"{readStateIndex:818; appliedIndex:817; }","duration":"325.318023ms","start":"2026-02-02T17:42:56.754464Z","end":"2026-02-02T17:42:57.079782Z","steps":["trace[863196454] 'read index received' (duration: 46.242µs)","trace[863196454] 'applied index is now lower than readState.Index' (duration: 325.270551ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:57.079869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.410785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:42:57.079882Z","caller":"traceutil/trace.go:171","msg":"trace[513024724] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:781; }","duration":"325.480478ms","start":"2026-02-02T17:42:56.754396Z","end":"2026-02-02T17:42:57.079876Z","steps":["trace[513024724] 'agreement among raft nodes before linearized reading' (duration: 325.422146ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:57.079904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:56.754377Z","time spent":"325.52293ms","remote":"127.0.0.1:43720","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-02T17:42:57.080136Z","caller":"traceutil/trace.go:171","msg":"trace[2041546090] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"752.851326ms","start":"2026-02-02T17:42:56.327277Z","end":"2026-02-02T17:42:57.080128Z","steps":["trace[2041546090] 'process raft request' (duration: 382.475843ms)","trace[2041546090] 'compare' (duration: 369.66082ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:57.080168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:56.327265Z","time spent":"752.887678ms","remote":"127.0.0.1:55388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4497,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T17:42:57.517018Z","caller":"traceutil/trace.go:171","msg":"trace[467428712] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"425.256202ms","start":"2026-02-02T17:42:57.091745Z","end":"2026-02-02T17:42:57.517001Z","steps":["trace[467428712] 'process raft request' (duration: 425.159719ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:57.517339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:57.091735Z","time spent":"425.560242ms","remote":"127.0.0.1:55388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4993,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T17:42:57.781545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.099525ms","expected-duration":"100ms","prefix":"","request":"header: txn: success: > failure: >>","response":"size:18"} {"level":"info","ts":"2026-02-02T17:42:57.781836Z","caller":"traceutil/trace.go:171","msg":"trace[886960263] transaction","detail":"{read_only:false; number_of_response:1; response_revision:783; }","duration":"260.649804ms","start":"2026-02-02T17:42:57.52117Z","end":"2026-02-02T17:42:57.78182Z","steps":["trace[886960263] 'process raft request' (duration: 88.166847ms)","trace[886960263] 'compare' (duration: 171.933749ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:42:57.962397Z","caller":"traceutil/trace.go:171","msg":"trace[1978577925] linearizableReadLoop","detail":"{readStateIndex:822; appliedIndex:821; }","duration":"172.152837ms","start":"2026-02-02T17:42:57.790211Z","end":"2026-02-02T17:42:57.962364Z","steps":["trace[1978577925] 'read index received' (duration: 67.374382ms)","trace[1978577925] 'applied index is now lower than readState.Index' (duration: 104.777085ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:57.962685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.438056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-wjx4f\" ","response":"range_response_count:1 size:9136"} {"level":"info","ts":"2026-02-02T17:42:57.962718Z","caller":"traceutil/trace.go:171","msg":"trace[1410207417] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-wjx4f; range_end:; response_count:1; response_revision:785; }","duration":"172.521319ms","start":"2026-02-02T17:42:57.790187Z","end":"2026-02-02T17:42:57.962708Z","steps":["trace[1410207417] 'agreement among raft nodes before linearized reading' (duration: 172.281271ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:57.962998Z","caller":"traceutil/trace.go:171","msg":"trace[1710001234] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"175.30216ms","start":"2026-02-02T17:42:57.787683Z","end":"2026-02-02T17:42:57.962985Z","steps":["trace[1710001234] 'process raft request' (duration: 117.234515ms)","trace[1710001234] 'compare' (duration: 57.3174ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:42:58.195555Z","caller":"traceutil/trace.go:171","msg":"trace[580016296] linearizableReadLoop","detail":"{readStateIndex:823; appliedIndex:822; }","duration":"122.093764ms","start":"2026-02-02T17:42:58.073446Z","end":"2026-02-02T17:42:58.19554Z","steps":["trace[580016296] 'read index received' (duration: 87.098132ms)","trace[580016296] 'applied index is now lower than readState.Index' (duration: 34.994932ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:58.195691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.232999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/cilium-operator-7fcd8d6ffd-wjx4f.18907ee468e332b6\" ","response":"range_response_count:1 size:889"} {"level":"info","ts":"2026-02-02T17:42:58.195708Z","caller":"traceutil/trace.go:171","msg":"trace[643772879] range","detail":"{range_begin:/registry/events/kube-system/cilium-operator-7fcd8d6ffd-wjx4f.18907ee468e332b6; range_end:; response_count:1; response_revision:786; }","duration":"122.298821ms","start":"2026-02-02T17:42:58.073403Z","end":"2026-02-02T17:42:58.195702Z","steps":["trace[643772879] 'agreement among raft nodes before linearized reading' (duration: 122.192758ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:58.195861Z","caller":"traceutil/trace.go:171","msg":"trace[573179534] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"228.030513ms","start":"2026-02-02T17:42:57.967822Z","end":"2026-02-02T17:42:58.195853Z","steps":["trace[573179534] 'process raft request' (duration: 192.769783ms)","trace[573179534] 'compare' (duration: 34.684692ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:43:00.075196Z","caller":"traceutil/trace.go:171","msg":"trace[1709164559] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"105.67743ms","start":"2026-02-02T17:42:59.969499Z","end":"2026-02-02T17:43:00.075176Z","steps":["trace[1709164559] 'process raft request' (duration: 105.565587ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:36.361729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.726922ms","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-02T17:43:36.36181Z","caller":"traceutil/trace.go:171","msg":"trace[891507581] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:880; }","duration":"101.850285ms","start":"2026-02-02T17:43:36.259943Z","end":"2026-02-02T17:43:36.361793Z","steps":["trace[891507581] 'range keys from in-memory index tree' (duration: 101.547158ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:36.362312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.45245ms","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:1 size:12727"} {"level":"info","ts":"2026-02-02T17:43:36.362355Z","caller":"traceutil/trace.go:171","msg":"trace[1515953179] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:880; }","duration":"109.526692ms","start":"2026-02-02T17:43:36.252815Z","end":"2026-02-02T17:43:36.362342Z","steps":["trace[1515953179] 'range keys from in-memory index tree' (duration: 109.338297ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:43:36.674081Z","caller":"traceutil/trace.go:171","msg":"trace[1894462606] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"100.375631ms","start":"2026-02-02T17:43:36.573687Z","end":"2026-02-02T17:43:36.674063Z","steps":["trace[1894462606] 'process raft request' (duration: 100.243788ms)"],"step_count":1}