{"level":"warn","ts":"2026-04-13T04:04:27.146963Z","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-13T04:04:27.147063Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.246: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.246:2380","--initial-cluster=instance=https://199.204.45.246:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.246:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.246: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-13T04:04:27.147135Z","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-13T04:04:27.147147Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.246:2380"]} {"level":"info","ts":"2026-04-13T04:04:27.147172Z","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-13T04:04:27.147859Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"]} {"level":"info","ts":"2026-04-13T04:04:27.147981Z","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.246:2380"],"listen-peer-urls":["https://199.204.45.246:2380"],"advertise-client-urls":["https://199.204.45.246:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.246: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-13T04:04:27.152441Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.190516ms"} {"level":"info","ts":"2026-04-13T04:04:27.158852Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ab97322ebdd79664","cluster-id":"87bc89380c3dc2c"} {"level":"info","ts":"2026-04-13T04:04:27.158936Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=()"} {"level":"info","ts":"2026-04-13T04:04:27.158954Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became follower at term 0"} {"level":"info","ts":"2026-04-13T04:04:27.158973Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ab97322ebdd79664 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-13T04:04:27.158982Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became follower at term 1"} {"level":"info","ts":"2026-04-13T04:04:27.159016Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=(12364406478303893092)"} {"level":"warn","ts":"2026-04-13T04:04:27.165221Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-13T04:04:27.166437Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-13T04:04:27.167466Z","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-13T04:04:27.169392Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ab97322ebdd79664","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-13T04:04:27.171672Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=(12364406478303893092)"} {"level":"info","ts":"2026-04-13T04:04:27.171287Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ab97322ebdd79664","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-13T04:04:27.172572Z","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-13T04:04:27.172692Z","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-13T04:04:27.172706Z","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-13T04:04:27.173307Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"87bc89380c3dc2c","local-member-id":"ab97322ebdd79664","added-peer-id":"ab97322ebdd79664","added-peer-peer-urls":["https://199.204.45.246:2380"]} {"level":"info","ts":"2026-04-13T04:04:27.175116Z","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-13T04:04:27.175217Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.246:2380"} {"level":"info","ts":"2026-04-13T04:04:27.175258Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.246:2380"} {"level":"info","ts":"2026-04-13T04:04:27.175436Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ab97322ebdd79664","initial-advertise-peer-urls":["https://199.204.45.246:2380"],"listen-peer-urls":["https://199.204.45.246:2380"],"advertise-client-urls":["https://199.204.45.246:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-13T04:04:27.175478Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-13T04:04:27.959641Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 is starting a new election at term 1"} {"level":"info","ts":"2026-04-13T04:04:27.959686Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-13T04:04:27.959699Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 received MsgPreVoteResp from ab97322ebdd79664 at term 1"} {"level":"info","ts":"2026-04-13T04:04:27.95971Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became candidate at term 2"} {"level":"info","ts":"2026-04-13T04:04:27.959716Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 received MsgVoteResp from ab97322ebdd79664 at term 2"} {"level":"info","ts":"2026-04-13T04:04:27.959723Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became leader at term 2"} {"level":"info","ts":"2026-04-13T04:04:27.959731Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ab97322ebdd79664 elected leader ab97322ebdd79664 at term 2"} {"level":"info","ts":"2026-04-13T04:04:27.960617Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ab97322ebdd79664","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.246:2379]}","request-path":"/0/members/ab97322ebdd79664/attributes","cluster-id":"87bc89380c3dc2c","publish-timeout":"7s"} {"level":"info","ts":"2026-04-13T04:04:27.960731Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-13T04:04:27.960972Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T04:04:27.961151Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-13T04:04:27.962584Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.246:2379"} {"level":"info","ts":"2026-04-13T04:04:27.962691Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-13T04:04:27.962704Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-13T04:04:27.964223Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-13T04:04:27.964261Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"87bc89380c3dc2c","local-member-id":"ab97322ebdd79664","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T04:04:27.964317Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T04:04:27.964337Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T04:05:30.992908Z","caller":"traceutil/trace.go:171","msg":"trace[1578193859] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"138.970051ms","start":"2026-04-13T04:05:30.853331Z","end":"2026-04-13T04:05:30.992301Z","steps":["trace[1578193859] 'process raft request' (duration: 137.109598ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T04:05:37.963208Z","caller":"traceutil/trace.go:171","msg":"trace[1878279368] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"380.260935ms","start":"2026-04-13T04:05:37.582922Z","end":"2026-04-13T04:05:37.963183Z","steps":["trace[1878279368] 'process raft request' (duration: 380.128872ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T04:05:37.963745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T04:05:37.582902Z","time spent":"380.390499ms","remote":"127.0.0.1:57370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T04:05:37.975379Z","caller":"traceutil/trace.go:171","msg":"trace[508525598] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"387.807789ms","start":"2026-04-13T04:05:37.587492Z","end":"2026-04-13T04:05:37.9753Z","steps":["trace[508525598] 'process raft request' (duration: 387.595655ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T04:05:37.975412Z","caller":"traceutil/trace.go:171","msg":"trace[622732532] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"383.752546ms","start":"2026-04-13T04:05:37.591634Z","end":"2026-04-13T04:05:37.975386Z","steps":["trace[622732532] 'process raft request' (duration: 383.542172ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T04:05:37.975525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T04:05:37.587473Z","time spent":"387.984063ms","remote":"127.0.0.1:57370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T04:05:37.975558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T04:05:37.591614Z","time spent":"383.887529ms","remote":"127.0.0.1:57370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T04:05:37.975653Z","caller":"traceutil/trace.go:171","msg":"trace[61710074] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"373.465822ms","start":"2026-04-13T04:05:37.602177Z","end":"2026-04-13T04:05:37.975642Z","steps":["trace[61710074] 'process raft request' (duration: 373.243167ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T04:05:37.975623Z","caller":"traceutil/trace.go:171","msg":"trace[915244424] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"381.32316ms","start":"2026-04-13T04:05:37.594109Z","end":"2026-04-13T04:05:37.975433Z","steps":["trace[915244424] 'process raft request' (duration: 381.165867ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T04:05:37.975737Z","caller":"traceutil/trace.go:171","msg":"trace[58694954] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"377.734289ms","start":"2026-04-13T04:05:37.597994Z","end":"2026-04-13T04:05:37.975728Z","steps":["trace[58694954] 'process raft request' (duration: 377.386181ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T04:05:37.975716Z","caller":"traceutil/trace.go:171","msg":"trace[412023871] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"379.403367ms","start":"2026-04-13T04:05:37.59628Z","end":"2026-04-13T04:05:37.975684Z","steps":["trace[412023871] 'process raft request' (duration: 379.047439ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T04:05:37.97579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T04:05:37.597975Z","time spent":"377.78737ms","remote":"127.0.0.1:57370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T04:05:37.975819Z","caller":"traceutil/trace.go:171","msg":"trace[1029076053] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"331.713889ms","start":"2026-04-13T04:05:37.6441Z","end":"2026-04-13T04:05:37.975814Z","steps":["trace[1029076053] 'process raft request' (duration: 331.481314ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T04:05:37.975861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T04:05:37.644078Z","time spent":"331.764841ms","remote":"127.0.0.1:57370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T04:05:37.975859Z","caller":"traceutil/trace.go:171","msg":"trace[1090159215] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"234.214035ms","start":"2026-04-13T04:05:37.741634Z","end":"2026-04-13T04:05:37.975848Z","steps":["trace[1090159215] 'process raft request' (duration: 234.00066ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T04:05:37.975862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T04:05:37.596252Z","time spent":"379.549221ms","remote":"127.0.0.1:57370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T04:05:37.975761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.492002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T04:05:37.975949Z","caller":"traceutil/trace.go:171","msg":"trace[882461459] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"369.614014ms","start":"2026-04-13T04:05:37.606323Z","end":"2026-04-13T04:05:37.975937Z","steps":["trace[882461459] 'process raft request' (duration: 369.137923ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T04:05:37.976002Z","caller":"traceutil/trace.go:171","msg":"trace[895748895] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:615; }","duration":"380.673806ms","start":"2026-04-13T04:05:37.595255Z","end":"2026-04-13T04:05:37.975929Z","steps":["trace[895748895] 'agreement among raft nodes before linearized reading' (duration: 380.460471ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T04:05:37.976026Z","caller":"traceutil/trace.go:171","msg":"trace[1156712788] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"350.085918ms","start":"2026-04-13T04:05:37.625934Z","end":"2026-04-13T04:05:37.97602Z","steps":["trace[1156712788] 'process raft request' (duration: 349.563136ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T04:05:37.976037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T04:05:37.606317Z","time spent":"369.691475ms","remote":"127.0.0.1:57370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T04:05:37.976054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T04:05:37.595232Z","time spent":"380.803399ms","remote":"127.0.0.1:57320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T04:05:37.976064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T04:05:37.625919Z","time spent":"350.130049ms","remote":"127.0.0.1:57370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T04:05:37.975703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T04:05:37.602168Z","time spent":"373.512153ms","remote":"127.0.0.1:57370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T04:05:37.976039Z","caller":"traceutil/trace.go:171","msg":"trace[672652021] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"338.232177ms","start":"2026-04-13T04:05:37.637693Z","end":"2026-04-13T04:05:37.975925Z","steps":["trace[672652021] 'process raft request' (duration: 337.849069ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T04:05:37.976211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T04:05:37.594095Z","time spent":"381.868292ms","remote":"127.0.0.1:57370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T04:05:37.975684Z","caller":"traceutil/trace.go:171","msg":"trace[1460130612] linearizableReadLoop","detail":"{readStateIndex:633; appliedIndex:630; }","duration":"380.41436ms","start":"2026-04-13T04:05:37.595259Z","end":"2026-04-13T04:05:37.975674Z","steps":["trace[1460130612] 'read index received' (duration: 367.912294ms)","trace[1460130612] 'applied index is now lower than readState.Index' (duration: 12.501216ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T04:05:37.976259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T04:05:37.637675Z","time spent":"338.475464ms","remote":"127.0.0.1:57370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T04:05:37.982416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.048035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-13T04:05:37.982478Z","caller":"traceutil/trace.go:171","msg":"trace[1319335165] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:617; }","duration":"225.149056ms","start":"2026-04-13T04:05:37.757313Z","end":"2026-04-13T04:05:37.982462Z","steps":["trace[1319335165] 'agreement among raft nodes before linearized reading' (duration: 225.035934ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T04:05:41.855103Z","caller":"traceutil/trace.go:171","msg":"trace[1982817924] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"138.413538ms","start":"2026-04-13T04:05:41.716645Z","end":"2026-04-13T04:05:41.855059Z","steps":["trace[1982817924] 'process raft request' (duration: 134.468498ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T04:05:45.020669Z","caller":"traceutil/trace.go:171","msg":"trace[1411682112] linearizableReadLoop","detail":"{readStateIndex:747; appliedIndex:746; }","duration":"421.57692ms","start":"2026-04-13T04:05:44.59904Z","end":"2026-04-13T04:05:45.020617Z","steps":["trace[1411682112] 'read index received' (duration: 414.260892ms)","trace[1411682112] 'applied index is now lower than readState.Index' (duration: 7.314838ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T04:05:45.02071Z","caller":"traceutil/trace.go:171","msg":"trace[438657468] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"687.029446ms","start":"2026-04-13T04:05:44.333655Z","end":"2026-04-13T04:05:45.020685Z","steps":["trace[438657468] 'process raft request' (duration: 680.897155ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T04:05:45.020785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.728823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T04:05:45.020872Z","caller":"traceutil/trace.go:171","msg":"trace[86564924] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:716; }","duration":"421.868426ms","start":"2026-04-13T04:05:44.598985Z","end":"2026-04-13T04:05:45.020854Z","steps":["trace[86564924] 'agreement among raft nodes before linearized reading' (duration: 421.711013ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T04:05:45.020944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T04:05:44.598956Z","time spent":"421.981629ms","remote":"127.0.0.1:57306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T04:05:45.02103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T04:05:44.333611Z","time spent":"687.154329ms","remote":"127.0.0.1:57416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T04:05:45.141619Z","caller":"traceutil/trace.go:171","msg":"trace[1854488714] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"108.006535ms","start":"2026-04-13T04:05:45.033545Z","end":"2026-04-13T04:05:45.141551Z","steps":["trace[1854488714] 'process raft request' (duration: 70.357845ms)","trace[1854488714] 'compare' (duration: 37.149579ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T04:05:46.614767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.290641229s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-13T04:05:46.615207Z","caller":"traceutil/trace.go:171","msg":"trace[1398721894] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"1.53528266s","start":"2026-04-13T04:05:45.079873Z","end":"2026-04-13T04:05:46.615156Z","steps":["trace[1398721894] 'process raft request' (duration: 244.034468ms)","trace[1398721894] 'compare' (duration: 1.290126235s)"],"step_count":2} {"level":"warn","ts":"2026-04-13T04:05:46.615404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T04:05:45.07985Z","time spent":"1.535456654s","remote":"127.0.0.1:57564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T04:05:46.615419Z","caller":"traceutil/trace.go:171","msg":"trace[1147605554] linearizableReadLoop","detail":"{readStateIndex:750; appliedIndex:748; }","duration":"988.883993ms","start":"2026-04-13T04:05:45.626498Z","end":"2026-04-13T04:05:46.615382Z","steps":["trace[1147605554] 'read index received' (duration: 59.991µs)","trace[1147605554] 'applied index is now lower than readState.Index' (duration: 988.821892ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T04:05:46.615444Z","caller":"traceutil/trace.go:171","msg":"trace[1152708010] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"1.46997764s","start":"2026-04-13T04:05:45.145384Z","end":"2026-04-13T04:05:46.615362Z","steps":["trace[1152708010] 'process raft request' (duration: 1.469669023s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T04:05:46.615797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T04:05:45.145361Z","time spent":"1.470242996s","remote":"127.0.0.1:57606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T04:05:46.641093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.014568219s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T04:05:46.641163Z","caller":"traceutil/trace.go:171","msg":"trace[299588730] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:719; }","duration":"1.014665051s","start":"2026-04-13T04:05:45.62648Z","end":"2026-04-13T04:05:46.641145Z","steps":["trace[299588730] 'agreement among raft nodes before linearized reading' (duration: 989.070868ms)","trace[299588730] 'range keys from in-memory index tree' (duration: 25.473341ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T04:05:46.641197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T04:05:45.626418Z","time spent":"1.014772173s","remote":"127.0.0.1:57306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T04:05:46.641723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.070907ms","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-13T04:05:46.641844Z","caller":"traceutil/trace.go:171","msg":"trace[1729123410] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:719; }","duration":"556.238071ms","start":"2026-04-13T04:05:46.08557Z","end":"2026-04-13T04:05:46.641808Z","steps":["trace[1729123410] 'agreement among raft nodes before linearized reading' (duration: 555.919724ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T04:05:46.641915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T04:05:46.085541Z","time spent":"556.362963ms","remote":"127.0.0.1:57606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-13T04:05:46.642036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.200202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T04:05:46.6421Z","caller":"traceutil/trace.go:171","msg":"trace[348847053] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:719; }","duration":"393.324595ms","start":"2026-04-13T04:05:46.24876Z","end":"2026-04-13T04:05:46.642085Z","steps":["trace[348847053] 'agreement among raft nodes before linearized reading' (duration: 393.191122ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T04:05:46.642178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T04:05:46.248737Z","time spent":"393.429766ms","remote":"127.0.0.1:57864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":30,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-04-13T04:05:46.642362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.734431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-13T04:05:46.642414Z","caller":"traceutil/trace.go:171","msg":"trace[988885142] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:719; }","duration":"492.823894ms","start":"2026-04-13T04:05:46.149578Z","end":"2026-04-13T04:05:46.642402Z","steps":["trace[988885142] 'agreement among raft nodes before linearized reading' (duration: 492.690571ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T04:05:46.642452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T04:05:46.149558Z","time spent":"492.886946ms","remote":"127.0.0.1:57606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-13T04:05:48.244059Z","caller":"traceutil/trace.go:171","msg":"trace[1069193765] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"146.538863ms","start":"2026-04-13T04:05:48.097276Z","end":"2026-04-13T04:05:48.243815Z","steps":["trace[1069193765] 'process raft request' (duration: 146.337458ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T04:06:01.215313Z","caller":"traceutil/trace.go:171","msg":"trace[1741212304] linearizableReadLoop","detail":"{readStateIndex:912; appliedIndex:911; }","duration":"183.61839ms","start":"2026-04-13T04:06:01.031674Z","end":"2026-04-13T04:06:01.215292Z","steps":["trace[1741212304] 'read index received' (duration: 183.488117ms)","trace[1741212304] 'applied index is now lower than readState.Index' (duration: 129.313µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T04:06:01.215551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.861735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-626mg\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-04-13T04:06:01.215642Z","caller":"traceutil/trace.go:171","msg":"trace[1236588388] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-626mg; range_end:; response_count:1; response_revision:876; }","duration":"183.984549ms","start":"2026-04-13T04:06:01.031636Z","end":"2026-04-13T04:06:01.21562Z","steps":["trace[1236588388] 'agreement among raft nodes before linearized reading' (duration: 183.761494ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T04:06:01.744586Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836959797968739945,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-13T04:06:01.856684Z","caller":"traceutil/trace.go:171","msg":"trace[378602600] transaction","detail":"{read_only:false; response_revision:877; number_of_response:1; }","duration":"639.684025ms","start":"2026-04-13T04:06:01.216978Z","end":"2026-04-13T04:06:01.856662Z","steps":["trace[378602600] 'process raft request' (duration: 631.923068ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T04:06:01.856807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T04:06:01.216953Z","time spent":"639.784809ms","remote":"127.0.0.1:57350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T04:06:01.86252Z","caller":"traceutil/trace.go:171","msg":"trace[677919906] linearizableReadLoop","detail":"{readStateIndex:915; appliedIndex:912; }","duration":"618.679566ms","start":"2026-04-13T04:06:01.243828Z","end":"2026-04-13T04:06:01.862507Z","steps":["trace[677919906] 'read index received' (duration: 605.090166ms)","trace[677919906] 'applied index is now lower than readState.Index' (duration: 13.58856ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T04:06:01.862723Z","caller":"traceutil/trace.go:171","msg":"trace[673224043] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"568.186044ms","start":"2026-04-13T04:06:01.294491Z","end":"2026-04-13T04:06:01.862677Z","steps":["trace[673224043] 'process raft request' (duration: 567.977909ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T04:06:01.862897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T04:06:01.294466Z","time spent":"568.333468ms","remote":"127.0.0.1:57606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T04:06:01.862917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.627032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T04:06:01.862965Z","caller":"traceutil/trace.go:171","msg":"trace[1380637587] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:879; }","duration":"262.673103ms","start":"2026-04-13T04:06:01.600276Z","end":"2026-04-13T04:06:01.862949Z","steps":["trace[1380637587] 'agreement among raft nodes before linearized reading' (duration: 262.601531ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T04:06:01.86299Z","caller":"traceutil/trace.go:171","msg":"trace[87928435] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"638.757484ms","start":"2026-04-13T04:06:01.223965Z","end":"2026-04-13T04:06:01.862723Z","steps":["trace[87928435] 'process raft request' (duration: 638.428736ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T04:06:01.863156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T04:06:01.223949Z","time spent":"639.119992ms","remote":"127.0.0.1:57516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T04:06:01.86324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.426952ms","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-13T04:06:01.863273Z","caller":"traceutil/trace.go:171","msg":"trace[1652677848] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:879; }","duration":"619.494544ms","start":"2026-04-13T04:06:01.243769Z","end":"2026-04-13T04:06:01.863264Z","steps":["trace[1652677848] 'agreement among raft nodes before linearized reading' (duration: 618.985733ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T04:06:01.863304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T04:06:01.243749Z","time spent":"619.548306ms","remote":"127.0.0.1:57606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-13T04:06:16.234356Z","caller":"traceutil/trace.go:171","msg":"trace[1812937383] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"129.142127ms","start":"2026-04-13T04:06:16.105181Z","end":"2026-04-13T04:06:16.234323Z","steps":["trace[1812937383] 'process raft request' (duration: 94.211839ms)","trace[1812937383] 'compare' (duration: 34.799325ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T04:06:32.31851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.847659ms","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-13T04:06:32.318637Z","caller":"traceutil/trace.go:171","msg":"trace[470531085] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1072; }","duration":"158.016013ms","start":"2026-04-13T04:06:32.160594Z","end":"2026-04-13T04:06:32.31861Z","steps":["trace[470531085] 'range keys from in-memory index tree' (duration: 157.574933ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T04:06:32.433877Z","caller":"traceutil/trace.go:171","msg":"trace[319567332] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"109.25048ms","start":"2026-04-13T04:06:32.324593Z","end":"2026-04-13T04:06:32.433844Z","steps":["trace[319567332] 'process raft request' (duration: 109.069956ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T04:06:38.537396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.332649ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T04:06:38.5375Z","caller":"traceutil/trace.go:171","msg":"trace[539694329] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1096; }","duration":"211.451472ms","start":"2026-04-13T04:06:38.326033Z","end":"2026-04-13T04:06:38.537485Z","steps":["trace[539694329] 'range keys from in-memory index tree' (duration: 211.307179ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T04:07:03.949796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.384274ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T04:07:03.949901Z","caller":"traceutil/trace.go:171","msg":"trace[1083012272] linearizableReadLoop","detail":"{readStateIndex:1230; appliedIndex:1229; }","duration":"350.711025ms","start":"2026-04-13T04:07:03.599174Z","end":"2026-04-13T04:07:03.949885Z","steps":["trace[1083012272] 'read index received' (duration: 174.871581ms)","trace[1083012272] 'applied index is now lower than readState.Index' (duration: 175.838104ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T04:07:03.949933Z","caller":"traceutil/trace.go:171","msg":"trace[1771276117] transaction","detail":"{read_only:false; response_revision:1181; number_of_response:1; }","duration":"680.181316ms","start":"2026-04-13T04:07:03.269705Z","end":"2026-04-13T04:07:03.949886Z","steps":["trace[1771276117] 'process raft request' (duration: 504.321291ms)","trace[1771276117] 'compare' (duration: 174.889172ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T04:07:03.950097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T04:07:03.26969Z","time spent":"680.322338ms","remote":"127.0.0.1:57606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T04:07:03.949956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.780157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T04:07:03.950296Z","caller":"traceutil/trace.go:171","msg":"trace[1087116053] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1181; }","duration":"351.143505ms","start":"2026-04-13T04:07:03.599137Z","end":"2026-04-13T04:07:03.95028Z","steps":["trace[1087116053] 'agreement among raft nodes before linearized reading' (duration: 350.792197ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T04:07:03.950349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T04:07:03.59912Z","time spent":"351.216076ms","remote":"127.0.0.1:57306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-13T04:07:05.547556Z","caller":"traceutil/trace.go:171","msg":"trace[971742104] linearizableReadLoop","detail":"{readStateIndex:1234; appliedIndex:1233; }","duration":"330.574334ms","start":"2026-04-13T04:07:05.216956Z","end":"2026-04-13T04:07:05.547531Z","steps":["trace[971742104] 'read index received' (duration: 330.35547ms)","trace[971742104] 'applied index is now lower than readState.Index' (duration: 217.894µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T04:07:05.547674Z","caller":"traceutil/trace.go:171","msg":"trace[1551998644] transaction","detail":"{read_only:false; response_revision:1185; number_of_response:1; }","duration":"335.74611ms","start":"2026-04-13T04:07:05.211915Z","end":"2026-04-13T04:07:05.547661Z","steps":["trace[1551998644] 'process raft request' (duration: 335.472344ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T04:07:05.547835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.854561ms","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-13T04:07:05.547899Z","caller":"traceutil/trace.go:171","msg":"trace[390072681] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1185; }","duration":"330.973153ms","start":"2026-04-13T04:07:05.216911Z","end":"2026-04-13T04:07:05.547884Z","steps":["trace[390072681] 'agreement among raft nodes before linearized reading' (duration: 330.797169ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T04:07:05.547925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T04:07:05.211889Z","time spent":"335.812992ms","remote":"127.0.0.1:57606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T04:07:05.547941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T04:07:05.21689Z","time spent":"331.042394ms","remote":"127.0.0.1:57606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-13T04:07:05.548366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.236459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-13T04:07:05.548492Z","caller":"traceutil/trace.go:171","msg":"trace[1280055523] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1185; }","duration":"331.407283ms","start":"2026-04-13T04:07:05.217052Z","end":"2026-04-13T04:07:05.54846Z","steps":["trace[1280055523] 'agreement among raft nodes before linearized reading' (duration: 330.677477ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T04:07:05.54855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T04:07:05.21704Z","time spent":"331.498285ms","remote":"127.0.0.1:57606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-13T04:07:05.866414Z","caller":"traceutil/trace.go:171","msg":"trace[262563731] transaction","detail":"{read_only:false; response_revision:1186; number_of_response:1; }","duration":"314.393621ms","start":"2026-04-13T04:07:05.551975Z","end":"2026-04-13T04:07:05.866368Z","steps":["trace[262563731] 'process raft request' (duration: 272.554292ms)","trace[262563731] 'compare' (duration: 41.403839ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T04:07:05.866529Z","caller":"traceutil/trace.go:171","msg":"trace[597833737] linearizableReadLoop","detail":"{readStateIndex:1236; appliedIndex:1234; }","duration":"266.082388ms","start":"2026-04-13T04:07:05.60042Z","end":"2026-04-13T04:07:05.866502Z","steps":["trace[597833737] 'read index received' (duration: 224.202308ms)","trace[597833737] 'applied index is now lower than readState.Index' (duration: 41.87887ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T04:07:05.86657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T04:07:05.551958Z","time spent":"314.531665ms","remote":"127.0.0.1:57606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T04:07:05.866627Z","caller":"traceutil/trace.go:171","msg":"trace[1889816734] transaction","detail":"{read_only:false; response_revision:1187; number_of_response:1; }","duration":"314.132835ms","start":"2026-04-13T04:07:05.552484Z","end":"2026-04-13T04:07:05.866616Z","steps":["trace[1889816734] 'process raft request' (duration: 313.926531ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T04:07:05.866685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T04:07:05.552461Z","time spent":"314.199876ms","remote":"127.0.0.1:57606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T04:07:05.866768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.321443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T04:07:05.866848Z","caller":"traceutil/trace.go:171","msg":"trace[81242427] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1187; }","duration":"266.553258ms","start":"2026-04-13T04:07:05.600277Z","end":"2026-04-13T04:07:05.866831Z","steps":["trace[81242427] 'agreement among raft nodes before linearized reading' (duration: 266.401645ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T04:07:07.107564Z","caller":"traceutil/trace.go:171","msg":"trace[1762424633] transaction","detail":"{read_only:false; response_revision:1190; number_of_response:1; }","duration":"334.497062ms","start":"2026-04-13T04:07:06.773029Z","end":"2026-04-13T04:07:07.107526Z","steps":["trace[1762424633] 'process raft request' (duration: 334.312798ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T04:07:07.107828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T04:07:06.772998Z","time spent":"334.714397ms","remote":"127.0.0.1:57606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T04:07:07.699139Z","caller":"traceutil/trace.go:171","msg":"trace[1166325969] linearizableReadLoop","detail":"{readStateIndex:1241; appliedIndex:1240; }","duration":"100.866453ms","start":"2026-04-13T04:07:07.598243Z","end":"2026-04-13T04:07:07.69911Z","steps":["trace[1166325969] 'read index received' (duration: 100.641288ms)","trace[1166325969] 'applied index is now lower than readState.Index' (duration: 223.945µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T04:07:07.699224Z","caller":"traceutil/trace.go:171","msg":"trace[1676268944] transaction","detail":"{read_only:false; response_revision:1191; number_of_response:1; }","duration":"141.254367ms","start":"2026-04-13T04:07:07.557932Z","end":"2026-04-13T04:07:07.699186Z","steps":["trace[1676268944] 'process raft request' (duration: 140.994642ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T04:07:07.699295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.042986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T04:07:07.699528Z","caller":"traceutil/trace.go:171","msg":"trace[246652803] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1191; }","duration":"101.404485ms","start":"2026-04-13T04:07:07.598106Z","end":"2026-04-13T04:07:07.699511Z","steps":["trace[246652803] 'agreement among raft nodes before linearized reading' (duration: 101.124378ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T04:07:08.020245Z","caller":"traceutil/trace.go:171","msg":"trace[1883774912] transaction","detail":"{read_only:false; response_revision:1193; number_of_response:1; }","duration":"145.285509ms","start":"2026-04-13T04:07:07.874916Z","end":"2026-04-13T04:07:08.020202Z","steps":["trace[1883774912] 'process raft request' (duration: 105.489536ms)","trace[1883774912] 'compare' (duration: 39.521967ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T04:07:21.567025Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836959797968740927,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-13T04:07:21.770847Z","caller":"traceutil/trace.go:171","msg":"trace[777768845] linearizableReadLoop","detail":"{readStateIndex:1340; appliedIndex:1339; }","duration":"704.575032ms","start":"2026-04-13T04:07:21.066251Z","end":"2026-04-13T04:07:21.770826Z","steps":["trace[777768845] 'read index received' (duration: 704.45659ms)","trace[777768845] 'applied index is now lower than readState.Index' (duration: 116.812µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T04:07:21.77099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T04:07:21.018192Z","time spent":"752.794574ms","remote":"127.0.0.1:57350","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-13T04:07:21.771228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.983782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T04:07:21.771272Z","caller":"traceutil/trace.go:171","msg":"trace[848811673] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1285; }","duration":"705.081744ms","start":"2026-04-13T04:07:21.06618Z","end":"2026-04-13T04:07:21.771262Z","steps":["trace[848811673] 'agreement among raft nodes before linearized reading' (duration: 704.991622ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T04:07:21.771306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T04:07:21.066126Z","time spent":"705.169626ms","remote":"127.0.0.1:57864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":5,"response size":30,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-04-13T04:07:21.771618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.885314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T04:07:21.771682Z","caller":"traceutil/trace.go:171","msg":"trace[1201790924] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1285; }","duration":"368.976896ms","start":"2026-04-13T04:07:21.402685Z","end":"2026-04-13T04:07:21.771662Z","steps":["trace[1201790924] 'agreement among raft nodes before linearized reading' (duration: 368.878204ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T04:07:21.771721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T04:07:21.40267Z","time spent":"369.039958ms","remote":"127.0.0.1:46092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-04-13T04:07:21.771714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.858646ms","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-13T04:07:21.771814Z","caller":"traceutil/trace.go:171","msg":"trace[38336684] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1285; }","duration":"588.038849ms","start":"2026-04-13T04:07:21.18375Z","end":"2026-04-13T04:07:21.771788Z","steps":["trace[38336684] 'agreement among raft nodes before linearized reading' (duration: 587.701591ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T04:07:21.77187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T04:07:21.183728Z","time spent":"588.131141ms","remote":"127.0.0.1:57606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-13T04:07:21.772127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.719236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T04:07:21.772227Z","caller":"traceutil/trace.go:171","msg":"trace[1832210504] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1285; }","duration":"174.279749ms","start":"2026-04-13T04:07:21.597923Z","end":"2026-04-13T04:07:21.772203Z","steps":["trace[1832210504] 'agreement among raft nodes before linearized reading' (duration: 173.563413ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T04:07:36.171065Z","caller":"traceutil/trace.go:171","msg":"trace[1719817386] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"107.082548ms","start":"2026-04-13T04:07:36.063727Z","end":"2026-04-13T04:07:36.170809Z","steps":["trace[1719817386] 'process raft request' (duration: 93.35199ms)","trace[1719817386] 'compare' (duration: 13.619735ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T04:07:36.674569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.447271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T04:07:36.674624Z","caller":"traceutil/trace.go:171","msg":"trace[1220085814] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1346; }","duration":"101.546734ms","start":"2026-04-13T04:07:36.573065Z","end":"2026-04-13T04:07:36.674612Z","steps":["trace[1220085814] 'count revisions from in-memory index tree' (duration: 101.38411ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T04:07:36.981915Z","caller":"traceutil/trace.go:171","msg":"trace[2033039362] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"304.026796ms","start":"2026-04-13T04:07:36.677853Z","end":"2026-04-13T04:07:36.98188Z","steps":["trace[2033039362] 'process raft request' (duration: 303.824822ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T04:07:36.982211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T04:07:36.677841Z","time spent":"304.20351ms","remote":"127.0.0.1:57606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T04:07:37.46764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.03225ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-13T04:07:38.318738Z","caller":"traceutil/trace.go:171","msg":"trace[762575096] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"310.446411ms","start":"2026-04-13T04:07:38.008269Z","end":"2026-04-13T04:07:38.318716Z","steps":["trace[762575096] 'process raft request' (duration: 310.143944ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T04:07:38.320252Z","caller":"traceutil/trace.go:171","msg":"trace[197926300] linearizableReadLoop","detail":"{readStateIndex:1409; appliedIndex:1409; }","duration":"305.191944ms","start":"2026-04-13T04:07:38.014805Z","end":"2026-04-13T04:07:38.319997Z","steps":["trace[197926300] 'read index received' (duration: 305.182574ms)","trace[197926300] 'applied index is now lower than readState.Index' (duration: 8.03µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T04:07:38.320306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.444709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T04:07:38.320436Z","caller":"traceutil/trace.go:171","msg":"trace[300671266] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1351; }","duration":"305.647874ms","start":"2026-04-13T04:07:38.014764Z","end":"2026-04-13T04:07:38.320412Z","steps":["trace[300671266] 'agreement among raft nodes before linearized reading' (duration: 305.333687ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T04:07:38.320502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T04:07:38.014747Z","time spent":"305.744106ms","remote":"127.0.0.1:57606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-13T04:07:38.320781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T04:07:38.008257Z","time spent":"310.553973ms","remote":"127.0.0.1:57402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":836,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T04:07:38.401835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.598982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-13T04:07:38.401916Z","caller":"traceutil/trace.go:171","msg":"trace[1794620198] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1352; }","duration":"338.749126ms","start":"2026-04-13T04:07:38.06315Z","end":"2026-04-13T04:07:38.4019Z","steps":["trace[1794620198] 'agreement among raft nodes before linearized reading' (duration: 338.545911ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T04:07:38.402071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T04:07:38.063109Z","time spent":"338.95314ms","remote":"127.0.0.1:57606","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":"info","ts":"2026-04-13T04:07:38.402421Z","caller":"traceutil/trace.go:171","msg":"trace[4409989] transaction","detail":"{read_only:false; response_revision:1352; number_of_response:1; }","duration":"207.880565ms","start":"2026-04-13T04:07:38.194532Z","end":"2026-04-13T04:07:38.402413Z","steps":["trace[4409989] 'process raft request' (duration: 205.681956ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T04:07:38.402531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.802211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-13T04:07:38.402547Z","caller":"traceutil/trace.go:171","msg":"trace[278575239] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1352; }","duration":"181.956275ms","start":"2026-04-13T04:07:38.220585Z","end":"2026-04-13T04:07:38.402541Z","steps":["trace[278575239] 'agreement among raft nodes before linearized reading' (duration: 181.906634ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T04:07:38.402703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.503424ms","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-13T04:07:38.402726Z","caller":"traceutil/trace.go:171","msg":"trace[809621981] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1352; }","duration":"227.570056ms","start":"2026-04-13T04:07:38.175149Z","end":"2026-04-13T04:07:38.402719Z","steps":["trace[809621981] 'agreement among raft nodes before linearized reading' (duration: 227.502314ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T04:07:38.402843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.738978ms","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-13T04:07:38.402859Z","caller":"traceutil/trace.go:171","msg":"trace[938903295] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1352; }","duration":"281.8059ms","start":"2026-04-13T04:07:38.121048Z","end":"2026-04-13T04:07:38.402854Z","steps":["trace[938903295] 'agreement among raft nodes before linearized reading' (duration: 281.761049ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T04:07:38.976996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.491583ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T04:07:38.977427Z","caller":"traceutil/trace.go:171","msg":"trace[1626714022] linearizableReadLoop","detail":"{readStateIndex:1415; appliedIndex:1411; }","duration":"558.7197ms","start":"2026-04-13T04:07:38.41869Z","end":"2026-04-13T04:07:38.97741Z","steps":["trace[1626714022] 'read index received' (duration: 326.593243ms)","trace[1626714022] 'applied index is now lower than readState.Index' (duration: 232.125587ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T04:07:38.977677Z","caller":"traceutil/trace.go:171","msg":"trace[1450889118] transaction","detail":"{read_only:false; response_revision:1355; number_of_response:1; }","duration":"571.231311ms","start":"2026-04-13T04:07:38.406407Z","end":"2026-04-13T04:07:38.977639Z","steps":["trace[1450889118] 'process raft request' (duration: 570.795901ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T04:07:38.985942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.765804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T04:07:38.985958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T04:07:38.406403Z","time spent":"579.423134ms","remote":"127.0.0.1:57606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T04:07:38.986003Z","caller":"traceutil/trace.go:171","msg":"trace[1699968404] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1358; }","duration":"496.897537ms","start":"2026-04-13T04:07:38.489093Z","end":"2026-04-13T04:07:38.985991Z","steps":["trace[1699968404] 'agreement among raft nodes before linearized reading' (duration: 496.685562ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T04:07:38.986077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T04:07:38.489069Z","time spent":"496.999789ms","remote":"127.0.0.1:46170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"info","ts":"2026-04-13T04:07:38.977762Z","caller":"traceutil/trace.go:171","msg":"trace[13334265] transaction","detail":"{read_only:false; response_revision:1357; number_of_response:1; }","duration":"569.633266ms","start":"2026-04-13T04:07:38.408121Z","end":"2026-04-13T04:07:38.977754Z","steps":["trace[13334265] 'process raft request' (duration: 569.236326ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T04:07:38.98636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.902791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T04:07:38.986417Z","caller":"traceutil/trace.go:171","msg":"trace[783191211] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1358; }","duration":"156.005174ms","start":"2026-04-13T04:07:38.830406Z","end":"2026-04-13T04:07:38.986411Z","steps":["trace[783191211] 'agreement among raft nodes before linearized reading' (duration: 155.910902ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T04:07:38.986539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.274855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T04:07:38.986576Z","caller":"traceutil/trace.go:171","msg":"trace[1878851614] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1358; }","duration":"388.363317ms","start":"2026-04-13T04:07:38.598208Z","end":"2026-04-13T04:07:38.986572Z","steps":["trace[1878851614] 'agreement among raft nodes before linearized reading' (duration: 388.314676ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T04:07:38.986607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T04:07:38.598126Z","time spent":"388.47446ms","remote":"127.0.0.1:57306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-13T04:07:38.977735Z","caller":"traceutil/trace.go:171","msg":"trace[469147763] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"571.446346ms","start":"2026-04-13T04:07:38.406234Z","end":"2026-04-13T04:07:38.97768Z","steps":["trace[469147763] 'process raft request' (duration: 339.199846ms)","trace[469147763] 'compare' (duration: 231.295648ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T04:07:38.98729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T04:07:38.406218Z","time spent":"581.01414ms","remote":"127.0.0.1:57606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T04:07:38.977804Z","caller":"traceutil/trace.go:171","msg":"trace[849744026] transaction","detail":"{read_only:false; response_revision:1356; number_of_response:1; }","duration":"570.7242ms","start":"2026-04-13T04:07:38.407068Z","end":"2026-04-13T04:07:38.977792Z","steps":["trace[849744026] 'process raft request' (duration: 570.254018ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T04:07:38.98744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T04:07:38.407062Z","time spent":"580.311434ms","remote":"127.0.0.1:57606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T04:07:38.977842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.151602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-04-13T04:07:38.978973Z","caller":"traceutil/trace.go:171","msg":"trace[1378059275] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"559.452808ms","start":"2026-04-13T04:07:38.419495Z","end":"2026-04-13T04:07:38.978948Z","steps":["trace[1378059275] 'process raft request' (duration: 559.327155ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T04:07:38.987536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T04:07:38.419483Z","time spent":"568.03534ms","remote":"127.0.0.1:57402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":773,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T04:07:38.987552Z","caller":"traceutil/trace.go:171","msg":"trace[1779838531] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1357; }","duration":"569.069552ms","start":"2026-04-13T04:07:38.418465Z","end":"2026-04-13T04:07:38.987535Z","steps":["trace[1779838531] 'agreement among raft nodes before linearized reading' (duration: 559.318674ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T04:07:38.987612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T04:07:38.418447Z","time spent":"569.154215ms","remote":"127.0.0.1:57606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":495,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-13T04:07:38.988103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T04:07:38.408093Z","time spent":"579.015456ms","remote":"127.0.0.1:57606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T04:08:06.563127Z","caller":"traceutil/trace.go:171","msg":"trace[1853168355] linearizableReadLoop","detail":"{readStateIndex:1583; appliedIndex:1582; }","duration":"130.086103ms","start":"2026-04-13T04:08:06.433016Z","end":"2026-04-13T04:08:06.563102Z","steps":["trace[1853168355] 'read index received' (duration: 129.908759ms)","trace[1853168355] 'applied index is now lower than readState.Index' (duration: 176.414µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T04:08:06.563229Z","caller":"traceutil/trace.go:171","msg":"trace[344259075] transaction","detail":"{read_only:false; response_revision:1520; number_of_response:1; }","duration":"265.388863ms","start":"2026-04-13T04:08:06.297786Z","end":"2026-04-13T04:08:06.563175Z","steps":["trace[344259075] 'process raft request' (duration: 265.137097ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T04:08:06.563263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.223956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T04:08:06.563308Z","caller":"traceutil/trace.go:171","msg":"trace[1156901902] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1520; }","duration":"130.312218ms","start":"2026-04-13T04:08:06.43298Z","end":"2026-04-13T04:08:06.563292Z","steps":["trace[1156901902] 'agreement among raft nodes before linearized reading' (duration: 130.229877ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T04:08:17.034283Z","caller":"traceutil/trace.go:171","msg":"trace[1221753513] transaction","detail":"{read_only:false; response_revision:1567; number_of_response:1; }","duration":"388.303535ms","start":"2026-04-13T04:08:16.645955Z","end":"2026-04-13T04:08:17.034258Z","steps":["trace[1221753513] 'process raft request' (duration: 388.10672ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T04:08:17.034432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T04:08:16.64589Z","time spent":"388.467479ms","remote":"127.0.0.1:57606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T04:08:17.271645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.276598ms","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-13T04:08:17.271744Z","caller":"traceutil/trace.go:171","msg":"trace[18112145] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1567; }","duration":"122.431151ms","start":"2026-04-13T04:08:17.149284Z","end":"2026-04-13T04:08:17.271716Z","steps":["trace[18112145] 'range keys from in-memory index tree' (duration: 122.089894ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T04:08:17.271858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.00748ms","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-13T04:08:17.271957Z","caller":"traceutil/trace.go:171","msg":"trace[1786273366] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1567; }","duration":"113.111953ms","start":"2026-04-13T04:08:17.158808Z","end":"2026-04-13T04:08:17.27192Z","steps":["trace[1786273366] 'range keys from in-memory index tree' (duration: 112.873817ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T04:08:17.27199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.511182ms","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-13T04:08:17.272016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.683258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-04-13T04:08:17.272062Z","caller":"traceutil/trace.go:171","msg":"trace[1042192199] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1567; }","duration":"117.607814ms","start":"2026-04-13T04:08:17.154438Z","end":"2026-04-13T04:08:17.272046Z","steps":["trace[1042192199] 'range keys from in-memory index tree' (duration: 117.403019ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T04:08:17.272067Z","caller":"traceutil/trace.go:171","msg":"trace[1865732793] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1567; }","duration":"168.773509ms","start":"2026-04-13T04:08:17.10328Z","end":"2026-04-13T04:08:17.272054Z","steps":["trace[1865732793] 'range keys from in-memory index tree' (duration: 168.505393ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T04:08:17.272051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.368944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-13T04:08:17.27235Z","caller":"traceutil/trace.go:171","msg":"trace[1403951924] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1567; }","duration":"132.702662ms","start":"2026-04-13T04:08:17.13963Z","end":"2026-04-13T04:08:17.272332Z","steps":["trace[1403951924] 'range keys from in-memory index tree' (duration: 132.192959ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T04:08:17.271903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.879119ms","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-13T04:08:17.272833Z","caller":"traceutil/trace.go:171","msg":"trace[1396534709] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1567; }","duration":"122.845981ms","start":"2026-04-13T04:08:17.149975Z","end":"2026-04-13T04:08:17.272821Z","steps":["trace[1396534709] 'range keys from in-memory index tree' (duration: 121.696735ms)"],"step_count":1}