{"level":"warn","ts":"2026-04-13T22:41:46.219949Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-13T22:41:46.220134Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.53: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.53:2380","--initial-cluster=instance=https://199.204.45.53:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.53:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.53: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-04-13T22:41:46.220249Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-13T22:41:46.220273Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-04-13T22:41:46.220313Z","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-04-13T22:41:46.221071Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"]} {"level":"info","ts":"2026-04-13T22:41:46.221187Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.53: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-04-13T22:41:46.228331Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.870144ms"} {"level":"info","ts":"2026-04-13T22:41:46.236984Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c47ca1a7b1fe506b","cluster-id":"95bd21e158fabf07"} {"level":"info","ts":"2026-04-13T22:41:46.237108Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=()"} {"level":"info","ts":"2026-04-13T22:41:46.237139Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 0"} {"level":"info","ts":"2026-04-13T22:41:46.237154Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c47ca1a7b1fe506b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-13T22:41:46.237169Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 1"} {"level":"info","ts":"2026-04-13T22:41:46.237216Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"warn","ts":"2026-04-13T22:41:46.245011Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-13T22:41:46.246402Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-13T22:41:46.247587Z","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-04-13T22:41:46.25316Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c47ca1a7b1fe506b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-13T22:41:46.253429Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c47ca1a7b1fe506b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-13T22:41:46.254086Z","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-04-13T22:41:46.254573Z","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-04-13T22:41:46.25469Z","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-04-13T22:41:46.255313Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"info","ts":"2026-04-13T22:41:46.256491Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","added-peer-id":"c47ca1a7b1fe506b","added-peer-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-04-13T22:41:46.258715Z","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-04-13T22:41:46.258917Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-04-13T22:41:46.258988Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c47ca1a7b1fe506b","initial-advertise-peer-urls":["https://199.204.45.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-13T22:41:46.259023Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-04-13T22:41:46.259066Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-13T22:41:46.538034Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b is starting a new election at term 1"} {"level":"info","ts":"2026-04-13T22:41:46.538125Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became pre-candidate at term 1"} {"level":"info","ts":"2026-04-13T22:41:46.538149Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgPreVoteResp from c47ca1a7b1fe506b at term 1"} {"level":"info","ts":"2026-04-13T22:41:46.53837Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became candidate at term 2"} {"level":"info","ts":"2026-04-13T22:41:46.538408Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgVoteResp from c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-04-13T22:41:46.538452Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became leader at term 2"} {"level":"info","ts":"2026-04-13T22:41:46.538475Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c47ca1a7b1fe506b elected leader c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-04-13T22:41:46.549633Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c47ca1a7b1fe506b","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.53:2379]}","request-path":"/0/members/c47ca1a7b1fe506b/attributes","cluster-id":"95bd21e158fabf07","publish-timeout":"7s"} {"level":"info","ts":"2026-04-13T22:41:46.549975Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-13T22:41:46.550021Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-13T22:41:46.550287Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T22:41:46.550399Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-13T22:41:46.550476Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-13T22:41:46.557227Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.53:2379"} {"level":"info","ts":"2026-04-13T22:41:46.557963Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-13T22:41:46.590566Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T22:41:46.59083Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T22:41:46.590975Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T22:41:48.186401Z","caller":"traceutil/trace.go:171","msg":"trace[566169877] transaction","detail":"{read_only:false; response_revision:30; number_of_response:1; }","duration":"102.940169ms","start":"2026-04-13T22:41:48.083419Z","end":"2026-04-13T22:41:48.186359Z","steps":["trace[566169877] 'process raft request' (duration: 102.885586ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T22:41:48.186489Z","caller":"traceutil/trace.go:171","msg":"trace[508183626] transaction","detail":"{read_only:false; response_revision:29; number_of_response:1; }","duration":"103.170675ms","start":"2026-04-13T22:41:48.083251Z","end":"2026-04-13T22:41:48.186422Z","steps":["trace[508183626] 'process raft request' (duration: 48.073565ms)","trace[508183626] 'compare' (duration: 54.813681ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T22:41:48.210427Z","caller":"traceutil/trace.go:171","msg":"trace[63426075] transaction","detail":"{read_only:false; response_revision:32; number_of_response:1; }","duration":"125.606284ms","start":"2026-04-13T22:41:48.084801Z","end":"2026-04-13T22:41:48.210407Z","steps":["trace[63426075] 'process raft request' (duration: 125.490627ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T22:41:48.210479Z","caller":"traceutil/trace.go:171","msg":"trace[2131278748] transaction","detail":"{read_only:false; response_revision:31; number_of_response:1; }","duration":"126.357094ms","start":"2026-04-13T22:41:48.084078Z","end":"2026-04-13T22:41:48.210436Z","steps":["trace[2131278748] 'process raft request' (duration: 126.104527ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T22:41:48.210599Z","caller":"traceutil/trace.go:171","msg":"trace[1868737370] transaction","detail":"{read_only:false; response_revision:33; number_of_response:1; }","duration":"124.839942ms","start":"2026-04-13T22:41:48.085742Z","end":"2026-04-13T22:41:48.210582Z","steps":["trace[1868737370] 'process raft request' (duration: 124.591155ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T22:41:48.210642Z","caller":"traceutil/trace.go:171","msg":"trace[411944035] transaction","detail":"{read_only:false; response_revision:35; number_of_response:1; }","duration":"123.745267ms","start":"2026-04-13T22:41:48.086881Z","end":"2026-04-13T22:41:48.210626Z","steps":["trace[411944035] 'process raft request' (duration: 123.537073ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T22:41:48.210738Z","caller":"traceutil/trace.go:171","msg":"trace[1309185905] transaction","detail":"{read_only:false; response_revision:34; number_of_response:1; }","duration":"124.116653ms","start":"2026-04-13T22:41:48.086606Z","end":"2026-04-13T22:41:48.210723Z","steps":["trace[1309185905] 'process raft request' (duration: 123.770059ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T22:41:48.210933Z","caller":"traceutil/trace.go:171","msg":"trace[270924648] transaction","detail":"{read_only:false; response_revision:36; number_of_response:1; }","duration":"123.876557ms","start":"2026-04-13T22:41:48.087049Z","end":"2026-04-13T22:41:48.210926Z","steps":["trace[270924648] 'process raft request' (duration: 123.405154ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T22:41:48.210956Z","caller":"traceutil/trace.go:171","msg":"trace[2127580284] transaction","detail":"{read_only:false; response_revision:37; number_of_response:1; }","duration":"123.716626ms","start":"2026-04-13T22:41:48.087226Z","end":"2026-04-13T22:41:48.210942Z","steps":["trace[2127580284] 'process raft request' (duration: 123.391603ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T22:41:48.423349Z","caller":"traceutil/trace.go:171","msg":"trace[1239405339] linearizableReadLoop","detail":"{readStateIndex:43; appliedIndex:42; }","duration":"207.798178ms","start":"2026-04-13T22:41:48.215532Z","end":"2026-04-13T22:41:48.42333Z","steps":["trace[1239405339] 'read index received' (duration: 4.675916ms)","trace[1239405339] 'applied index is now lower than readState.Index' (duration: 203.121452ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T22:41:48.423534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.935438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-node-lease/\" range_end:\"/registry/resourcequotas/kube-node-lease0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-13T22:41:48.4236Z","caller":"traceutil/trace.go:171","msg":"trace[1587449335] range","detail":"{range_begin:/registry/resourcequotas/kube-node-lease/; range_end:/registry/resourcequotas/kube-node-lease0; response_count:0; response_revision:39; }","duration":"208.091679ms","start":"2026-04-13T22:41:48.215497Z","end":"2026-04-13T22:41:48.423588Z","steps":["trace[1587449335] 'agreement among raft nodes before linearized reading' (duration: 207.904036ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T22:41:48.423603Z","caller":"traceutil/trace.go:171","msg":"trace[296639520] transaction","detail":"{read_only:false; response_revision:39; number_of_response:1; }","duration":"208.017544ms","start":"2026-04-13T22:41:48.215483Z","end":"2026-04-13T22:41:48.423501Z","steps":["trace[296639520] 'process raft request' (duration: 127.317801ms)","trace[296639520] 'compare' (duration: 80.426754ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T22:41:48.789529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.074743ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-13T22:41:48.789945Z","caller":"traceutil/trace.go:171","msg":"trace[2099315801] linearizableReadLoop","detail":"{readStateIndex:51; appliedIndex:49; }","duration":"240.588359ms","start":"2026-04-13T22:41:48.549335Z","end":"2026-04-13T22:41:48.789924Z","steps":["trace[2099315801] 'read index received' (duration: 23.987754ms)","trace[2099315801] 'applied index is now lower than readState.Index' (duration: 216.598875ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T22:41:48.789995Z","caller":"traceutil/trace.go:171","msg":"trace[1728872102] transaction","detail":"{read_only:false; response_revision:47; number_of_response:1; }","duration":"243.375338ms","start":"2026-04-13T22:41:48.546587Z","end":"2026-04-13T22:41:48.789962Z","steps":["trace[1728872102] 'process raft request' (duration: 119.064741ms)","trace[1728872102] 'compare' (duration: 122.799305ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T22:41:48.790304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.954243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/system-nodes\" ","response":"range_response_count:1 size:1070"} {"level":"info","ts":"2026-04-13T22:41:48.790434Z","caller":"traceutil/trace.go:171","msg":"trace[127880326] range","detail":"{range_begin:/registry/flowschemas/system-nodes; range_end:; response_count:1; response_revision:47; }","duration":"241.117665ms","start":"2026-04-13T22:41:48.549261Z","end":"2026-04-13T22:41:48.790379Z","steps":["trace[127880326] 'agreement among raft nodes before linearized reading' (duration: 240.794163ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T22:41:50.334596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.338109ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-13T22:41:50.334952Z","caller":"traceutil/trace.go:171","msg":"trace[1535092930] transaction","detail":"{read_only:false; response_revision:173; number_of_response:1; }","duration":"348.765386ms","start":"2026-04-13T22:41:49.986163Z","end":"2026-04-13T22:41:50.334928Z","steps":["trace[1535092930] 'process raft request' (duration: 128.01633ms)","trace[1535092930] 'compare' (duration: 220.173377ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T22:41:50.335125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T22:41:49.986128Z","time spent":"348.96462ms","remote":"127.0.0.1:46072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T22:41:50.342132Z","caller":"traceutil/trace.go:171","msg":"trace[2032013385] linearizableReadLoop","detail":"{readStateIndex:179; appliedIndex:177; }","duration":"273.125042ms","start":"2026-04-13T22:41:50.068984Z","end":"2026-04-13T22:41:50.342109Z","steps":["trace[2032013385] 'read index received' (duration: 45.211639ms)","trace[2032013385] 'applied index is now lower than readState.Index' (duration: 227.909473ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T22:41:50.342251Z","caller":"traceutil/trace.go:171","msg":"trace[1500586185] transaction","detail":"{read_only:false; response_revision:174; number_of_response:1; }","duration":"312.050645ms","start":"2026-04-13T22:41:50.030174Z","end":"2026-04-13T22:41:50.342225Z","steps":["trace[1500586185] 'process raft request' (duration: 311.813769ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T22:41:50.342351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.354118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T22:41:50.342396Z","caller":"traceutil/trace.go:171","msg":"trace[1531506528] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:174; }","duration":"273.448655ms","start":"2026-04-13T22:41:50.068933Z","end":"2026-04-13T22:41:50.342382Z","steps":["trace[1531506528] 'agreement among raft nodes before linearized reading' (duration: 273.307265ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T22:41:50.342461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T22:41:50.030138Z","time spent":"312.187794ms","remote":"127.0.0.1:38252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T22:41:50.342599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.876872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T22:41:50.342682Z","caller":"traceutil/trace.go:171","msg":"trace[1592371083] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:174; }","duration":"242.075365ms","start":"2026-04-13T22:41:50.100588Z","end":"2026-04-13T22:41:50.342664Z","steps":["trace[1592371083] 'agreement among raft nodes before linearized reading' (duration: 241.779525ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T22:41:50.343034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.636285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T22:41:50.343106Z","caller":"traceutil/trace.go:171","msg":"trace[1394704027] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:174; }","duration":"269.727631ms","start":"2026-04-13T22:41:50.073366Z","end":"2026-04-13T22:41:50.343093Z","steps":["trace[1394704027] 'agreement among raft nodes before linearized reading' (duration: 268.926577ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T22:41:51.274166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.818112ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-13T22:41:51.27439Z","caller":"traceutil/trace.go:171","msg":"trace[1055292407] linearizableReadLoop","detail":"{readStateIndex:189; appliedIndex:188; }","duration":"204.786375ms","start":"2026-04-13T22:41:51.069571Z","end":"2026-04-13T22:41:51.274358Z","steps":["trace[1055292407] 'read index received' (duration: 60.077µs)","trace[1055292407] 'applied index is now lower than readState.Index' (duration: 204.724378ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T22:41:51.274592Z","caller":"traceutil/trace.go:171","msg":"trace[1519328979] transaction","detail":"{read_only:false; response_revision:184; number_of_response:1; }","duration":"758.846319ms","start":"2026-04-13T22:41:50.515719Z","end":"2026-04-13T22:41:51.274565Z","steps":["trace[1519328979] 'process raft request' (duration: 100.534152ms)","trace[1519328979] 'compare' (duration: 657.64023ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T22:41:51.27475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T22:41:50.515704Z","time spent":"758.951256ms","remote":"127.0.0.1:46040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T22:41:51.275088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.48328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T22:41:51.275157Z","caller":"traceutil/trace.go:171","msg":"trace[1041204986] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:184; }","duration":"205.631622ms","start":"2026-04-13T22:41:51.069507Z","end":"2026-04-13T22:41:51.275139Z","steps":["trace[1041204986] 'agreement among raft nodes before linearized reading' (duration: 204.971656ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T22:41:51.775385Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794898557312761538,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T22:41:52.275726Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794898557312761538,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T22:41:52.370428Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.235720126s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-13T22:41:53.101055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00026273s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-13T22:41:53.101153Z","caller":"traceutil/trace.go:171","msg":"trace[1813044436] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000417923s","start":"2026-04-13T22:41:51.100712Z","end":"2026-04-13T22:41:53.10113Z","steps":["trace[1813044436] 'agreement among raft nodes before linearized reading' (duration: 2.000283459s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T22:41:53.101215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T22:41:51.100698Z","time spent":"2.000501378s","remote":"127.0.0.1:38062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/13 22:41:53 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-13T22:41:53.162621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.671364ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-13T22:41:53.162767Z","caller":"traceutil/trace.go:171","msg":"trace[1722012616] linearizableReadLoop","detail":"{readStateIndex:190; appliedIndex:189; }","duration":"1.888259075s","start":"2026-04-13T22:41:51.274486Z","end":"2026-04-13T22:41:53.162745Z","steps":["trace[1722012616] 'read index received' (duration: 1.097305844s)","trace[1722012616] 'applied index is now lower than readState.Index' (duration: 790.951041ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T22:41:53.162874Z","caller":"traceutil/trace.go:171","msg":"trace[239848742] transaction","detail":"{read_only:false; response_revision:185; number_of_response:1; }","duration":"2.028208024s","start":"2026-04-13T22:41:51.134593Z","end":"2026-04-13T22:41:53.162801Z","steps":["trace[239848742] 'process raft request' (duration: 1.237256753s)","trace[239848742] 'compare' (duration: 790.442605ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T22:41:53.162969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.884228735s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/system:controller:cloud-provider\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T22:41:53.163096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T22:41:51.134565Z","time spent":"2.028389646s","remote":"127.0.0.1:38252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3959,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T22:41:53.163026Z","caller":"traceutil/trace.go:171","msg":"trace[1900017812] range","detail":"{range_begin:/registry/roles/kube-system/system:controller:cloud-provider; range_end:; response_count:0; response_revision:185; }","duration":"1.88432003s","start":"2026-04-13T22:41:51.278684Z","end":"2026-04-13T22:41:53.163004Z","steps":["trace[1900017812] 'agreement among raft nodes before linearized reading' (duration: 1.884188187s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T22:41:53.1632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T22:41:51.278666Z","time spent":"1.884518144s","remote":"127.0.0.1:46040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":29,"request content":"key:\"/registry/roles/kube-system/system:controller:cloud-provider\" "} {"level":"warn","ts":"2026-04-13T22:41:53.163452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.118989057s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T22:41:53.163496Z","caller":"traceutil/trace.go:171","msg":"trace[231974015] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:185; }","duration":"1.119071233s","start":"2026-04-13T22:41:52.04441Z","end":"2026-04-13T22:41:53.163482Z","steps":["trace[231974015] 'agreement among raft nodes before linearized reading' (duration: 1.119001976s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T22:41:53.1635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.105213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T22:41:53.163535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T22:41:52.044378Z","time spent":"1.119146339s","remote":"127.0.0.1:45974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-13T22:41:53.163534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.005894ms","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-04-13T22:41:53.163581Z","caller":"traceutil/trace.go:171","msg":"trace[662199262] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:185; }","duration":"506.245526ms","start":"2026-04-13T22:41:52.65731Z","end":"2026-04-13T22:41:53.163555Z","steps":["trace[662199262] 'agreement among raft nodes before linearized reading' (duration: 506.124532ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T22:41:53.163607Z","caller":"traceutil/trace.go:171","msg":"trace[470953389] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:185; }","duration":"767.128198ms","start":"2026-04-13T22:41:52.39646Z","end":"2026-04-13T22:41:53.163588Z","steps":["trace[470953389] 'agreement among raft nodes before linearized reading' (duration: 766.951406ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T22:41:53.163611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.599478381s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T22:41:53.163628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T22:41:52.657276Z","time spent":"506.341651ms","remote":"127.0.0.1:45974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-13T22:41:53.163652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T22:41:52.396438Z","time spent":"767.205394ms","remote":"127.0.0.1:45974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-13T22:41:53.163664Z","caller":"traceutil/trace.go:171","msg":"trace[1209036689] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:185; }","duration":"1.599578826s","start":"2026-04-13T22:41:51.564071Z","end":"2026-04-13T22:41:53.16365Z","steps":["trace[1209036689] 'agreement among raft nodes before linearized reading' (duration: 1.59948694s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T22:41:53.163709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T22:41:51.564045Z","time spent":"1.599652333s","remote":"127.0.0.1:45974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-13T22:41:53.163792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.171773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T22:41:53.16382Z","caller":"traceutil/trace.go:171","msg":"trace[411821705] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:185; }","duration":"595.235209ms","start":"2026-04-13T22:41:52.568575Z","end":"2026-04-13T22:41:53.16381Z","steps":["trace[411821705] 'agreement among raft nodes before linearized reading' (duration: 595.190442ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T22:41:53.163862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T22:41:52.56853Z","time spent":"595.312216ms","remote":"127.0.0.1:38046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T22:41:53.96518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.124086ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T22:41:53.965398Z","caller":"traceutil/trace.go:171","msg":"trace[1188595145] transaction","detail":"{read_only:false; response_revision:186; number_of_response:1; }","duration":"796.852914ms","start":"2026-04-13T22:41:53.168506Z","end":"2026-04-13T22:41:53.965359Z","steps":["trace[1188595145] 'process raft request' (duration: 444.320367ms)","trace[1188595145] 'compare' (duration: 352.003821ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T22:41:53.965545Z","caller":"traceutil/trace.go:171","msg":"trace[1725576572] transaction","detail":"{read_only:false; response_revision:188; number_of_response:1; }","duration":"796.663362ms","start":"2026-04-13T22:41:53.168852Z","end":"2026-04-13T22:41:53.965516Z","steps":["trace[1725576572] 'process raft request' (duration: 796.47719ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T22:41:53.965579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T22:41:53.168484Z","time spent":"797.011657ms","remote":"127.0.0.1:45974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T22:41:53.965634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"765.546937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T22:41:53.965675Z","caller":"traceutil/trace.go:171","msg":"trace[1177187381] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:190; }","duration":"765.623183ms","start":"2026-04-13T22:41:53.200042Z","end":"2026-04-13T22:41:53.965665Z","steps":["trace[1177187381] 'agreement among raft nodes before linearized reading' (duration: 765.557656ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T22:41:53.965674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T22:41:53.168842Z","time spent":"796.775487ms","remote":"127.0.0.1:45974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T22:41:53.965715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T22:41:53.200028Z","time spent":"765.679041ms","remote":"127.0.0.1:38062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-13T22:41:53.965793Z","caller":"traceutil/trace.go:171","msg":"trace[1071971726] transaction","detail":"{read_only:false; response_revision:187; number_of_response:1; }","duration":"797.113451ms","start":"2026-04-13T22:41:53.168669Z","end":"2026-04-13T22:41:53.965783Z","steps":["trace[1071971726] 'process raft request' (duration: 796.606724ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T22:41:53.965894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T22:41:53.16866Z","time spent":"797.159309ms","remote":"127.0.0.1:45974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T22:41:53.965936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T22:41:53.260744Z","time spent":"705.188948ms","remote":"127.0.0.1:38168","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-13T22:41:53.965568Z","caller":"traceutil/trace.go:171","msg":"trace[460482850] linearizableReadLoop","detail":"{readStateIndex:195; appliedIndex:190; }","duration":"765.46671ms","start":"2026-04-13T22:41:53.200083Z","end":"2026-04-13T22:41:53.96555Z","steps":["trace[460482850] 'read index received' (duration: 412.730012ms)","trace[460482850] 'applied index is now lower than readState.Index' (duration: 352.735838ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T22:41:53.965983Z","caller":"traceutil/trace.go:171","msg":"trace[902325866] transaction","detail":"{read_only:false; response_revision:189; number_of_response:1; }","duration":"795.744855ms","start":"2026-04-13T22:41:53.170222Z","end":"2026-04-13T22:41:53.965967Z","steps":["trace[902325866] 'process raft request' (duration: 795.132873ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T22:41:53.966048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T22:41:53.170215Z","time spent":"795.807411ms","remote":"127.0.0.1:45974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T22:41:53.966069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.280136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T22:41:53.966104Z","caller":"traceutil/trace.go:171","msg":"trace[1312134969] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:190; }","duration":"396.368983ms","start":"2026-04-13T22:41:53.569724Z","end":"2026-04-13T22:41:53.966093Z","steps":["trace[1312134969] 'agreement among raft nodes before linearized reading' (duration: 396.308095ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T22:41:53.966074Z","caller":"traceutil/trace.go:171","msg":"trace[1451616179] transaction","detail":"{read_only:false; response_revision:190; number_of_response:1; }","duration":"795.436788ms","start":"2026-04-13T22:41:53.170602Z","end":"2026-04-13T22:41:53.966039Z","steps":["trace[1451616179] 'process raft request' (duration: 794.800178ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T22:41:53.966139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T22:41:53.569697Z","time spent":"396.436419ms","remote":"127.0.0.1:38046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T22:41:53.966235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T22:41:53.170581Z","time spent":"795.576782ms","remote":"127.0.0.1:46040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":610,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T22:41:55.206677Z","caller":"traceutil/trace.go:171","msg":"trace[46468489] transaction","detail":"{read_only:false; response_revision:262; number_of_response:1; }","duration":"109.26072ms","start":"2026-04-13T22:41:55.097391Z","end":"2026-04-13T22:41:55.206651Z","steps":["trace[46468489] 'process raft request' (duration: 100.355005ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T22:41:55.381583Z","caller":"traceutil/trace.go:171","msg":"trace[1204177285] transaction","detail":"{read_only:false; response_revision:263; number_of_response:1; }","duration":"167.352649ms","start":"2026-04-13T22:41:55.214191Z","end":"2026-04-13T22:41:55.381544Z","steps":["trace[1204177285] 'process raft request' (duration: 151.679546ms)","trace[1204177285] 'compare' (duration: 15.558608ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T22:41:55.411312Z","caller":"traceutil/trace.go:171","msg":"trace[871198201] transaction","detail":"{read_only:false; response_revision:264; number_of_response:1; }","duration":"195.156268ms","start":"2026-04-13T22:41:55.216122Z","end":"2026-04-13T22:41:55.411278Z","steps":["trace[871198201] 'process raft request' (duration: 194.927988ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T22:41:55.558764Z","caller":"traceutil/trace.go:171","msg":"trace[2062480454] linearizableReadLoop","detail":"{readStateIndex:273; appliedIndex:272; }","duration":"117.285523ms","start":"2026-04-13T22:41:55.441448Z","end":"2026-04-13T22:41:55.558734Z","steps":["trace[2062480454] 'read index received' (duration: 109.459831ms)","trace[2062480454] 'applied index is now lower than readState.Index' (duration: 7.824513ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T22:41:55.558864Z","caller":"traceutil/trace.go:171","msg":"trace[318246499] transaction","detail":"{read_only:false; response_revision:266; number_of_response:1; }","duration":"131.144585ms","start":"2026-04-13T22:41:55.427711Z","end":"2026-04-13T22:41:55.558855Z","steps":["trace[318246499] 'process raft request' (duration: 123.287234ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T22:41:55.559025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.564552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"warn","ts":"2026-04-13T22:41:55.559281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.835254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-eu6kcx\" ","response":"range_response_count:1 size:540"} {"level":"info","ts":"2026-04-13T22:41:55.559367Z","caller":"traceutil/trace.go:171","msg":"trace[1025140419] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:266; }","duration":"117.902847ms","start":"2026-04-13T22:41:55.44144Z","end":"2026-04-13T22:41:55.559343Z","steps":["trace[1025140419] 'agreement among raft nodes before linearized reading' (duration: 117.526593ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T22:41:55.559381Z","caller":"traceutil/trace.go:171","msg":"trace[40426561] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-eu6kcx; range_end:; response_count:1; response_revision:266; }","duration":"117.039215ms","start":"2026-04-13T22:41:55.442321Z","end":"2026-04-13T22:41:55.559361Z","steps":["trace[40426561] 'agreement among raft nodes before linearized reading' (duration: 116.785376ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T22:42:04.907816Z","caller":"traceutil/trace.go:171","msg":"trace[1387217831] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"114.062647ms","start":"2026-04-13T22:42:04.79373Z","end":"2026-04-13T22:42:04.907793Z","steps":["trace[1387217831] 'process raft request' (duration: 113.879443ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T22:42:06.229946Z","caller":"traceutil/trace.go:171","msg":"trace[1434188125] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"169.241226ms","start":"2026-04-13T22:42:06.060674Z","end":"2026-04-13T22:42:06.229916Z","steps":["trace[1434188125] 'process raft request' (duration: 77.828282ms)","trace[1434188125] 'compare' (duration: 91.112921ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T22:42:06.230724Z","caller":"traceutil/trace.go:171","msg":"trace[1213017892] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"169.43935ms","start":"2026-04-13T22:42:06.060728Z","end":"2026-04-13T22:42:06.230167Z","steps":["trace[1213017892] 'process raft request' (duration: 169.109188ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T22:42:18.332766Z","caller":"traceutil/trace.go:171","msg":"trace[1487767001] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"303.192954ms","start":"2026-04-13T22:42:18.029545Z","end":"2026-04-13T22:42:18.332738Z","steps":["trace[1487767001] 'process raft request' (duration: 302.887639ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T22:42:18.332922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T22:42:18.029525Z","time spent":"303.323133ms","remote":"127.0.0.1:38126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T22:42:18.340382Z","caller":"traceutil/trace.go:171","msg":"trace[147024423] linearizableReadLoop","detail":"{readStateIndex:539; appliedIndex:528; }","duration":"198.546088ms","start":"2026-04-13T22:42:18.141818Z","end":"2026-04-13T22:42:18.340364Z","steps":["trace[147024423] 'read index received' (duration: 190.148634ms)","trace[147024423] 'applied index is now lower than readState.Index' (duration: 8.396394ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T22:42:18.340622Z","caller":"traceutil/trace.go:171","msg":"trace[965422696] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"310.671809ms","start":"2026-04-13T22:42:18.029936Z","end":"2026-04-13T22:42:18.340608Z","steps":["trace[965422696] 'process raft request' (duration: 309.8011ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T22:42:18.340657Z","caller":"traceutil/trace.go:171","msg":"trace[1954123523] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"298.640442ms","start":"2026-04-13T22:42:18.041997Z","end":"2026-04-13T22:42:18.340637Z","steps":["trace[1954123523] 'process raft request' (duration: 298.034209ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T22:42:18.340753Z","caller":"traceutil/trace.go:171","msg":"trace[1758684712] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"300.41007ms","start":"2026-04-13T22:42:18.040333Z","end":"2026-04-13T22:42:18.340743Z","steps":["trace[1758684712] 'process raft request' (duration: 299.601619ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T22:42:18.340707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T22:42:18.029926Z","time spent":"310.732739ms","remote":"127.0.0.1:38126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T22:42:18.340819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T22:42:18.040314Z","time spent":"300.467719ms","remote":"127.0.0.1:38126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T22:42:18.340826Z","caller":"traceutil/trace.go:171","msg":"trace[2050712469] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"310.738899ms","start":"2026-04-13T22:42:18.030052Z","end":"2026-04-13T22:42:18.340791Z","steps":["trace[2050712469] 'process raft request' (duration: 309.760161ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T22:42:18.340848Z","caller":"traceutil/trace.go:171","msg":"trace[979106790] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"301.593675ms","start":"2026-04-13T22:42:18.039213Z","end":"2026-04-13T22:42:18.340807Z","steps":["trace[979106790] 'process raft request' (duration: 300.640136ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T22:42:18.340907Z","caller":"traceutil/trace.go:171","msg":"trace[1200064281] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"299.131086ms","start":"2026-04-13T22:42:18.041742Z","end":"2026-04-13T22:42:18.340873Z","steps":["trace[1200064281] 'process raft request' (duration: 298.246677ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T22:42:18.340941Z","caller":"traceutil/trace.go:171","msg":"trace[1328808919] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"273.307442ms","start":"2026-04-13T22:42:18.067622Z","end":"2026-04-13T22:42:18.340929Z","steps":["trace[1328808919] 'process raft request' (duration: 272.456532ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T22:42:18.340988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T22:42:18.039184Z","time spent":"301.729943ms","remote":"127.0.0.1:38126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T22:42:18.340984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T22:42:18.030026Z","time spent":"310.842668ms","remote":"127.0.0.1:38126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T22:42:18.341115Z","caller":"traceutil/trace.go:171","msg":"trace[2118539372] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"137.52317ms","start":"2026-04-13T22:42:18.203585Z","end":"2026-04-13T22:42:18.341108Z","steps":["trace[2118539372] 'process raft request' (duration: 136.719739ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T22:42:18.341176Z","caller":"traceutil/trace.go:171","msg":"trace[1188085314] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"265.788478ms","start":"2026-04-13T22:42:18.075382Z","end":"2026-04-13T22:42:18.34117Z","steps":["trace[1188085314] 'process raft request' (duration: 264.744651ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T22:42:18.341197Z","caller":"traceutil/trace.go:171","msg":"trace[510203479] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"251.622077ms","start":"2026-04-13T22:42:18.089563Z","end":"2026-04-13T22:42:18.341185Z","steps":["trace[510203479] 'process raft request' (duration: 250.617959ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T22:42:18.340702Z","caller":"traceutil/trace.go:171","msg":"trace[1651526380] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"300.39905ms","start":"2026-04-13T22:42:18.040274Z","end":"2026-04-13T22:42:18.340673Z","steps":["trace[1651526380] 'process raft request' (duration: 299.622879ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T22:42:18.341378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T22:42:18.040244Z","time spent":"301.099911ms","remote":"127.0.0.1:38126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T22:42:18.34131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.487886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-13T22:42:18.341506Z","caller":"traceutil/trace.go:171","msg":"trace[2140221689] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:521; }","duration":"199.750312ms","start":"2026-04-13T22:42:18.141748Z","end":"2026-04-13T22:42:18.341498Z","steps":["trace[2140221689] 'agreement among raft nodes before linearized reading' (duration: 199.466306ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T22:42:18.350345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.703163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-13T22:42:18.350382Z","caller":"traceutil/trace.go:171","msg":"trace[1777811257] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:523; }","duration":"184.771942ms","start":"2026-04-13T22:42:18.165602Z","end":"2026-04-13T22:42:18.350373Z","steps":["trace[1777811257] 'agreement among raft nodes before linearized reading' (duration: 184.697332ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T22:42:19.04619Z","caller":"traceutil/trace.go:171","msg":"trace[63067521] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"130.855117ms","start":"2026-04-13T22:42:18.915309Z","end":"2026-04-13T22:42:19.046164Z","steps":["trace[63067521] 'process raft request' (duration: 130.691099ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T22:42:19.046547Z","caller":"traceutil/trace.go:171","msg":"trace[1299400594] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"137.121358ms","start":"2026-04-13T22:42:18.909362Z","end":"2026-04-13T22:42:19.046483Z","steps":["trace[1299400594] 'process raft request' (duration: 108.313441ms)","trace[1299400594] 'compare' (duration: 28.049466ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T22:42:19.382355Z","caller":"traceutil/trace.go:171","msg":"trace[1782420941] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"129.992507ms","start":"2026-04-13T22:42:19.252342Z","end":"2026-04-13T22:42:19.382335Z","steps":["trace[1782420941] 'process raft request' (duration: 122.926149ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T22:42:25.959829Z","caller":"traceutil/trace.go:171","msg":"trace[1483214970] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:646; }","duration":"201.541852ms","start":"2026-04-13T22:42:25.758261Z","end":"2026-04-13T22:42:25.959803Z","steps":["trace[1483214970] 'read index received' (duration: 200.556348ms)","trace[1483214970] 'applied index is now lower than readState.Index' (duration: 984.484µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T22:42:25.959917Z","caller":"traceutil/trace.go:171","msg":"trace[1003527300] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"491.577004ms","start":"2026-04-13T22:42:25.468299Z","end":"2026-04-13T22:42:25.959876Z","steps":["trace[1003527300] 'process raft request' (duration: 490.884437ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T22:42:25.959999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.714122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T22:42:25.960054Z","caller":"traceutil/trace.go:171","msg":"trace[1484351761] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:622; }","duration":"201.803491ms","start":"2026-04-13T22:42:25.758224Z","end":"2026-04-13T22:42:25.960028Z","steps":["trace[1484351761] 'agreement among raft nodes before linearized reading' (duration: 201.722991ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T22:42:25.960117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T22:42:25.468265Z","time spent":"491.736232ms","remote":"127.0.0.1:38182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036301,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T22:42:25.960255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.66699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-75n8m\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-13T22:42:25.96035Z","caller":"traceutil/trace.go:171","msg":"trace[1708183725] range","detail":"{range_begin:/registry/pods/kube-system/cilium-75n8m; range_end:; response_count:1; response_revision:622; }","duration":"108.826749ms","start":"2026-04-13T22:42:25.8515Z","end":"2026-04-13T22:42:25.960326Z","steps":["trace[1708183725] 'agreement among raft nodes before linearized reading' (duration: 108.60597ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T22:42:26.532745Z","caller":"traceutil/trace.go:171","msg":"trace[884013418] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"555.613578ms","start":"2026-04-13T22:42:25.97708Z","end":"2026-04-13T22:42:26.532694Z","steps":["trace[884013418] 'process raft request' (duration: 555.354498ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T22:42:26.5331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T22:42:25.977058Z","time spent":"555.894167ms","remote":"127.0.0.1:38252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T22:42:26.568065Z","caller":"traceutil/trace.go:171","msg":"trace[901601302] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:647; }","duration":"171.995399ms","start":"2026-04-13T22:42:26.396045Z","end":"2026-04-13T22:42:26.56804Z","steps":["trace[901601302] 'read index received' (duration: 136.420264ms)","trace[901601302] 'applied index is now lower than readState.Index' (duration: 35.573755ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T22:42:26.568459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.336398ms","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-04-13T22:42:26.568528Z","caller":"traceutil/trace.go:171","msg":"trace[1559241355] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:624; }","duration":"172.483927ms","start":"2026-04-13T22:42:26.396009Z","end":"2026-04-13T22:42:26.568493Z","steps":["trace[1559241355] 'agreement among raft nodes before linearized reading' (duration: 172.198029ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T22:42:26.568503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.698629ms","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-04-13T22:42:26.568662Z","caller":"traceutil/trace.go:171","msg":"trace[729783569] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:624; }","duration":"171.908238ms","start":"2026-04-13T22:42:26.396737Z","end":"2026-04-13T22:42:26.568645Z","steps":["trace[729783569] 'agreement among raft nodes before linearized reading' (duration: 171.60516ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T22:42:26.56873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.912785ms","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-04-13T22:42:26.568273Z","caller":"traceutil/trace.go:171","msg":"trace[735537164] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"552.125578ms","start":"2026-04-13T22:42:26.016125Z","end":"2026-04-13T22:42:26.568251Z","steps":["trace[735537164] 'process raft request' (duration: 551.76166ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T22:42:26.568924Z","caller":"traceutil/trace.go:171","msg":"trace[1020637289] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:624; }","duration":"150.175064ms","start":"2026-04-13T22:42:26.418677Z","end":"2026-04-13T22:42:26.568852Z","steps":["trace[1020637289] 'agreement among raft nodes before linearized reading' (duration: 149.766036ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T22:42:26.569054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T22:42:26.016075Z","time spent":"552.892726ms","remote":"127.0.0.1:38312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T22:42:26.569109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.048254ms","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-04-13T22:42:26.56915Z","caller":"traceutil/trace.go:171","msg":"trace[1978353893] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:624; }","duration":"141.112884ms","start":"2026-04-13T22:42:26.428026Z","end":"2026-04-13T22:42:26.569139Z","steps":["trace[1978353893] 'agreement among raft nodes before linearized reading' (duration: 140.341467ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T22:42:26.677926Z","caller":"traceutil/trace.go:171","msg":"trace[1296811832] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"104.257404ms","start":"2026-04-13T22:42:26.57364Z","end":"2026-04-13T22:42:26.677897Z","steps":["trace[1296811832] 'process raft request' (duration: 104.033766ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T22:42:26.678384Z","caller":"traceutil/trace.go:171","msg":"trace[1350405836] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:649; }","duration":"100.943719ms","start":"2026-04-13T22:42:26.577361Z","end":"2026-04-13T22:42:26.678305Z","steps":["trace[1350405836] 'read index received' (duration: 31.365953ms)","trace[1350405836] 'applied index is now lower than readState.Index' (duration: 69.576656ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T22:42:26.678441Z","caller":"traceutil/trace.go:171","msg":"trace[237250151] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"104.243166ms","start":"2026-04-13T22:42:26.574184Z","end":"2026-04-13T22:42:26.678427Z","steps":["trace[237250151] 'process raft request' (duration: 103.848347ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T22:42:26.67851Z","caller":"traceutil/trace.go:171","msg":"trace[1162090171] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"104.482904ms","start":"2026-04-13T22:42:26.57401Z","end":"2026-04-13T22:42:26.678493Z","steps":["trace[1162090171] 'process raft request' (duration: 103.799847ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T22:42:26.67864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.255908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-13T22:42:26.67869Z","caller":"traceutil/trace.go:171","msg":"trace[430263569] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:628; }","duration":"101.355726ms","start":"2026-04-13T22:42:26.577322Z","end":"2026-04-13T22:42:26.678677Z","steps":["trace[430263569] 'agreement among raft nodes before linearized reading' (duration: 101.130318ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T22:42:28.633433Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.447364544s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-13T22:42:28.756797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000334363s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-13T22:42:28.756879Z","caller":"traceutil/trace.go:171","msg":"trace[1209815719] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000460872s","start":"2026-04-13T22:42:26.7564Z","end":"2026-04-13T22:42:28.756861Z","steps":["trace[1209815719] 'agreement among raft nodes before linearized reading' (duration: 2.000365162s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T22:42:28.756987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T22:42:26.756382Z","time spent":"2.000593451s","remote":"127.0.0.1:38046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/13 22:42:28 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-13T22:42:29.233105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.395039224s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-13T22:42:29.233452Z","caller":"traceutil/trace.go:171","msg":"trace[889838348] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"2.047532067s","start":"2026-04-13T22:42:27.185893Z","end":"2026-04-13T22:42:29.233425Z","steps":["trace[889838348] 'process raft request' (duration: 2.047370156s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T22:42:29.233569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T22:42:27.185871Z","time spent":"2.047635317s","remote":"127.0.0.1:45974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T22:42:29.233944Z","caller":"traceutil/trace.go:171","msg":"trace[2002847874] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"2.545180772s","start":"2026-04-13T22:42:26.688743Z","end":"2026-04-13T22:42:29.233924Z","steps":["trace[2002847874] 'process raft request' (duration: 149.240749ms)","trace[2002847874] 'compare' (duration: 2.394717455s)"],"step_count":2} {"level":"warn","ts":"2026-04-13T22:42:29.234022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T22:42:26.688732Z","time spent":"2.545252952s","remote":"127.0.0.1:38252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T22:42:29.234252Z","caller":"traceutil/trace.go:171","msg":"trace[1019521799] linearizableReadLoop","detail":"{readStateIndex:654; appliedIndex:653; }","duration":"2.477764657s","start":"2026-04-13T22:42:26.756447Z","end":"2026-04-13T22:42:29.234212Z","steps":["trace[1019521799] 'read index received' (duration: 81.568375ms)","trace[1019521799] 'applied index is now lower than readState.Index' (duration: 2.396194222s)"],"step_count":2} {"level":"info","ts":"2026-04-13T22:42:29.237891Z","caller":"traceutil/trace.go:171","msg":"trace[1748735740] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"1.304008605s","start":"2026-04-13T22:42:27.933855Z","end":"2026-04-13T22:42:29.237864Z","steps":["trace[1748735740] 'process raft request' (duration: 1.303789925s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T22:42:29.238146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T22:42:27.933834Z","time spent":"1.304163655s","remote":"127.0.0.1:38242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4538,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T22:42:29.241233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.959634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T22:42:29.24129Z","caller":"traceutil/trace.go:171","msg":"trace[1737118062] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:632; }","duration":"484.031665ms","start":"2026-04-13T22:42:28.757227Z","end":"2026-04-13T22:42:29.241258Z","steps":["trace[1737118062] 'agreement among raft nodes before linearized reading' (duration: 483.982016ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T22:42:29.241289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.596738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-13T22:42:29.241314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T22:42:28.757171Z","time spent":"484.137734ms","remote":"127.0.0.1:38062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-13T22:42:29.241326Z","caller":"traceutil/trace.go:171","msg":"trace[1336647710] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:632; }","duration":"592.676977ms","start":"2026-04-13T22:42:28.64864Z","end":"2026-04-13T22:42:29.241317Z","steps":["trace[1336647710] 'agreement among raft nodes before linearized reading' (duration: 592.546338ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T22:42:29.24135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T22:42:28.648619Z","time spent":"592.725857ms","remote":"127.0.0.1:45974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-13T22:42:29.241452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.823463ms","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-04-13T22:42:29.241512Z","caller":"traceutil/trace.go:171","msg":"trace[1845042817] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:632; }","duration":"557.948153ms","start":"2026-04-13T22:42:28.683551Z","end":"2026-04-13T22:42:29.241499Z","steps":["trace[1845042817] 'agreement among raft nodes before linearized reading' (duration: 557.716143ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T22:42:29.241497Z","caller":"traceutil/trace.go:171","msg":"trace[1154495697] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"262.880027ms","start":"2026-04-13T22:42:28.978609Z","end":"2026-04-13T22:42:29.241489Z","steps":["trace[1154495697] 'process raft request' (duration: 262.493058ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T22:42:29.241522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.690222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-13T22:42:29.24155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T22:42:28.683496Z","time spent":"558.047563ms","remote":"127.0.0.1:45974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-13T22:42:29.241556Z","caller":"traceutil/trace.go:171","msg":"trace[1444655831] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:632; }","duration":"558.774682ms","start":"2026-04-13T22:42:28.682769Z","end":"2026-04-13T22:42:29.241543Z","steps":["trace[1444655831] 'agreement among raft nodes before linearized reading' (duration: 558.696692ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T22:42:29.241577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T22:42:28.682747Z","time spent":"558.826712ms","remote":"127.0.0.1:45974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-13T22:42:29.241717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.743414ms","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-04-13T22:42:29.241805Z","caller":"traceutil/trace.go:171","msg":"trace[131423213] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:632; }","duration":"557.851364ms","start":"2026-04-13T22:42:28.683944Z","end":"2026-04-13T22:42:29.241796Z","steps":["trace[131423213] 'agreement among raft nodes before linearized reading' (duration: 557.504154ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T22:42:29.241945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T22:42:28.683911Z","time spent":"558.023644ms","remote":"127.0.0.1:45974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-13T22:42:30.282892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.766231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T22:42:30.283038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.591187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T22:42:30.283112Z","caller":"traceutil/trace.go:171","msg":"trace[2134788516] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:645; }","duration":"221.670977ms","start":"2026-04-13T22:42:30.061425Z","end":"2026-04-13T22:42:30.283096Z","steps":["trace[2134788516] 'range keys from in-memory index tree' (duration: 221.562947ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T22:42:30.283311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.5884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T22:42:30.283391Z","caller":"traceutil/trace.go:171","msg":"trace[1859417430] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:645; }","duration":"132.70014ms","start":"2026-04-13T22:42:30.150672Z","end":"2026-04-13T22:42:30.283372Z","steps":["trace[1859417430] 'count revisions from in-memory index tree' (duration: 132.51822ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T22:42:30.283428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.68598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T22:42:30.283473Z","caller":"traceutil/trace.go:171","msg":"trace[1948347073] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:645; }","duration":"132.73323ms","start":"2026-04-13T22:42:30.150729Z","end":"2026-04-13T22:42:30.283463Z","steps":["trace[1948347073] 'range keys from in-memory index tree' (duration: 132.63938ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T22:42:30.282943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.388897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T22:42:30.283845Z","caller":"traceutil/trace.go:171","msg":"trace[1370207719] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:645; }","duration":"222.318657ms","start":"2026-04-13T22:42:30.061513Z","end":"2026-04-13T22:42:30.283831Z","steps":["trace[1370207719] 'range keys from in-memory index tree' (duration: 221.357247ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T22:42:30.283001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.625097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T22:42:30.283904Z","caller":"traceutil/trace.go:171","msg":"trace[1189927167] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:645; }","duration":"222.546707ms","start":"2026-04-13T22:42:30.061342Z","end":"2026-04-13T22:42:30.283889Z","steps":["trace[1189927167] 'count revisions from in-memory index tree' (duration: 221.564387ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T22:42:30.286334Z","caller":"traceutil/trace.go:171","msg":"trace[250010901] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:645; }","duration":"135.24149ms","start":"2026-04-13T22:42:30.15107Z","end":"2026-04-13T22:42:30.286311Z","steps":["trace[250010901] 'range keys from in-memory index tree' (duration: 131.701341ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T22:42:33.089074Z","caller":"traceutil/trace.go:171","msg":"trace[838903805] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"181.538252ms","start":"2026-04-13T22:42:32.907501Z","end":"2026-04-13T22:42:33.089039Z","steps":["trace[838903805] 'process raft request' (duration: 145.576468ms)","trace[838903805] 'compare' (duration: 35.710453ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T22:42:33.82871Z","caller":"traceutil/trace.go:171","msg":"trace[1680586006] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"278.869538ms","start":"2026-04-13T22:42:33.549805Z","end":"2026-04-13T22:42:33.828675Z","steps":["trace[1680586006] 'process raft request' (duration: 278.587747ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T22:42:34.141521Z","caller":"traceutil/trace.go:171","msg":"trace[1067938749] linearizableReadLoop","detail":"{readStateIndex:728; appliedIndex:726; }","duration":"382.436862ms","start":"2026-04-13T22:42:33.759051Z","end":"2026-04-13T22:42:34.141488Z","steps":["trace[1067938749] 'read index received' (duration: 69.358643ms)","trace[1067938749] 'applied index is now lower than readState.Index' (duration: 313.076589ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T22:42:34.141595Z","caller":"traceutil/trace.go:171","msg":"trace[908969740] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"511.962331ms","start":"2026-04-13T22:42:33.629578Z","end":"2026-04-13T22:42:34.14154Z","steps":["trace[908969740] 'process raft request' (duration: 510.738797ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T22:42:34.141672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.607412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T22:42:34.141776Z","caller":"traceutil/trace.go:171","msg":"trace[99177908] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:702; }","duration":"382.750952ms","start":"2026-04-13T22:42:33.759001Z","end":"2026-04-13T22:42:34.141752Z","steps":["trace[99177908] 'agreement among raft nodes before linearized reading' (duration: 382.594272ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T22:42:34.141801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T22:42:33.629555Z","time spent":"512.143901ms","remote":"127.0.0.1:38126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T22:42:34.141824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T22:42:33.758981Z","time spent":"382.833993ms","remote":"127.0.0.1:38046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T22:42:34.141832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.344163ms","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-04-13T22:42:34.141879Z","caller":"traceutil/trace.go:171","msg":"trace[1520748916] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:702; }","duration":"315.434644ms","start":"2026-04-13T22:42:33.826429Z","end":"2026-04-13T22:42:34.141863Z","steps":["trace[1520748916] 'agreement among raft nodes before linearized reading' (duration: 315.229373ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T22:42:34.141928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T22:42:33.826417Z","time spent":"315.502204ms","remote":"127.0.0.1:38126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":9344,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"warn","ts":"2026-04-13T22:42:34.142145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.663317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-04-13T22:42:34.142187Z","caller":"traceutil/trace.go:171","msg":"trace[902289295] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:702; }","duration":"114.725687ms","start":"2026-04-13T22:42:34.027448Z","end":"2026-04-13T22:42:34.142174Z","steps":["trace[902289295] 'agreement among raft nodes before linearized reading' (duration: 114.573896ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T22:42:34.354646Z","caller":"traceutil/trace.go:171","msg":"trace[1487505050] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"201.883892ms","start":"2026-04-13T22:42:34.152719Z","end":"2026-04-13T22:42:34.354603Z","steps":["trace[1487505050] 'process raft request' (duration: 201.301121ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T22:42:34.354668Z","caller":"traceutil/trace.go:171","msg":"trace[2047944005] linearizableReadLoop","detail":"{readStateIndex:729; appliedIndex:728; }","duration":"150.405131ms","start":"2026-04-13T22:42:34.20388Z","end":"2026-04-13T22:42:34.354285Z","steps":["trace[2047944005] 'read index received' (duration: 150.23326ms)","trace[2047944005] 'applied index is now lower than readState.Index' (duration: 170.531µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T22:42:34.354874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.983442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.53\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-13T22:42:34.354932Z","caller":"traceutil/trace.go:171","msg":"trace[1236763752] range","detail":"{range_begin:/registry/masterleases/199.204.45.53; range_end:; response_count:1; response_revision:703; }","duration":"151.099493ms","start":"2026-04-13T22:42:34.203819Z","end":"2026-04-13T22:42:34.354918Z","steps":["trace[1236763752] 'agreement among raft nodes before linearized reading' (duration: 150.867872ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T22:42:34.363378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.535191ms","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-04-13T22:42:34.363487Z","caller":"traceutil/trace.go:171","msg":"trace[79857291] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:704; }","duration":"136.876542ms","start":"2026-04-13T22:42:34.226592Z","end":"2026-04-13T22:42:34.363469Z","steps":["trace[79857291] 'agreement among raft nodes before linearized reading' (duration: 136.586731ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T22:42:47.915716Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794898557312762777,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-13T22:42:48.191995Z","caller":"traceutil/trace.go:171","msg":"trace[879943918] linearizableReadLoop","detail":"{readStateIndex:828; appliedIndex:827; }","duration":"776.485667ms","start":"2026-04-13T22:42:47.415474Z","end":"2026-04-13T22:42:48.191959Z","steps":["trace[879943918] 'read index received' (duration: 776.122702ms)","trace[879943918] 'applied index is now lower than readState.Index' (duration: 361.145µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T22:42:48.192156Z","caller":"traceutil/trace.go:171","msg":"trace[1753313195] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"840.118256ms","start":"2026-04-13T22:42:47.351995Z","end":"2026-04-13T22:42:48.192113Z","steps":["trace[1753313195] 'process raft request' (duration: 839.637999ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T22:42:48.192283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.725862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T22:42:48.192347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T22:42:47.351956Z","time spent":"840.297929ms","remote":"127.0.0.1:38252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4024,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T22:42:48.192346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.9726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T22:42:48.192361Z","caller":"traceutil/trace.go:171","msg":"trace[631346391] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:798; }","duration":"537.885435ms","start":"2026-04-13T22:42:47.654447Z","end":"2026-04-13T22:42:48.192332Z","steps":["trace[631346391] 'agreement among raft nodes before linearized reading' (duration: 537.747052ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T22:42:48.192412Z","caller":"traceutil/trace.go:171","msg":"trace[521322042] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:798; }","duration":"433.116652ms","start":"2026-04-13T22:42:47.75928Z","end":"2026-04-13T22:42:48.192397Z","steps":["trace[521322042] 'agreement among raft nodes before linearized reading' (duration: 432.96654ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T22:42:48.192418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T22:42:47.654411Z","time spent":"537.996876ms","remote":"127.0.0.1:38046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T22:42:48.192416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.043304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-13T22:42:48.192432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"696.848952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-13T22:42:48.192459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T22:42:47.759256Z","time spent":"433.193113ms","remote":"127.0.0.1:38062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-13T22:42:48.192503Z","caller":"traceutil/trace.go:171","msg":"trace[174315653] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:798; }","duration":"697.172836ms","start":"2026-04-13T22:42:47.495308Z","end":"2026-04-13T22:42:48.192481Z","steps":["trace[174315653] 'agreement among raft nodes before linearized reading' (duration: 696.959064ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T22:42:48.192513Z","caller":"traceutil/trace.go:171","msg":"trace[713169572] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:798; }","duration":"697.001074ms","start":"2026-04-13T22:42:47.495495Z","end":"2026-04-13T22:42:48.192496Z","steps":["trace[713169572] 'agreement among raft nodes before linearized reading' (duration: 696.781741ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T22:42:48.192563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T22:42:47.495445Z","time spent":"697.107415ms","remote":"127.0.0.1:45974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-13T22:42:48.192567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T22:42:47.495288Z","time spent":"697.261677ms","remote":"127.0.0.1:45974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-13T22:42:48.192761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.68608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-13T22:42:48.193623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"727.022575ms","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-04-13T22:42:48.193659Z","caller":"traceutil/trace.go:171","msg":"trace[1606764724] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:798; }","duration":"716.736401ms","start":"2026-04-13T22:42:47.476047Z","end":"2026-04-13T22:42:48.192783Z","steps":["trace[1606764724] 'agreement among raft nodes before linearized reading' (duration: 716.64656ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T22:42:48.193692Z","caller":"traceutil/trace.go:171","msg":"trace[2147046801] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:798; }","duration":"727.147537ms","start":"2026-04-13T22:42:47.466529Z","end":"2026-04-13T22:42:48.193676Z","steps":["trace[2147046801] 'agreement among raft nodes before linearized reading' (duration: 725.634095ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T22:42:48.193733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T22:42:47.476036Z","time spent":"717.678134ms","remote":"127.0.0.1:45974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-13T22:42:48.193737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T22:42:47.466507Z","time spent":"727.218309ms","remote":"127.0.0.1:45974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-13T22:42:48.194691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"779.213854ms","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-04-13T22:42:48.194758Z","caller":"traceutil/trace.go:171","msg":"trace[481338599] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:798; }","duration":"779.328566ms","start":"2026-04-13T22:42:47.415414Z","end":"2026-04-13T22:42:48.194742Z","steps":["trace[481338599] 'agreement among raft nodes before linearized reading' (duration: 776.71917ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T22:42:48.194807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T22:42:47.415378Z","time spent":"779.410316ms","remote":"127.0.0.1:45974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-13T22:43:00.001763Z","caller":"traceutil/trace.go:171","msg":"trace[135053723] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"116.511135ms","start":"2026-04-13T22:42:59.88523Z","end":"2026-04-13T22:43:00.001741Z","steps":["trace[135053723] 'process raft request' (duration: 116.366282ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T22:43:18.56611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.694124ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T22:43:18.566234Z","caller":"traceutil/trace.go:171","msg":"trace[2102501471] linearizableReadLoop","detail":"{readStateIndex:1030; appliedIndex:1029; }","duration":"138.565574ms","start":"2026-04-13T22:43:18.427651Z","end":"2026-04-13T22:43:18.566217Z","steps":["trace[2102501471] 'read index received' (duration: 30.675444ms)","trace[2102501471] 'applied index is now lower than readState.Index' (duration: 107.88903ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T22:43:18.566386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.30005ms","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-04-13T22:43:18.566353Z","caller":"traceutil/trace.go:171","msg":"trace[1877040640] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"174.404193ms","start":"2026-04-13T22:43:18.391914Z","end":"2026-04-13T22:43:18.566318Z","steps":["trace[1877040640] 'process raft request' (duration: 66.4069ms)","trace[1877040640] 'compare' (duration: 107.598312ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T22:43:18.566425Z","caller":"traceutil/trace.go:171","msg":"trace[1461320829] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:992; }","duration":"170.348111ms","start":"2026-04-13T22:43:18.396067Z","end":"2026-04-13T22:43:18.566415Z","steps":["trace[1461320829] 'agreement among raft nodes before linearized reading' (duration: 170.195917ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T22:43:18.566639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.30261ms","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-04-13T22:43:18.566705Z","caller":"traceutil/trace.go:171","msg":"trace[465246774] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:992; }","duration":"170.409633ms","start":"2026-04-13T22:43:18.39628Z","end":"2026-04-13T22:43:18.56669Z","steps":["trace[465246774] 'agreement among raft nodes before linearized reading' (duration: 170.105104ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T22:43:18.608256Z","caller":"traceutil/trace.go:171","msg":"trace[1113442642] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"176.748515ms","start":"2026-04-13T22:43:18.431483Z","end":"2026-04-13T22:43:18.608232Z","steps":["trace[1113442642] 'process raft request' (duration: 176.619611ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T22:43:22.677898Z","caller":"traceutil/trace.go:171","msg":"trace[227230955] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"234.852205ms","start":"2026-04-13T22:43:22.443023Z","end":"2026-04-13T22:43:22.677875Z","steps":["trace[227230955] 'process raft request' (duration: 234.67418ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T22:44:09.107485Z","caller":"traceutil/trace.go:171","msg":"trace[444021935] transaction","detail":"{read_only:false; response_revision:1165; number_of_response:1; }","duration":"110.261475ms","start":"2026-04-13T22:44:08.997202Z","end":"2026-04-13T22:44:09.107464Z","steps":["trace[444021935] 'process raft request' (duration: 110.212946ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T22:44:09.107607Z","caller":"traceutil/trace.go:171","msg":"trace[1318639909] transaction","detail":"{read_only:false; response_revision:1164; number_of_response:1; }","duration":"110.571695ms","start":"2026-04-13T22:44:08.996999Z","end":"2026-04-13T22:44:09.10757Z","steps":["trace[1318639909] 'process raft request' (duration: 107.675137ms)"],"step_count":1}