{"level":"warn","ts":"2026-04-08T06:13:04.87226Z","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-08T06:13:04.872381Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.216: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.216:2380","--initial-cluster=instance=https://199.204.45.216:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.216:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.216: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-08T06:13:04.872455Z","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-08T06:13:04.872482Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-04-08T06:13:04.872508Z","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-08T06:13:04.8731Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"]} {"level":"info","ts":"2026-04-08T06:13:04.873213Z","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.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.216: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-08T06:13:04.975131Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"88.812967ms"} {"level":"info","ts":"2026-04-08T06:13:05.043816Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e98569549cc430c","cluster-id":"65d70b4d1d4a8bf9"} {"level":"info","ts":"2026-04-08T06:13:05.043989Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=()"} {"level":"info","ts":"2026-04-08T06:13:05.044082Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 0"} {"level":"info","ts":"2026-04-08T06:13:05.044158Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e98569549cc430c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-08T06:13:05.04421Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 1"} {"level":"info","ts":"2026-04-08T06:13:05.044318Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"warn","ts":"2026-04-08T06:13:05.271769Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-08T06:13:05.341614Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-08T06:13:05.395113Z","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-08T06:13:05.450761Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e98569549cc430c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-08T06:13:05.451221Z","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-08T06:13:05.451413Z","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-08T06:13:05.451503Z","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-08T06:13:05.452643Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e98569549cc430c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-08T06:13:05.457151Z","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-08T06:13:05.457307Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-04-08T06:13:05.457367Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-04-08T06:13:05.45744Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e98569549cc430c","initial-advertise-peer-urls":["https://199.204.45.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-08T06:13:05.457493Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-08T06:13:05.465403Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"info","ts":"2026-04-08T06:13:05.46564Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","added-peer-id":"4e98569549cc430c","added-peer-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-04-08T06:13:05.549331Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c is starting a new election at term 1"} {"level":"info","ts":"2026-04-08T06:13:05.549372Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became pre-candidate at term 1"} {"level":"info","ts":"2026-04-08T06:13:05.549492Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgPreVoteResp from 4e98569549cc430c at term 1"} {"level":"info","ts":"2026-04-08T06:13:05.549646Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became candidate at term 2"} {"level":"info","ts":"2026-04-08T06:13:05.549672Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgVoteResp from 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-04-08T06:13:05.549698Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became leader at term 2"} {"level":"info","ts":"2026-04-08T06:13:05.54974Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e98569549cc430c elected leader 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-04-08T06:13:05.666716Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T06:13:05.666994Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e98569549cc430c","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.216:2379]}","request-path":"/0/members/4e98569549cc430c/attributes","cluster-id":"65d70b4d1d4a8bf9","publish-timeout":"7s"} {"level":"info","ts":"2026-04-08T06:13:05.667001Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T06:13:05.667037Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T06:13:05.667271Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-08T06:13:05.667326Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-08T06:13:05.672352Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.216:2379"} {"level":"info","ts":"2026-04-08T06:13:05.673751Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-08T06:13:05.791042Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T06:13:05.791221Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T06:13:05.791431Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-04-08T06:13:07.368263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.993902ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-08T06:13:07.368549Z","caller":"traceutil/trace.go:171","msg":"trace[1812254755] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"411.657228ms","start":"2026-04-08T06:13:06.956849Z","end":"2026-04-08T06:13:07.368507Z","steps":["trace[1812254755] 'process raft request' (duration: 202.878642ms)","trace[1812254755] 'compare' (duration: 206.72547ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:13:07.368697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:13:06.956834Z","time spent":"411.81839ms","remote":"127.0.0.1:41578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T06:13:07.368769Z","caller":"traceutil/trace.go:171","msg":"trace[180590706] linearizableReadLoop","detail":"{readStateIndex:15; appliedIndex:7; }","duration":"407.086534ms","start":"2026-04-08T06:13:06.961667Z","end":"2026-04-08T06:13:07.368753Z","steps":["trace[180590706] 'read index received' (duration: 71.764795ms)","trace[180590706] 'applied index is now lower than readState.Index' (duration: 335.320859ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:13:07.368935Z","caller":"traceutil/trace.go:171","msg":"trace[316410062] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"411.91718ms","start":"2026-04-08T06:13:06.957006Z","end":"2026-04-08T06:13:07.368923Z","steps":["trace[316410062] 'process raft request' (duration: 411.395226ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:13:07.369002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:13:06.956999Z","time spent":"411.97316ms","remote":"127.0.0.1:41578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T06:13:07.369042Z","caller":"traceutil/trace.go:171","msg":"trace[1369315063] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"410.196027ms","start":"2026-04-08T06:13:06.958795Z","end":"2026-04-08T06:13:07.368991Z","steps":["trace[1369315063] 'process raft request' (duration: 409.828984ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:13:07.369065Z","caller":"traceutil/trace.go:171","msg":"trace[1284212129] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"404.845687ms","start":"2026-04-08T06:13:06.964188Z","end":"2026-04-08T06:13:07.369033Z","steps":["trace[1284212129] 'process raft request' (duration: 404.526415ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:13:07.369198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.498227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-08T06:13:07.369213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:13:06.958788Z","time spent":"410.339978ms","remote":"127.0.0.1:41476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T06:13:07.36923Z","caller":"traceutil/trace.go:171","msg":"trace[1667675095] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:13; }","duration":"407.577198ms","start":"2026-04-08T06:13:06.961642Z","end":"2026-04-08T06:13:07.369219Z","steps":["trace[1667675095] 'agreement among raft nodes before linearized reading' (duration: 407.442637ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:13:07.36923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:13:06.964179Z","time spent":"404.958418ms","remote":"127.0.0.1:41308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T06:13:07.369262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:13:06.961631Z","time spent":"407.622418ms","remote":"127.0.0.1:41426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":26,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-04-08T06:13:07.369353Z","caller":"traceutil/trace.go:171","msg":"trace[65489730] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"412.137741ms","start":"2026-04-08T06:13:06.957203Z","end":"2026-04-08T06:13:07.36934Z","steps":["trace[65489730] 'process raft request' (duration: 411.360276ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:13:07.369417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:13:06.957182Z","time spent":"412.206822ms","remote":"127.0.0.1:41578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T06:13:07.369475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.995335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-04-08T06:13:07.369491Z","caller":"traceutil/trace.go:171","msg":"trace[269632798] transaction","detail":"{read_only:false; number_of_response:0; response_revision:10; }","duration":"412.118281ms","start":"2026-04-08T06:13:06.957361Z","end":"2026-04-08T06:13:07.369479Z","steps":["trace[269632798] 'process raft request' (duration: 411.235465ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:13:07.369529Z","caller":"traceutil/trace.go:171","msg":"trace[1433588194] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:13; }","duration":"365.052367ms","start":"2026-04-08T06:13:07.004451Z","end":"2026-04-08T06:13:07.369504Z","steps":["trace[1433588194] 'agreement among raft nodes before linearized reading' (duration: 364.984696ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:13:07.36955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:13:06.957319Z","time spent":"412.209202ms","remote":"127.0.0.1:41178","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":26,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T06:13:07.369563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:13:07.004441Z","time spent":"365.114467ms","remote":"127.0.0.1:41178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":372,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-04-08T06:13:07.369562Z","caller":"traceutil/trace.go:171","msg":"trace[124340692] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"412.401663ms","start":"2026-04-08T06:13:06.957151Z","end":"2026-04-08T06:13:07.369553Z","steps":["trace[124340692] 'process raft request' (duration: 411.352016ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:13:07.369592Z","caller":"traceutil/trace.go:171","msg":"trace[2094770352] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"405.833154ms","start":"2026-04-08T06:13:06.963745Z","end":"2026-04-08T06:13:07.369579Z","steps":["trace[2094770352] 'process raft request' (duration: 404.925528ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:13:07.369626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:13:06.957143Z","time spent":"412.453813ms","remote":"127.0.0.1:41578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T06:13:07.369648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:13:06.963737Z","time spent":"405.885104ms","remote":"127.0.0.1:41240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3159,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T06:13:07.369749Z","caller":"traceutil/trace.go:171","msg":"trace[1095042912] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"412.595754ms","start":"2026-04-08T06:13:06.957144Z","end":"2026-04-08T06:13:07.369739Z","steps":["trace[1095042912] 'process raft request' (duration: 411.390056ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:13:07.369791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:13:06.957121Z","time spent":"412.650675ms","remote":"127.0.0.1:41578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T06:13:07.369818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.874264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-08T06:13:07.369855Z","caller":"traceutil/trace.go:171","msg":"trace[415379185] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:13; }","duration":"112.974535ms","start":"2026-04-08T06:13:07.256871Z","end":"2026-04-08T06:13:07.369845Z","steps":["trace[415379185] 'agreement among raft nodes before linearized reading' (duration: 112.915075ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:13:07.369991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.555202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-08T06:13:07.37001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.638173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-08T06:13:07.37005Z","caller":"traceutil/trace.go:171","msg":"trace[1716645733] range","detail":"{range_begin:/registry/resourcequotas/kube-public/; range_end:/registry/resourcequotas/kube-public0; response_count:0; response_revision:13; }","duration":"316.646052ms","start":"2026-04-08T06:13:07.053384Z","end":"2026-04-08T06:13:07.37003Z","steps":["trace[1716645733] 'agreement among raft nodes before linearized reading' (duration: 316.577352ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:13:07.370067Z","caller":"traceutil/trace.go:171","msg":"trace[769144032] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:13; }","duration":"216.763903ms","start":"2026-04-08T06:13:07.153291Z","end":"2026-04-08T06:13:07.370055Z","steps":["trace[769144032] 'agreement among raft nodes before linearized reading' (duration: 216.667553ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:13:07.370096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:13:07.053361Z","time spent":"316.726813ms","remote":"127.0.0.1:41152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":26,"request content":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" "} {"level":"warn","ts":"2026-04-08T06:13:07.719203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.515543ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-08T06:13:07.719439Z","caller":"traceutil/trace.go:171","msg":"trace[575269897] transaction","detail":"{read_only:false; response_revision:24; number_of_response:1; }","duration":"275.519192ms","start":"2026-04-08T06:13:07.443888Z","end":"2026-04-08T06:13:07.719407Z","steps":["trace[575269897] 'process raft request' (duration: 129.683117ms)","trace[575269897] 'compare' (duration: 145.291271ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:13:07.721155Z","caller":"traceutil/trace.go:171","msg":"trace[1897835301] transaction","detail":"{read_only:false; response_revision:29; number_of_response:1; }","duration":"275.830835ms","start":"2026-04-08T06:13:07.445262Z","end":"2026-04-08T06:13:07.721092Z","steps":["trace[1897835301] 'process raft request' (duration: 275.743164ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:13:07.7214Z","caller":"traceutil/trace.go:171","msg":"trace[1326548647] transaction","detail":"{read_only:false; response_revision:25; number_of_response:1; }","duration":"274.764286ms","start":"2026-04-08T06:13:07.445111Z","end":"2026-04-08T06:13:07.719875Z","steps":["trace[1326548647] 'process raft request' (duration: 274.283933ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:13:07.721993Z","caller":"traceutil/trace.go:171","msg":"trace[44812362] transaction","detail":"{read_only:false; response_revision:26; number_of_response:1; }","duration":"276.663901ms","start":"2026-04-08T06:13:07.445176Z","end":"2026-04-08T06:13:07.721839Z","steps":["trace[44812362] 'process raft request' (duration: 274.583256ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:13:07.722097Z","caller":"traceutil/trace.go:171","msg":"trace[1702556123] transaction","detail":"{read_only:false; response_revision:28; number_of_response:1; }","duration":"276.852792ms","start":"2026-04-08T06:13:07.445224Z","end":"2026-04-08T06:13:07.722077Z","steps":["trace[1702556123] 'process raft request' (duration: 275.644993ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:13:07.722331Z","caller":"traceutil/trace.go:171","msg":"trace[85986410] transaction","detail":"{read_only:false; response_revision:27; number_of_response:1; }","duration":"276.802091ms","start":"2026-04-08T06:13:07.445222Z","end":"2026-04-08T06:13:07.722024Z","steps":["trace[85986410] 'process raft request' (duration: 275.561573ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:13:07.724051Z","caller":"traceutil/trace.go:171","msg":"trace[1979891576] linearizableReadLoop","detail":"{readStateIndex:35; appliedIndex:27; }","duration":"277.874269ms","start":"2026-04-08T06:13:07.446135Z","end":"2026-04-08T06:13:07.72401Z","steps":["trace[1979891576] 'read index received' (duration: 127.527791ms)","trace[1979891576] 'applied index is now lower than readState.Index' (duration: 150.345308ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:13:07.724567Z","caller":"traceutil/trace.go:171","msg":"trace[1383863329] transaction","detail":"{read_only:false; response_revision:30; number_of_response:1; }","duration":"279.24071ms","start":"2026-04-08T06:13:07.445282Z","end":"2026-04-08T06:13:07.724523Z","steps":["trace[1383863329] 'process raft request' (duration: 275.759034ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:13:07.724902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.183456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-08T06:13:07.724928Z","caller":"traceutil/trace.go:171","msg":"trace[1432873280] transaction","detail":"{read_only:false; response_revision:31; number_of_response:1; }","duration":"279.27242ms","start":"2026-04-08T06:13:07.445451Z","end":"2026-04-08T06:13:07.724724Z","steps":["trace[1432873280] 'process raft request' (duration: 278.478404ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:13:07.724988Z","caller":"traceutil/trace.go:171","msg":"trace[496355139] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:31; }","duration":"228.297627ms","start":"2026-04-08T06:13:07.49666Z","end":"2026-04-08T06:13:07.724957Z","steps":["trace[496355139] 'agreement among raft nodes before linearized reading' (duration: 227.546531ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:13:07.725005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.780815ms","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-08T06:13:07.725151Z","caller":"traceutil/trace.go:171","msg":"trace[786210758] range","detail":"{range_begin:/registry/resourcequotas/kube-node-lease/; range_end:/registry/resourcequotas/kube-node-lease0; response_count:0; response_revision:31; }","duration":"279.017197ms","start":"2026-04-08T06:13:07.446108Z","end":"2026-04-08T06:13:07.725126Z","steps":["trace[786210758] 'agreement among raft nodes before linearized reading' (duration: 278.05041ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:13:08.051141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.400806ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-08T06:13:08.051258Z","caller":"traceutil/trace.go:171","msg":"trace[1621375960] linearizableReadLoop","detail":"{readStateIndex:44; appliedIndex:43; }","duration":"245.022979ms","start":"2026-04-08T06:13:07.806217Z","end":"2026-04-08T06:13:08.05124Z","steps":["trace[1621375960] 'read index received' (duration: 60.406952ms)","trace[1621375960] 'applied index is now lower than readState.Index' (duration: 184.614917ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:13:08.051496Z","caller":"traceutil/trace.go:171","msg":"trace[1793588010] transaction","detail":"{read_only:false; response_revision:40; number_of_response:1; }","duration":"245.557062ms","start":"2026-04-08T06:13:07.805712Z","end":"2026-04-08T06:13:08.051269Z","steps":["trace[1793588010] 'process raft request' (duration: 60.941885ms)","trace[1793588010] 'compare' (duration: 184.234475ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:13:08.051822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.570813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/default/\" range_end:\"/registry/resourcequotas/default0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-08T06:13:08.05191Z","caller":"traceutil/trace.go:171","msg":"trace[566307650] range","detail":"{range_begin:/registry/resourcequotas/default/; range_end:/registry/resourcequotas/default0; response_count:0; response_revision:40; }","duration":"245.701143ms","start":"2026-04-08T06:13:07.806183Z","end":"2026-04-08T06:13:08.051884Z","steps":["trace[566307650] 'agreement among raft nodes before linearized reading' (duration: 245.314651ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:13:08.05572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.549934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-08T06:13:08.055767Z","caller":"traceutil/trace.go:171","msg":"trace[330316393] transaction","detail":"{read_only:false; response_revision:41; number_of_response:1; }","duration":"249.45766ms","start":"2026-04-08T06:13:07.806297Z","end":"2026-04-08T06:13:08.055755Z","steps":["trace[330316393] 'process raft request' (duration: 249.178418ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:13:08.055767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.566424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-08T06:13:08.055897Z","caller":"traceutil/trace.go:171","msg":"trace[342647733] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:41; }","duration":"207.696385ms","start":"2026-04-08T06:13:07.848187Z","end":"2026-04-08T06:13:08.055883Z","steps":["trace[342647733] 'agreement among raft nodes before linearized reading' (duration: 207.535634ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:13:08.055783Z","caller":"traceutil/trace.go:171","msg":"trace[1008013068] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:41; }","duration":"207.648775ms","start":"2026-04-08T06:13:07.848121Z","end":"2026-04-08T06:13:08.055769Z","steps":["trace[1008013068] 'agreement among raft nodes before linearized reading' (duration: 207.505094ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:13:08.055726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.552725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-08T06:13:08.056075Z","caller":"traceutil/trace.go:171","msg":"trace[590695814] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:41; }","duration":"103.949657ms","start":"2026-04-08T06:13:07.952106Z","end":"2026-04-08T06:13:08.056056Z","steps":["trace[590695814] 'agreement among raft nodes before linearized reading' (duration: 103.533274ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:13:08.055778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.520526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-08T06:13:08.05628Z","caller":"traceutil/trace.go:171","msg":"trace[1519495429] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:41; }","duration":"200.067911ms","start":"2026-04-08T06:13:07.856189Z","end":"2026-04-08T06:13:08.056257Z","steps":["trace[1519495429] 'agreement among raft nodes before linearized reading' (duration: 199.497256ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:13:08.410083Z","caller":"traceutil/trace.go:171","msg":"trace[494582373] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"146.143803ms","start":"2026-04-08T06:13:08.263913Z","end":"2026-04-08T06:13:08.410057Z","steps":["trace[494582373] 'process raft request' (duration: 146.079873ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:13:08.41027Z","caller":"traceutil/trace.go:171","msg":"trace[1649022439] transaction","detail":"{read_only:false; response_revision:71; number_of_response:1; }","duration":"147.638015ms","start":"2026-04-08T06:13:08.262603Z","end":"2026-04-08T06:13:08.410241Z","steps":["trace[1649022439] 'process raft request' (duration: 112.387477ms)","trace[1649022439] 'compare' (duration: 34.808755ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:13:08.505527Z","caller":"traceutil/trace.go:171","msg":"trace[1777043448] linearizableReadLoop","detail":"{readStateIndex:78; appliedIndex:75; }","duration":"229.323868ms","start":"2026-04-08T06:13:08.276185Z","end":"2026-04-08T06:13:08.505509Z","steps":["trace[1777043448] 'read index received' (duration: 98.816529ms)","trace[1777043448] 'applied index is now lower than readState.Index' (duration: 130.506339ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:13:08.50563Z","caller":"traceutil/trace.go:171","msg":"trace[1263964966] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"229.683151ms","start":"2026-04-08T06:13:08.275924Z","end":"2026-04-08T06:13:08.505607Z","steps":["trace[1263964966] 'process raft request' (duration: 229.49362ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:13:08.505734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.53982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:basic-user\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-08T06:13:08.505775Z","caller":"traceutil/trace.go:171","msg":"trace[335723857] range","detail":"{range_begin:/registry/clusterroles/system:basic-user; range_end:; response_count:0; response_revision:73; }","duration":"229.62295ms","start":"2026-04-08T06:13:08.276142Z","end":"2026-04-08T06:13:08.505765Z","steps":["trace[335723857] 'agreement among raft nodes before linearized reading' (duration: 229.464349ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:13:09.242781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.041899ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-04-08T06:13:09.242858Z","caller":"traceutil/trace.go:171","msg":"trace[355983780] linearizableReadLoop","detail":"{readStateIndex:79; appliedIndex:78; }","duration":"737.257756ms","start":"2026-04-08T06:13:08.505587Z","end":"2026-04-08T06:13:09.242844Z","steps":["trace[355983780] 'read index received' (duration: 144.973565ms)","trace[355983780] 'applied index is now lower than readState.Index' (duration: 592.283151ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:13:09.242953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"829.618357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18a44cf787c902b8\" ","response":"range_response_count:1 size:654"} {"level":"info","ts":"2026-04-08T06:13:09.242935Z","caller":"traceutil/trace.go:171","msg":"trace[95347224] transaction","detail":"{read_only:false; response_revision:74; number_of_response:1; }","duration":"827.467742ms","start":"2026-04-08T06:13:08.41541Z","end":"2026-04-08T06:13:09.242877Z","steps":["trace[95347224] 'process raft request' (duration: 235.135521ms)","trace[95347224] 'compare' (duration: 591.927128ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:13:09.243133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:13:08.415393Z","time spent":"827.628873ms","remote":"127.0.0.1:41468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1088,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T06:13:09.242975Z","caller":"traceutil/trace.go:171","msg":"trace[2036017711] range","detail":"{range_begin:/registry/events/default/instance.18a44cf787c902b8; range_end:; response_count:1; response_revision:74; }","duration":"829.675347ms","start":"2026-04-08T06:13:08.413291Z","end":"2026-04-08T06:13:09.242967Z","steps":["trace[2036017711] 'agreement among raft nodes before linearized reading' (duration: 829.602387ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:13:09.243457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:13:08.413234Z","time spent":"830.208582ms","remote":"127.0.0.1:41136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":676,"request content":"key:\"/registry/events/default/instance.18a44cf787c902b8\" "} {"level":"warn","ts":"2026-04-08T06:13:09.262517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.830786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-08T06:13:09.262535Z","caller":"traceutil/trace.go:171","msg":"trace[151551662] transaction","detail":"{read_only:false; response_revision:75; number_of_response:1; }","duration":"753.685705ms","start":"2026-04-08T06:13:08.508827Z","end":"2026-04-08T06:13:09.262513Z","steps":["trace[151551662] 'process raft request' (duration: 753.441703ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:13:09.262559Z","caller":"traceutil/trace.go:171","msg":"trace[1998730919] transaction","detail":"{read_only:false; response_revision:76; number_of_response:1; }","duration":"753.703965ms","start":"2026-04-08T06:13:08.508827Z","end":"2026-04-08T06:13:09.262531Z","steps":["trace[1998730919] 'process raft request' (duration: 753.557874ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:13:09.262599Z","caller":"traceutil/trace.go:171","msg":"trace[1547476748] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:76; }","duration":"309.949278ms","start":"2026-04-08T06:13:08.952631Z","end":"2026-04-08T06:13:09.26258Z","steps":["trace[1547476748] 'agreement among raft nodes before linearized reading' (duration: 309.796987ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:13:09.262645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:13:08.508812Z","time spent":"753.777966ms","remote":"127.0.0.1:41476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":565,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T06:13:09.262647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:13:08.952606Z","time spent":"310.030498ms","remote":"127.0.0.1:44052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-08T06:13:09.262684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:13:08.508802Z","time spent":"753.821646ms","remote":"127.0.0.1:41386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":665,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T06:13:10.050077Z","caller":"traceutil/trace.go:171","msg":"trace[223720442] transaction","detail":"{read_only:false; response_revision:165; number_of_response:1; }","duration":"105.589413ms","start":"2026-04-08T06:13:09.944454Z","end":"2026-04-08T06:13:10.050043Z","steps":["trace[223720442] 'process raft request' (duration: 46.079124ms)","trace[223720442] 'compare' (duration: 59.333208ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:13:10.308659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.603118ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-08T06:13:10.30877Z","caller":"traceutil/trace.go:171","msg":"trace[1479664348] transaction","detail":"{read_only:false; response_revision:168; number_of_response:1; }","duration":"197.321904ms","start":"2026-04-08T06:13:10.11143Z","end":"2026-04-08T06:13:10.308752Z","steps":["trace[1479664348] 'process raft request' (duration: 39.549675ms)","trace[1479664348] 'compare' (duration: 157.429556ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:13:10.990069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.62584ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-08T06:13:10.990194Z","caller":"traceutil/trace.go:171","msg":"trace[725031595] transaction","detail":"{read_only:false; response_revision:193; number_of_response:1; }","duration":"246.783204ms","start":"2026-04-08T06:13:10.743389Z","end":"2026-04-08T06:13:10.990173Z","steps":["trace[725031595] 'process raft request' (duration: 86.972922ms)","trace[725031595] 'compare' (duration: 159.416929ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:13:11.394817Z","caller":"traceutil/trace.go:171","msg":"trace[233542149] transaction","detail":"{read_only:false; response_revision:198; number_of_response:1; }","duration":"205.691924ms","start":"2026-04-08T06:13:11.189095Z","end":"2026-04-08T06:13:11.394787Z","steps":["trace[233542149] 'process raft request' (duration: 137.658806ms)","trace[233542149] 'compare' (duration: 67.762002ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:13:11.745483Z","caller":"traceutil/trace.go:171","msg":"trace[742913105] transaction","detail":"{read_only:false; response_revision:209; number_of_response:1; }","duration":"113.551052ms","start":"2026-04-08T06:13:11.631903Z","end":"2026-04-08T06:13:11.745454Z","steps":["trace[742913105] 'process raft request' (duration: 101.238256ms)","trace[742913105] 'compare' (duration: 12.212124ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:13:11.750354Z","caller":"traceutil/trace.go:171","msg":"trace[380766893] transaction","detail":"{read_only:false; response_revision:211; number_of_response:1; }","duration":"118.256084ms","start":"2026-04-08T06:13:11.632074Z","end":"2026-04-08T06:13:11.75033Z","steps":["trace[380766893] 'process raft request' (duration: 118.219453ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:13:11.750404Z","caller":"traceutil/trace.go:171","msg":"trace[799151149] transaction","detail":"{read_only:false; response_revision:210; number_of_response:1; }","duration":"118.420296ms","start":"2026-04-08T06:13:11.631929Z","end":"2026-04-08T06:13:11.750349Z","steps":["trace[799151149] 'process raft request' (duration: 118.272133ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:13:12.22355Z","caller":"traceutil/trace.go:171","msg":"trace[1510812939] transaction","detail":"{read_only:false; response_revision:229; number_of_response:1; }","duration":"100.110317ms","start":"2026-04-08T06:13:12.123384Z","end":"2026-04-08T06:13:12.223494Z","steps":["trace[1510812939] 'process raft request' (duration: 55.048689ms)","trace[1510812939] 'compare' (duration: 44.772442ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:13:14.264615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.431155ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T06:13:14.264807Z","caller":"traceutil/trace.go:171","msg":"trace[1988992164] linearizableReadLoop","detail":"{readStateIndex:273; appliedIndex:271; }","duration":"485.916984ms","start":"2026-04-08T06:13:13.778874Z","end":"2026-04-08T06:13:14.264791Z","steps":["trace[1988992164] 'read index received' (duration: 63.310685ms)","trace[1988992164] 'applied index is now lower than readState.Index' (duration: 422.605229ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:13:14.264861Z","caller":"traceutil/trace.go:171","msg":"trace[719960410] transaction","detail":"{read_only:false; response_revision:267; number_of_response:1; }","duration":"495.697208ms","start":"2026-04-08T06:13:13.769143Z","end":"2026-04-08T06:13:14.26484Z","steps":["trace[719960410] 'process raft request' (duration: 495.598496ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:13:14.26492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.038746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-08T06:13:14.264958Z","caller":"traceutil/trace.go:171","msg":"trace[1008947157] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:267; }","duration":"486.087547ms","start":"2026-04-08T06:13:13.778859Z","end":"2026-04-08T06:13:14.264947Z","steps":["trace[1008947157] 'agreement among raft nodes before linearized reading' (duration: 486.022686ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:13:14.264967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:13:13.769129Z","time spent":"495.780629ms","remote":"127.0.0.1:41312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T06:13:14.264998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:13:13.778853Z","time spent":"486.137738ms","remote":"127.0.0.1:41178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-04-08T06:13:14.26508Z","caller":"traceutil/trace.go:171","msg":"trace[1330776119] transaction","detail":"{read_only:false; response_revision:266; number_of_response:1; }","duration":"496.387331ms","start":"2026-04-08T06:13:13.768659Z","end":"2026-04-08T06:13:14.265046Z","steps":["trace[1330776119] 'process raft request' (duration: 73.434476ms)","trace[1330776119] 'compare' (duration: 422.302103ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:13:14.265223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:13:13.76864Z","time spent":"496.512533ms","remote":"127.0.0.1:41312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T06:13:14.265243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.941663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3709"} {"level":"info","ts":"2026-04-08T06:13:14.265269Z","caller":"traceutil/trace.go:171","msg":"trace[524863811] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:267; }","duration":"273.996965ms","start":"2026-04-08T06:13:13.991265Z","end":"2026-04-08T06:13:14.265262Z","steps":["trace[524863811] 'agreement among raft nodes before linearized reading' (duration: 273.948524ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:13:24.847513Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831409586253962123,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-08T06:13:25.347702Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831409586253962123,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-08T06:13:25.371646Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.105759383s","expected-duration":"1s"} {"level":"info","ts":"2026-04-08T06:13:25.372141Z","caller":"traceutil/trace.go:171","msg":"trace[1037066265] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"1.106396784s","start":"2026-04-08T06:13:24.265713Z","end":"2026-04-08T06:13:25.37211Z","steps":["trace[1037066265] 'process raft request' (duration: 1.106207211s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:13:25.372338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:13:24.26569Z","time spent":"1.106538376s","remote":"127.0.0.1:41244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6950,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T06:13:25.456108Z","caller":"traceutil/trace.go:171","msg":"trace[88178185] linearizableReadLoop","detail":"{readStateIndex:419; appliedIndex:417; }","duration":"1.109008827s","start":"2026-04-08T06:13:24.347075Z","end":"2026-04-08T06:13:25.456083Z","steps":["trace[88178185] 'read index received' (duration: 1.024809658s)","trace[88178185] 'applied index is now lower than readState.Index' (duration: 84.198189ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:13:25.456201Z","caller":"traceutil/trace.go:171","msg":"trace[1662520140] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"673.7116ms","start":"2026-04-08T06:13:24.782466Z","end":"2026-04-08T06:13:25.456178Z","steps":["trace[1662520140] 'process raft request' (duration: 673.496737ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:13:25.456292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.109180969s","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-08T06:13:25.45633Z","caller":"traceutil/trace.go:171","msg":"trace[1661519808] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:408; }","duration":"1.109293572s","start":"2026-04-08T06:13:24.347025Z","end":"2026-04-08T06:13:25.456318Z","steps":["trace[1661519808] 'agreement among raft nodes before linearized reading' (duration: 1.10918848s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:13:25.456372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:13:24.347004Z","time spent":"1.109358492s","remote":"127.0.0.1:41312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-08T06:13:25.456376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"799.013472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4375"} {"level":"info","ts":"2026-04-08T06:13:25.456412Z","caller":"traceutil/trace.go:171","msg":"trace[672373261] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:408; }","duration":"799.083794ms","start":"2026-04-08T06:13:24.657317Z","end":"2026-04-08T06:13:25.456401Z","steps":["trace[672373261] 'agreement among raft nodes before linearized reading' (duration: 799.013473ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:13:25.456389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.801143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T06:13:25.456442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:13:24.657296Z","time spent":"799.138564ms","remote":"127.0.0.1:41240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4397,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-04-08T06:13:25.456476Z","caller":"traceutil/trace.go:171","msg":"trace[1967366176] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:408; }","duration":"178.924985ms","start":"2026-04-08T06:13:25.277529Z","end":"2026-04-08T06:13:25.456453Z","steps":["trace[1967366176] 'agreement among raft nodes before linearized reading' (duration: 178.785832ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:13:25.456624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.818495ms","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-08T06:13:25.456726Z","caller":"traceutil/trace.go:171","msg":"trace[380356823] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:408; }","duration":"161.980378ms","start":"2026-04-08T06:13:25.294718Z","end":"2026-04-08T06:13:25.456699Z","steps":["trace[380356823] 'agreement among raft nodes before linearized reading' (duration: 161.701493ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:13:25.456757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.049393637s","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-08T06:13:25.456798Z","caller":"traceutil/trace.go:171","msg":"trace[932137094] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:408; }","duration":"1.049458238s","start":"2026-04-08T06:13:24.40733Z","end":"2026-04-08T06:13:25.456789Z","steps":["trace[932137094] 'agreement among raft nodes before linearized reading' (duration: 1.049387817s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:13:25.456833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:13:24.407318Z","time spent":"1.049508129s","remote":"127.0.0.1:41312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-08T06:13:25.45688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:13:24.782416Z","time spent":"673.839132ms","remote":"127.0.0.1:41312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T06:13:25.456952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.069954728s","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-08T06:13:25.457005Z","caller":"traceutil/trace.go:171","msg":"trace[737084994] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:408; }","duration":"1.07003999s","start":"2026-04-08T06:13:24.386952Z","end":"2026-04-08T06:13:25.456992Z","steps":["trace[737084994] 'agreement among raft nodes before linearized reading' (duration: 1.069312438s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:13:25.457047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:13:24.386908Z","time spent":"1.07013034s","remote":"127.0.0.1:41312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-08T06:13:27.777399Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831409586253962135,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-08T06:13:27.882326Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.54024713s","expected-duration":"1s"} {"level":"info","ts":"2026-04-08T06:13:27.883135Z","caller":"traceutil/trace.go:171","msg":"trace[718358508] linearizableReadLoop","detail":"{readStateIndex:425; appliedIndex:424; }","duration":"606.387152ms","start":"2026-04-08T06:13:27.276725Z","end":"2026-04-08T06:13:27.883112Z","steps":["trace[718358508] 'read index received' (duration: 605.870574ms)","trace[718358508] 'applied index is now lower than readState.Index' (duration: 515.618µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:13:27.883191Z","caller":"traceutil/trace.go:171","msg":"trace[421553526] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"1.541187456s","start":"2026-04-08T06:13:26.341978Z","end":"2026-04-08T06:13:27.883165Z","steps":["trace[421553526] 'process raft request' (duration: 1.540600887s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:13:27.883276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.540045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T06:13:27.883317Z","caller":"traceutil/trace.go:171","msg":"trace[1808170659] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:414; }","duration":"606.634567ms","start":"2026-04-08T06:13:27.276672Z","end":"2026-04-08T06:13:27.883307Z","steps":["trace[1808170659] 'agreement among raft nodes before linearized reading' (duration: 606.552395ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:13:27.883416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:13:27.276653Z","time spent":"606.751858ms","remote":"127.0.0.1:44052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-08T06:13:27.883655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:13:26.341959Z","time spent":"1.541591453s","remote":"127.0.0.1:41490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T06:13:27.95486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.490717ms","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-08T06:13:27.954941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.62705ms","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-08T06:13:27.955001Z","caller":"traceutil/trace.go:171","msg":"trace[2059270473] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:414; }","duration":"467.6745ms","start":"2026-04-08T06:13:27.487305Z","end":"2026-04-08T06:13:27.954979Z","steps":["trace[2059270473] 'agreement among raft nodes before linearized reading' (duration: 467.376745ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:13:27.955046Z","caller":"traceutil/trace.go:171","msg":"trace[613389158] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:414; }","duration":"467.811262ms","start":"2026-04-08T06:13:27.487209Z","end":"2026-04-08T06:13:27.955021Z","steps":["trace[613389158] 'agreement among raft nodes before linearized reading' (duration: 467.537038ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:13:27.955066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:13:27.487293Z","time spent":"467.761061ms","remote":"127.0.0.1:41312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-08T06:13:27.95511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:13:27.487197Z","time spent":"467.901864ms","remote":"127.0.0.1:41312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-08T06:13:27.955161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.135268ms","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-08T06:13:27.955241Z","caller":"traceutil/trace.go:171","msg":"trace[1374297673] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:414; }","duration":"471.27624ms","start":"2026-04-08T06:13:27.48395Z","end":"2026-04-08T06:13:27.955226Z","steps":["trace[1374297673] 'agreement among raft nodes before linearized reading' (duration: 471.117807ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:13:27.9553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:13:27.483933Z","time spent":"471.358021ms","remote":"127.0.0.1:41312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-08T06:13:27.955547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.879229ms","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-08T06:13:27.955665Z","caller":"traceutil/trace.go:171","msg":"trace[1886715003] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:414; }","duration":"469.029423ms","start":"2026-04-08T06:13:27.486617Z","end":"2026-04-08T06:13:27.955647Z","steps":["trace[1886715003] 'agreement among raft nodes before linearized reading' (duration: 468.431533ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:13:27.955792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:13:27.486608Z","time spent":"469.170435ms","remote":"127.0.0.1:41312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-08T06:13:28.146624Z","caller":"traceutil/trace.go:171","msg":"trace[569262259] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"178.758542ms","start":"2026-04-08T06:13:27.96783Z","end":"2026-04-08T06:13:28.146589Z","steps":["trace[569262259] 'process raft request' (duration: 178.683441ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:13:28.146708Z","caller":"traceutil/trace.go:171","msg":"trace[941788631] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"185.8909ms","start":"2026-04-08T06:13:27.96081Z","end":"2026-04-08T06:13:28.146701Z","steps":["trace[941788631] 'process raft request' (duration: 185.491933ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:13:28.146646Z","caller":"traceutil/trace.go:171","msg":"trace[1997263295] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"185.703877ms","start":"2026-04-08T06:13:27.960903Z","end":"2026-04-08T06:13:28.146607Z","steps":["trace[1997263295] 'process raft request' (duration: 185.435223ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:13:28.146874Z","caller":"traceutil/trace.go:171","msg":"trace[1986389965] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"184.775852ms","start":"2026-04-08T06:13:27.962063Z","end":"2026-04-08T06:13:28.146838Z","steps":["trace[1986389965] 'process raft request' (duration: 184.307864ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:13:28.14708Z","caller":"traceutil/trace.go:171","msg":"trace[1983712636] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"184.741251ms","start":"2026-04-08T06:13:27.96232Z","end":"2026-04-08T06:13:28.147062Z","steps":["trace[1983712636] 'process raft request' (duration: 184.121301ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:13:28.147426Z","caller":"traceutil/trace.go:171","msg":"trace[1601152044] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"187.602389ms","start":"2026-04-08T06:13:27.959801Z","end":"2026-04-08T06:13:28.147404Z","steps":["trace[1601152044] 'process raft request' (duration: 118.662869ms)","trace[1601152044] 'compare' (duration: 67.70102ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:13:40.649899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.235829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T06:13:40.64998Z","caller":"traceutil/trace.go:171","msg":"trace[761745651] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:509; }","duration":"152.3374ms","start":"2026-04-08T06:13:40.497627Z","end":"2026-04-08T06:13:40.649964Z","steps":["trace[761745651] 'range keys from in-memory index tree' (duration: 152.150668ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:13:41.094129Z","caller":"traceutil/trace.go:171","msg":"trace[332036875] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"119.433796ms","start":"2026-04-08T06:13:40.974641Z","end":"2026-04-08T06:13:41.094075Z","steps":["trace[332036875] 'process raft request' (duration: 119.214443ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:13:41.094188Z","caller":"traceutil/trace.go:171","msg":"trace[1652405959] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"111.171241ms","start":"2026-04-08T06:13:40.982999Z","end":"2026-04-08T06:13:41.094171Z","steps":["trace[1652405959] 'process raft request' (duration: 111.129621ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:13:41.094365Z","caller":"traceutil/trace.go:171","msg":"trace[1868627729] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"118.138776ms","start":"2026-04-08T06:13:40.976211Z","end":"2026-04-08T06:13:41.094349Z","steps":["trace[1868627729] 'process raft request' (duration: 117.74053ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:13:41.094436Z","caller":"traceutil/trace.go:171","msg":"trace[1182161031] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"114.553772ms","start":"2026-04-08T06:13:40.979875Z","end":"2026-04-08T06:13:41.094428Z","steps":["trace[1182161031] 'process raft request' (duration: 114.138396ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:13:41.094505Z","caller":"traceutil/trace.go:171","msg":"trace[2032586566] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"119.74887ms","start":"2026-04-08T06:13:40.974752Z","end":"2026-04-08T06:13:41.0945Z","steps":["trace[2032586566] 'process raft request' (duration: 119.157462ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:13:41.094571Z","caller":"traceutil/trace.go:171","msg":"trace[473059917] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"120.013714ms","start":"2026-04-08T06:13:40.974552Z","end":"2026-04-08T06:13:41.094566Z","steps":["trace[473059917] 'process raft request' (duration: 114.354589ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:13:41.095727Z","caller":"traceutil/trace.go:171","msg":"trace[992240463] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"101.684089ms","start":"2026-04-08T06:13:40.994029Z","end":"2026-04-08T06:13:41.095713Z","steps":["trace[992240463] 'process raft request' (duration: 101.183481ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:13:41.095817Z","caller":"traceutil/trace.go:171","msg":"trace[1426259788] transaction","detail":"{read_only:false; number_of_response:1; response_revision:527; }","duration":"101.700939ms","start":"2026-04-08T06:13:40.994108Z","end":"2026-04-08T06:13:41.095809Z","steps":["trace[1426259788] 'process raft request' (duration: 101.356754ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:13:41.095977Z","caller":"traceutil/trace.go:171","msg":"trace[472313177] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"101.011988ms","start":"2026-04-08T06:13:40.994956Z","end":"2026-04-08T06:13:41.095967Z","steps":["trace[472313177] 'process raft request' (duration: 100.558601ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:13:41.77502Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831409586253962360,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-08T06:13:42.275838Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831409586253962360,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-08T06:13:42.767524Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.494370661s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-08T06:13:42.88044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.602408ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T06:13:42.880566Z","caller":"traceutil/trace.go:171","msg":"trace[1527042738] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"1.607527067s","start":"2026-04-08T06:13:41.273021Z","end":"2026-04-08T06:13:42.880548Z","steps":["trace[1527042738] 'process raft request' (duration: 1.494740116s)","trace[1527042738] 'compare' (duration: 112.364395ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:13:42.880632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:13:41.272989Z","time spent":"1.607615489s","remote":"127.0.0.1:41104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29546,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T06:13:42.884161Z","caller":"traceutil/trace.go:171","msg":"trace[1937569358] linearizableReadLoop","detail":"{readStateIndex:569; appliedIndex:565; }","duration":"1.609825411s","start":"2026-04-08T06:13:41.274319Z","end":"2026-04-08T06:13:42.884145Z","steps":["trace[1937569358] 'read index received' (duration: 1.493456417s)","trace[1937569358] 'applied index is now lower than readState.Index' (duration: 116.367944ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:13:42.884255Z","caller":"traceutil/trace.go:171","msg":"trace[1198817799] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"1.597830042s","start":"2026-04-08T06:13:41.286416Z","end":"2026-04-08T06:13:42.884246Z","steps":["trace[1198817799] 'process raft request' (duration: 1.597361955s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:13:42.884327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:13:41.286399Z","time spent":"1.597882282s","remote":"127.0.0.1:41104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29461,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T06:13:42.884469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.610134785s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T06:13:42.884533Z","caller":"traceutil/trace.go:171","msg":"trace[112616595] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:551; }","duration":"1.610225057s","start":"2026-04-08T06:13:41.274293Z","end":"2026-04-08T06:13:42.884518Z","steps":["trace[112616595] 'agreement among raft nodes before linearized reading' (duration: 1.610129856s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:13:42.884563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:13:41.274261Z","time spent":"1.610297348s","remote":"127.0.0.1:44044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-08T06:13:42.88456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"819.84922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-08T06:13:42.886935Z","caller":"traceutil/trace.go:171","msg":"trace[71393478] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:551; }","duration":"822.232086ms","start":"2026-04-08T06:13:42.064666Z","end":"2026-04-08T06:13:42.886898Z","steps":["trace[71393478] 'agreement among raft nodes before linearized reading' (duration: 819.85303ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:13:42.886995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:13:42.064644Z","time spent":"822.335978ms","remote":"127.0.0.1:41312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-08T06:13:42.884689Z","caller":"traceutil/trace.go:171","msg":"trace[216142705] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"1.574967061s","start":"2026-04-08T06:13:41.309714Z","end":"2026-04-08T06:13:42.884681Z","steps":["trace[216142705] 'process raft request' (duration: 1.574175049s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:13:42.887341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:13:41.309676Z","time spent":"1.577577429s","remote":"127.0.0.1:41104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T06:13:42.88471Z","caller":"traceutil/trace.go:171","msg":"trace[615104306] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"1.565719353s","start":"2026-04-08T06:13:41.318985Z","end":"2026-04-08T06:13:42.884705Z","steps":["trace[615104306] 'process raft request' (duration: 1.564961282s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:13:42.887499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:13:41.318907Z","time spent":"1.568544535s","remote":"127.0.0.1:41104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T06:13:42.88482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.14315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.216\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-08T06:13:42.887593Z","caller":"traceutil/trace.go:171","msg":"trace[197903669] range","detail":"{range_begin:/registry/masterleases/199.204.45.216; range_end:; response_count:1; response_revision:551; }","duration":"428.943542ms","start":"2026-04-08T06:13:42.458633Z","end":"2026-04-08T06:13:42.887577Z","steps":["trace[197903669] 'agreement among raft nodes before linearized reading' (duration: 426.16161ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:13:42.887635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:13:42.458611Z","time spent":"429.011792ms","remote":"127.0.0.1:41084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.216\" "} {"level":"warn","ts":"2026-04-08T06:13:42.884862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.876424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-08T06:13:42.887717Z","caller":"traceutil/trace.go:171","msg":"trace[32727792] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:551; }","duration":"673.760258ms","start":"2026-04-08T06:13:42.213945Z","end":"2026-04-08T06:13:42.887705Z","steps":["trace[32727792] 'agreement among raft nodes before linearized reading' (duration: 670.898285ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:13:42.88776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:13:42.213911Z","time spent":"673.838898ms","remote":"127.0.0.1:41312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-08T06:13:42.884887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.009932ms","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-08T06:13:42.887803Z","caller":"traceutil/trace.go:171","msg":"trace[1389416894] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:551; }","duration":"672.923435ms","start":"2026-04-08T06:13:42.214873Z","end":"2026-04-08T06:13:42.887796Z","steps":["trace[1389416894] 'agreement among raft nodes before linearized reading' (duration: 669.998632ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:13:42.887833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:13:42.214812Z","time spent":"673.014036ms","remote":"127.0.0.1:41312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-08T06:13:42.884929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"674.680911ms","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-08T06:13:42.88793Z","caller":"traceutil/trace.go:171","msg":"trace[1854288717] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:551; }","duration":"677.684926ms","start":"2026-04-08T06:13:42.210239Z","end":"2026-04-08T06:13:42.887924Z","steps":["trace[1854288717] 'agreement among raft nodes before linearized reading' (duration: 674.647551ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:13:42.887957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:13:42.210181Z","time spent":"677.769727ms","remote":"127.0.0.1:41312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-08T06:13:48.347295Z","caller":"traceutil/trace.go:171","msg":"trace[688379982] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"164.827217ms","start":"2026-04-08T06:13:48.182416Z","end":"2026-04-08T06:13:48.347244Z","steps":["trace[688379982] 'process raft request' (duration: 164.30134ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:13:48.883344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.529905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T06:13:48.883481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.595066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T06:13:48.884333Z","caller":"traceutil/trace.go:171","msg":"trace[461387074] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:616; }","duration":"120.490578ms","start":"2026-04-08T06:13:48.763808Z","end":"2026-04-08T06:13:48.884298Z","steps":["trace[461387074] 'range keys from in-memory index tree' (duration: 119.503094ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:13:48.884496Z","caller":"traceutil/trace.go:171","msg":"trace[1434817224] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:616; }","duration":"120.355047ms","start":"2026-04-08T06:13:48.763744Z","end":"2026-04-08T06:13:48.884099Z","steps":["trace[1434817224] 'count revisions from in-memory index tree' (duration: 119.463524ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:13:49.049871Z","caller":"traceutil/trace.go:171","msg":"trace[282802434] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"192.985182ms","start":"2026-04-08T06:13:48.856861Z","end":"2026-04-08T06:13:49.049846Z","steps":["trace[282802434] 'process raft request' (duration: 192.429164ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:13:50.112407Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.063146685s","expected-duration":"1s"} {"level":"info","ts":"2026-04-08T06:13:50.113174Z","caller":"traceutil/trace.go:171","msg":"trace[1311117825] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"1.219456237s","start":"2026-04-08T06:13:48.893681Z","end":"2026-04-08T06:13:50.113137Z","steps":["trace[1311117825] 'process raft request' (duration: 1.219136121s)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:13:50.113361Z","caller":"traceutil/trace.go:171","msg":"trace[1211573679] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:636; }","duration":"1.167544481s","start":"2026-04-08T06:13:48.945431Z","end":"2026-04-08T06:13:50.112975Z","steps":["trace[1211573679] 'read index received' (duration: 103.791047ms)","trace[1211573679] 'applied index is now lower than readState.Index' (duration: 1.063752534s)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:13:50.113401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:13:48.893662Z","time spent":"1.219589639s","remote":"127.0.0.1:41244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T06:13:50.113554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.168081578s","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-08T06:13:50.113674Z","caller":"traceutil/trace.go:171","msg":"trace[106426078] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:618; }","duration":"1.168274611s","start":"2026-04-08T06:13:48.945374Z","end":"2026-04-08T06:13:50.113648Z","steps":["trace[106426078] 'agreement among raft nodes before linearized reading' (duration: 1.167892236s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:13:50.113738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:13:48.945355Z","time spent":"1.168372882s","remote":"127.0.0.1:41312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-08T06:13:50.342867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.735037ms","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-08T06:13:50.343043Z","caller":"traceutil/trace.go:171","msg":"trace[1488663200] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:638; }","duration":"229.597659ms","start":"2026-04-08T06:13:50.113423Z","end":"2026-04-08T06:13:50.343021Z","steps":["trace[1488663200] 'read index received' (duration: 213.25739ms)","trace[1488663200] 'applied index is now lower than readState.Index' (duration: 16.339409ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:13:50.343189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.375749916s","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-08T06:13:50.343249Z","caller":"traceutil/trace.go:171","msg":"trace[1970926785] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:619; }","duration":"1.375828127s","start":"2026-04-08T06:13:48.96741Z","end":"2026-04-08T06:13:50.343238Z","steps":["trace[1970926785] 'agreement among raft nodes before linearized reading' (duration: 1.375652655s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:13:50.343281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:13:48.967402Z","time spent":"1.375871788s","remote":"127.0.0.1:41312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-08T06:13:50.343201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.376377496s","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-08T06:13:50.343082Z","caller":"traceutil/trace.go:171","msg":"trace[372877258] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:618; }","duration":"185.994451ms","start":"2026-04-08T06:13:50.157071Z","end":"2026-04-08T06:13:50.343065Z","steps":["trace[372877258] 'range keys from in-memory index tree' (duration: 185.703977ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:13:50.343366Z","caller":"traceutil/trace.go:171","msg":"trace[535760762] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:619; }","duration":"1.376568639s","start":"2026-04-08T06:13:48.966784Z","end":"2026-04-08T06:13:50.343353Z","steps":["trace[535760762] 'agreement among raft nodes before linearized reading' (duration: 1.376304695s)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:13:50.343264Z","caller":"traceutil/trace.go:171","msg":"trace[2052694972] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"1.229002046s","start":"2026-04-08T06:13:49.114192Z","end":"2026-04-08T06:13:50.343194Z","steps":["trace[2052694972] 'process raft request' (duration: 1.212473025s)","trace[2052694972] 'compare' (duration: 16.155386ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:13:50.343406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:13:48.966767Z","time spent":"1.376627249s","remote":"127.0.0.1:41312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-08T06:13:50.343602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:13:49.114166Z","time spent":"1.229386262s","remote":"127.0.0.1:41564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T06:13:50.343644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.067359241s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T06:13:50.3435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.376531828s","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-08T06:13:50.343791Z","caller":"traceutil/trace.go:171","msg":"trace[489787474] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:619; }","duration":"1.067509033s","start":"2026-04-08T06:13:49.276237Z","end":"2026-04-08T06:13:50.343746Z","steps":["trace[489787474] 'agreement among raft nodes before linearized reading' (duration: 1.066812173s)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:13:50.343766Z","caller":"traceutil/trace.go:171","msg":"trace[759984337] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:619; }","duration":"1.376821522s","start":"2026-04-08T06:13:48.96693Z","end":"2026-04-08T06:13:50.343752Z","steps":["trace[759984337] 'agreement among raft nodes before linearized reading' (duration: 1.376173283s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:13:50.343833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:13:49.276216Z","time spent":"1.067608305s","remote":"127.0.0.1:44044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-08T06:13:50.34386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:13:48.966907Z","time spent":"1.376923443s","remote":"127.0.0.1:41312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-08T06:13:50.365727Z","caller":"traceutil/trace.go:171","msg":"trace[1181065153] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"248.22077ms","start":"2026-04-08T06:13:50.117484Z","end":"2026-04-08T06:13:50.365704Z","steps":["trace[1181065153] 'process raft request' (duration: 247.989447ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:13:52.776592Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831409586253962563,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-08T06:13:52.941414Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.133330513s","expected-duration":"1s"} {"level":"info","ts":"2026-04-08T06:13:52.942371Z","caller":"traceutil/trace.go:171","msg":"trace[1304446910] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:643; }","duration":"666.011783ms","start":"2026-04-08T06:13:52.276336Z","end":"2026-04-08T06:13:52.942348Z","steps":["trace[1304446910] 'read index received' (duration: 665.201231ms)","trace[1304446910] 'applied index is now lower than readState.Index' (duration: 809.802µs)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:13:52.942538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"666.184895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T06:13:52.942618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.246539ms","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-08T06:13:52.942684Z","caller":"traceutil/trace.go:171","msg":"trace[1383575459] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:624; }","duration":"519.344971ms","start":"2026-04-08T06:13:52.423325Z","end":"2026-04-08T06:13:52.94267Z","steps":["trace[1383575459] 'agreement among raft nodes before linearized reading' (duration: 519.144209ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:13:52.94268Z","caller":"traceutil/trace.go:171","msg":"trace[1702227040] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:624; }","duration":"666.321187ms","start":"2026-04-08T06:13:52.276288Z","end":"2026-04-08T06:13:52.942609Z","steps":["trace[1702227040] 'agreement among raft nodes before linearized reading' (duration: 666.150954ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:13:52.942725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:13:52.423311Z","time spent":"519.407742ms","remote":"127.0.0.1:41312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-08T06:13:52.942777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:13:52.276268Z","time spent":"666.462738ms","remote":"127.0.0.1:44052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-08T06:13:52.942793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.75007ms","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-08T06:13:52.942836Z","caller":"traceutil/trace.go:171","msg":"trace[2007549344] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:624; }","duration":"520.826231ms","start":"2026-04-08T06:13:52.421993Z","end":"2026-04-08T06:13:52.942819Z","steps":["trace[2007549344] 'agreement among raft nodes before linearized reading' (duration: 520.7231ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:13:52.942877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:13:52.421969Z","time spent":"520.900312ms","remote":"127.0.0.1:41312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-08T06:13:52.942664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.089061ms","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-08T06:13:52.943008Z","caller":"traceutil/trace.go:171","msg":"trace[291339604] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:624; }","duration":"520.515027ms","start":"2026-04-08T06:13:52.422484Z","end":"2026-04-08T06:13:52.942999Z","steps":["trace[291339604] 'agreement among raft nodes before linearized reading' (duration: 520.04223ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:13:52.943039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:13:52.422466Z","time spent":"520.565577ms","remote":"127.0.0.1:41312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-08T06:13:52.943035Z","caller":"traceutil/trace.go:171","msg":"trace[1657492011] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"2.135412744s","start":"2026-04-08T06:13:50.807Z","end":"2026-04-08T06:13:52.942413Z","steps":["trace[1657492011] 'process raft request' (duration: 2.134875386s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:13:52.943304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.176785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.216\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-08T06:13:52.943353Z","caller":"traceutil/trace.go:171","msg":"trace[312953391] range","detail":"{range_begin:/registry/masterleases/199.204.45.216; range_end:; response_count:1; response_revision:624; }","duration":"489.259736ms","start":"2026-04-08T06:13:52.454078Z","end":"2026-04-08T06:13:52.943338Z","steps":["trace[312953391] 'agreement among raft nodes before linearized reading' (duration: 488.444105ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:13:52.943339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:13:50.806973Z","time spent":"2.136202224s","remote":"127.0.0.1:41168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036321,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T06:13:52.943384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:13:52.454059Z","time spent":"489.317257ms","remote":"127.0.0.1:41084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.216\" "} {"level":"warn","ts":"2026-04-08T06:13:52.943407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.94664ms","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-08T06:13:52.943438Z","caller":"traceutil/trace.go:171","msg":"trace[500118918] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:624; }","duration":"572.978691ms","start":"2026-04-08T06:13:52.37045Z","end":"2026-04-08T06:13:52.943429Z","steps":["trace[500118918] 'agreement among raft nodes before linearized reading' (duration: 571.990517ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:13:52.943458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:13:52.370394Z","time spent":"573.059561ms","remote":"127.0.0.1:41312","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-08T06:13:55.137543Z","caller":"traceutil/trace.go:171","msg":"trace[289368957] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"166.152617ms","start":"2026-04-08T06:13:54.971366Z","end":"2026-04-08T06:13:55.137518Z","steps":["trace[289368957] 'process raft request' (duration: 166.013565ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:13:55.140227Z","caller":"traceutil/trace.go:171","msg":"trace[748804437] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"167.322974ms","start":"2026-04-08T06:13:54.972879Z","end":"2026-04-08T06:13:55.140202Z","steps":["trace[748804437] 'process raft request' (duration: 167.237263ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:13:55.140489Z","caller":"traceutil/trace.go:171","msg":"trace[2026625488] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"167.930921ms","start":"2026-04-08T06:13:54.972548Z","end":"2026-04-08T06:13:55.140479Z","steps":["trace[2026625488] 'process raft request' (duration: 167.349834ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:13:55.140538Z","caller":"traceutil/trace.go:171","msg":"trace[1919116443] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"167.186411ms","start":"2026-04-08T06:13:54.973335Z","end":"2026-04-08T06:13:55.140522Z","steps":["trace[1919116443] 'process raft request' (duration: 166.819526ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:13:58.177051Z","caller":"traceutil/trace.go:171","msg":"trace[2038167659] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"113.867959ms","start":"2026-04-08T06:13:58.06314Z","end":"2026-04-08T06:13:58.177008Z","steps":["trace[2038167659] 'process raft request' (duration: 113.616455ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:13:59.325935Z","caller":"traceutil/trace.go:171","msg":"trace[1811470681] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"169.627853ms","start":"2026-04-08T06:13:59.156276Z","end":"2026-04-08T06:13:59.325904Z","steps":["trace[1811470681] 'process raft request' (duration: 139.431925ms)","trace[1811470681] 'compare' (duration: 29.919224ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:13:59.326002Z","caller":"traceutil/trace.go:171","msg":"trace[1339509457] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"169.636452ms","start":"2026-04-08T06:13:59.156329Z","end":"2026-04-08T06:13:59.325966Z","steps":["trace[1339509457] 'process raft request' (duration: 169.45528ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:13:59.326129Z","caller":"traceutil/trace.go:171","msg":"trace[866589903] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"169.44601ms","start":"2026-04-08T06:13:59.156648Z","end":"2026-04-08T06:13:59.326094Z","steps":["trace[866589903] 'process raft request' (duration: 169.182787ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:14:00.022052Z","caller":"traceutil/trace.go:171","msg":"trace[1523964969] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"108.112121ms","start":"2026-04-08T06:13:59.913919Z","end":"2026-04-08T06:14:00.022031Z","steps":["trace[1523964969] 'process raft request' (duration: 107.945709ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:14:00.153919Z","caller":"traceutil/trace.go:171","msg":"trace[1380680669] linearizableReadLoop","detail":"{readStateIndex:745; appliedIndex:741; }","duration":"200.508777ms","start":"2026-04-08T06:13:59.953389Z","end":"2026-04-08T06:14:00.153898Z","steps":["trace[1380680669] 'read index received' (duration: 68.50468ms)","trace[1380680669] 'applied index is now lower than readState.Index' (duration: 132.003207ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:14:00.153937Z","caller":"traceutil/trace.go:171","msg":"trace[1800886697] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"221.628014ms","start":"2026-04-08T06:13:59.932292Z","end":"2026-04-08T06:14:00.15392Z","steps":["trace[1800886697] 'process raft request' (duration: 221.501862ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:14:00.154004Z","caller":"traceutil/trace.go:171","msg":"trace[643128371] transaction","detail":"{read_only:false; number_of_response:1; response_revision:722; }","duration":"208.915358ms","start":"2026-04-08T06:13:59.945068Z","end":"2026-04-08T06:14:00.153983Z","steps":["trace[643128371] 'process raft request' (duration: 208.792536ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:14:00.154101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.688319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T06:14:00.154144Z","caller":"traceutil/trace.go:171","msg":"trace[335041805] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:722; }","duration":"200.778481ms","start":"2026-04-08T06:13:59.953355Z","end":"2026-04-08T06:14:00.154134Z","steps":["trace[335041805] 'agreement among raft nodes before linearized reading' (duration: 200.668899ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:14:00.154173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.637609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T06:14:00.154249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.750004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T06:14:00.154269Z","caller":"traceutil/trace.go:171","msg":"trace[280319135] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:722; }","duration":"200.77038ms","start":"2026-04-08T06:13:59.953477Z","end":"2026-04-08T06:14:00.154248Z","steps":["trace[280319135] 'agreement among raft nodes before linearized reading' (duration: 200.598568ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:14:00.154282Z","caller":"traceutil/trace.go:171","msg":"trace[983938260] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:722; }","duration":"141.800125ms","start":"2026-04-08T06:14:00.012472Z","end":"2026-04-08T06:14:00.154272Z","steps":["trace[983938260] 'agreement among raft nodes before linearized reading' (duration: 141.759205ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:14:00.154299Z","caller":"traceutil/trace.go:171","msg":"trace[2005265313] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"224.471431ms","start":"2026-04-08T06:13:59.929381Z","end":"2026-04-08T06:14:00.153852Z","steps":["trace[2005265313] 'process raft request' (duration: 192.787217ms)","trace[2005265313] 'compare' (duration: 31.498562ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:14:00.154412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.19674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T06:14:00.154535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.371472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T06:14:00.154585Z","caller":"traceutil/trace.go:171","msg":"trace[1895480168] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:722; }","duration":"174.450463ms","start":"2026-04-08T06:13:59.980118Z","end":"2026-04-08T06:14:00.154568Z","steps":["trace[1895480168] 'agreement among raft nodes before linearized reading' (duration: 174.354902ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:14:00.15421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.985557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T06:14:00.15459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.717099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T06:14:00.154636Z","caller":"traceutil/trace.go:171","msg":"trace[1875222576] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:0; response_revision:722; }","duration":"200.77621ms","start":"2026-04-08T06:13:59.953849Z","end":"2026-04-08T06:14:00.154625Z","steps":["trace[1875222576] 'agreement among raft nodes before linearized reading' (duration: 200.708869ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:14:00.15464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.674799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T06:14:00.154643Z","caller":"traceutil/trace.go:171","msg":"trace[749977122] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:722; }","duration":"142.427353ms","start":"2026-04-08T06:14:00.012194Z","end":"2026-04-08T06:14:00.154621Z","steps":["trace[749977122] 'agreement among raft nodes before linearized reading' (duration: 141.974697ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:14:00.154677Z","caller":"traceutil/trace.go:171","msg":"trace[997324532] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:722; }","duration":"200.730769ms","start":"2026-04-08T06:13:59.953935Z","end":"2026-04-08T06:14:00.154665Z","steps":["trace[997324532] 'agreement among raft nodes before linearized reading' (duration: 200.676969ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:14:00.154534Z","caller":"traceutil/trace.go:171","msg":"trace[532235001] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:722; }","duration":"142.361192ms","start":"2026-04-08T06:14:00.012146Z","end":"2026-04-08T06:14:00.154507Z","steps":["trace[532235001] 'agreement among raft nodes before linearized reading' (duration: 142.1602ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:14:00.15444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.24928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T06:14:00.154815Z","caller":"traceutil/trace.go:171","msg":"trace[1584737276] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:722; }","duration":"174.629435ms","start":"2026-04-08T06:13:59.980171Z","end":"2026-04-08T06:14:00.1548Z","steps":["trace[1584737276] 'agreement among raft nodes before linearized reading' (duration: 174.24217ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:14:00.154232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.306698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T06:14:00.155218Z","caller":"traceutil/trace.go:171","msg":"trace[930887696] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:722; }","duration":"174.306401ms","start":"2026-04-08T06:13:59.980881Z","end":"2026-04-08T06:14:00.155187Z","steps":["trace[930887696] 'agreement among raft nodes before linearized reading' (duration: 173.267978ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:14:00.357413Z","caller":"traceutil/trace.go:171","msg":"trace[55739260] transaction","detail":"{read_only:false; number_of_response:1; response_revision:723; }","duration":"193.841815ms","start":"2026-04-08T06:14:00.163542Z","end":"2026-04-08T06:14:00.357384Z","steps":["trace[55739260] 'process raft request' (duration: 163.036192ms)","trace[55739260] 'compare' (duration: 30.663961ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:14:06.907189Z","caller":"traceutil/trace.go:171","msg":"trace[734020014] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"168.21236ms","start":"2026-04-08T06:14:06.738956Z","end":"2026-04-08T06:14:06.907168Z","steps":["trace[734020014] 'process raft request' (duration: 168.088378ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:14:10.777048Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831409586253962890,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-08T06:14:10.85031Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.455964243s","expected-duration":"1s"} {"level":"info","ts":"2026-04-08T06:14:10.85074Z","caller":"traceutil/trace.go:171","msg":"trace[1724520227] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"1.456447689s","start":"2026-04-08T06:14:09.394265Z","end":"2026-04-08T06:14:10.850713Z","steps":["trace[1724520227] 'process raft request' (duration: 1.456266977s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:14:10.850948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:14:09.394219Z","time spent":"1.456593461s","remote":"127.0.0.1:41312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T06:14:10.913744Z","caller":"traceutil/trace.go:171","msg":"trace[651509700] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"1.519318274s","start":"2026-04-08T06:14:09.394408Z","end":"2026-04-08T06:14:10.913726Z","steps":["trace[651509700] 'process raft request' (duration: 1.519163882s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:14:10.913899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:14:09.394381Z","time spent":"1.519416585s","remote":"127.0.0.1:41312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T06:14:10.914005Z","caller":"traceutil/trace.go:171","msg":"trace[1641119661] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"965.285855ms","start":"2026-04-08T06:14:09.948707Z","end":"2026-04-08T06:14:10.913993Z","steps":["trace[1641119661] 'process raft request' (duration: 964.983512ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:14:10.913992Z","caller":"traceutil/trace.go:171","msg":"trace[593028132] linearizableReadLoop","detail":"{readStateIndex:822; appliedIndex:818; }","duration":"637.914632ms","start":"2026-04-08T06:14:10.276023Z","end":"2026-04-08T06:14:10.913937Z","steps":["trace[593028132] 'read index received' (duration: 574.585752ms)","trace[593028132] 'applied index is now lower than readState.Index' (duration: 63.316769ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:14:10.914108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:14:09.948684Z","time spent":"965.387246ms","remote":"127.0.0.1:41292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T06:14:10.914297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.280316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T06:14:10.914326Z","caller":"traceutil/trace.go:171","msg":"trace[88942553] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:794; }","duration":"638.351207ms","start":"2026-04-08T06:14:10.275968Z","end":"2026-04-08T06:14:10.914319Z","steps":["trace[88942553] 'agreement among raft nodes before linearized reading' (duration: 638.304797ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:14:10.914376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:14:10.275946Z","time spent":"638.424228ms","remote":"127.0.0.1:44052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-08T06:14:10.914434Z","caller":"traceutil/trace.go:171","msg":"trace[1684335490] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"1.519168041s","start":"2026-04-08T06:14:09.39462Z","end":"2026-04-08T06:14:10.913788Z","steps":["trace[1684335490] 'process raft request' (duration: 1.519045949s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:14:10.914615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:14:09.394611Z","time spent":"1.51991238s","remote":"127.0.0.1:41312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T06:14:15.092742Z","caller":"traceutil/trace.go:171","msg":"trace[948223210] transaction","detail":"{read_only:false; response_revision:848; number_of_response:1; }","duration":"102.257125ms","start":"2026-04-08T06:14:14.990458Z","end":"2026-04-08T06:14:15.092715Z","steps":["trace[948223210] 'process raft request' (duration: 102.053632ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:14:15.092812Z","caller":"traceutil/trace.go:171","msg":"trace[1338424149] transaction","detail":"{read_only:false; response_revision:849; number_of_response:1; }","duration":"100.068229ms","start":"2026-04-08T06:14:14.992697Z","end":"2026-04-08T06:14:15.092765Z","steps":["trace[1338424149] 'process raft request' (duration: 99.947647ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:14:21.336404Z","caller":"traceutil/trace.go:171","msg":"trace[750396549] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"115.406687ms","start":"2026-04-08T06:14:21.22097Z","end":"2026-04-08T06:14:21.336376Z","steps":["trace[750396549] 'process raft request' (duration: 103.126985ms)","trace[750396549] 'compare' (duration: 12.11929ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:14:21.573089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.924534ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-08T06:14:21.573331Z","caller":"traceutil/trace.go:171","msg":"trace[1158364160] linearizableReadLoop","detail":"{readStateIndex:947; appliedIndex:944; }","duration":"314.437193ms","start":"2026-04-08T06:14:21.258871Z","end":"2026-04-08T06:14:21.573308Z","steps":["trace[1158364160] 'read index received' (duration: 65.231845ms)","trace[1158364160] 'applied index is now lower than readState.Index' (duration: 249.204208ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:14:21.573559Z","caller":"traceutil/trace.go:171","msg":"trace[1784842389] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"351.860188ms","start":"2026-04-08T06:14:21.221678Z","end":"2026-04-08T06:14:21.573539Z","steps":["trace[1784842389] 'process raft request' (duration: 247.366867ms)","trace[1784842389] 'compare' (duration: 103.627421ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:14:21.573692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.815988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-08T06:14:21.573748Z","caller":"traceutil/trace.go:171","msg":"trace[878131211] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:917; }","duration":"314.882019ms","start":"2026-04-08T06:14:21.258853Z","end":"2026-04-08T06:14:21.573735Z","steps":["trace[878131211] 'agreement among raft nodes before linearized reading' (duration: 314.665747ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:14:21.57375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:14:21.221653Z","time spent":"352.037519ms","remote":"127.0.0.1:41272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":178,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T06:14:21.573788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:14:21.258802Z","time spent":"314.979099ms","remote":"127.0.0.1:41312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-08T06:14:21.574062Z","caller":"traceutil/trace.go:171","msg":"trace[539974596] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"349.629412ms","start":"2026-04-08T06:14:21.224422Z","end":"2026-04-08T06:14:21.574052Z","steps":["trace[539974596] 'process raft request' (duration: 348.806212ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:14:21.574123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:14:21.224405Z","time spent":"349.687853ms","remote":"127.0.0.1:41168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12761,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T06:14:21.574162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.64288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T06:14:21.574188Z","caller":"traceutil/trace.go:171","msg":"trace[1403387321] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:917; }","duration":"298.692951ms","start":"2026-04-08T06:14:21.275487Z","end":"2026-04-08T06:14:21.57418Z","steps":["trace[1403387321] 'agreement among raft nodes before linearized reading' (duration: 298.654291ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:14:24.453045Z","caller":"traceutil/trace.go:171","msg":"trace[260775856] linearizableReadLoop","detail":"{readStateIndex:979; appliedIndex:978; }","duration":"176.908289ms","start":"2026-04-08T06:14:24.276118Z","end":"2026-04-08T06:14:24.453026Z","steps":["trace[260775856] 'read index received' (duration: 176.730577ms)","trace[260775856] 'applied index is now lower than readState.Index' (duration: 176.582µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:14:24.453084Z","caller":"traceutil/trace.go:171","msg":"trace[199657324] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"460.592508ms","start":"2026-04-08T06:14:23.992462Z","end":"2026-04-08T06:14:24.453055Z","steps":["trace[199657324] 'process raft request' (duration: 460.451976ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:14:24.453404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:14:23.992442Z","time spent":"460.713159ms","remote":"127.0.0.1:41136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":811,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T06:14:24.45345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.322234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T06:14:24.45352Z","caller":"traceutil/trace.go:171","msg":"trace[1648468792] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:948; }","duration":"177.439505ms","start":"2026-04-08T06:14:24.276059Z","end":"2026-04-08T06:14:24.453499Z","steps":["trace[1648468792] 'agreement among raft nodes before linearized reading' (duration: 177.084521ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:14:24.64408Z","caller":"traceutil/trace.go:171","msg":"trace[1033119951] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"183.006849ms","start":"2026-04-08T06:14:24.461044Z","end":"2026-04-08T06:14:24.64405Z","steps":["trace[1033119951] 'process raft request' (duration: 182.869157ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:14:24.801893Z","caller":"traceutil/trace.go:171","msg":"trace[1110404982] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"148.223811ms","start":"2026-04-08T06:14:24.653652Z","end":"2026-04-08T06:14:24.801875Z","steps":["trace[1110404982] 'process raft request' (duration: 148.18415ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:14:24.801917Z","caller":"traceutil/trace.go:171","msg":"trace[436612862] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"150.421336ms","start":"2026-04-08T06:14:24.65148Z","end":"2026-04-08T06:14:24.801901Z","steps":["trace[436612862] 'process raft request' (duration: 150.258764ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:14:49.930823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.188708ms","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-08T06:14:49.930905Z","caller":"traceutil/trace.go:171","msg":"trace[525584787] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1059; }","duration":"194.323239ms","start":"2026-04-08T06:14:49.736562Z","end":"2026-04-08T06:14:49.930885Z","steps":["trace[525584787] 'range keys from in-memory index tree' (duration: 194.015046ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:14:57.26786Z","caller":"traceutil/trace.go:171","msg":"trace[1228474625] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"113.60341ms","start":"2026-04-08T06:14:57.154237Z","end":"2026-04-08T06:14:57.26784Z","steps":["trace[1228474625] 'process raft request' (duration: 112.851203ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:15:10.321355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.076581ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T06:15:10.321454Z","caller":"traceutil/trace.go:171","msg":"trace[810653014] transaction","detail":"{read_only:false; response_revision:1126; number_of_response:1; }","duration":"171.013113ms","start":"2026-04-08T06:15:10.150424Z","end":"2026-04-08T06:15:10.321438Z","steps":["trace[810653014] 'process raft request' (duration: 59.785391ms)","trace[810653014] 'compare' (duration: 110.98274ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:15:31.784248Z","caller":"traceutil/trace.go:171","msg":"trace[1977442840] transaction","detail":"{read_only:false; response_revision:1194; number_of_response:1; }","duration":"118.112229ms","start":"2026-04-08T06:15:31.66611Z","end":"2026-04-08T06:15:31.784222Z","steps":["trace[1977442840] 'process raft request' (duration: 117.885837ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:15:34.03477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.499212ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T06:15:34.034865Z","caller":"traceutil/trace.go:171","msg":"trace[742876259] linearizableReadLoop","detail":"{readStateIndex:1248; appliedIndex:1247; }","duration":"171.146202ms","start":"2026-04-08T06:15:33.863705Z","end":"2026-04-08T06:15:34.034851Z","steps":["trace[742876259] 'read index received' (duration: 67.304877ms)","trace[742876259] 'applied index is now lower than readState.Index' (duration: 103.840345ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:15:34.034899Z","caller":"traceutil/trace.go:171","msg":"trace[1826188990] transaction","detail":"{read_only:false; response_revision:1202; number_of_response:1; }","duration":"226.163568ms","start":"2026-04-08T06:15:33.808709Z","end":"2026-04-08T06:15:34.034873Z","steps":["trace[1826188990] 'process raft request' (duration: 122.302893ms)","trace[1826188990] 'compare' (duration: 103.334791ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:15:34.035116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.187362ms","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-08T06:15:34.035148Z","caller":"traceutil/trace.go:171","msg":"trace[1557872785] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1202; }","duration":"171.252284ms","start":"2026-04-08T06:15:33.863885Z","end":"2026-04-08T06:15:34.035137Z","steps":["trace[1557872785] 'agreement among raft nodes before linearized reading' (duration: 171.153983ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:15:34.035444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.708747ms","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-08T06:15:34.035555Z","caller":"traceutil/trace.go:171","msg":"trace[74453299] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1202; }","duration":"171.856868ms","start":"2026-04-08T06:15:33.86367Z","end":"2026-04-08T06:15:34.035527Z","steps":["trace[74453299] 'agreement among raft nodes before linearized reading' (duration: 171.579156ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:15:53.917478Z","caller":"traceutil/trace.go:171","msg":"trace[465580850] linearizableReadLoop","detail":"{readStateIndex:1352; appliedIndex:1351; }","duration":"112.184295ms","start":"2026-04-08T06:15:53.805275Z","end":"2026-04-08T06:15:53.917459Z","steps":["trace[465580850] 'read index received' (duration: 112.005983ms)","trace[465580850] 'applied index is now lower than readState.Index' (duration: 177.612µs)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:15:53.917616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.314796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T06:15:53.917653Z","caller":"traceutil/trace.go:171","msg":"trace[1652240875] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1300; }","duration":"112.407056ms","start":"2026-04-08T06:15:53.805232Z","end":"2026-04-08T06:15:53.917639Z","steps":["trace[1652240875] 'agreement among raft nodes before linearized reading' (duration: 112.304796ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:15:53.917651Z","caller":"traceutil/trace.go:171","msg":"trace[952966062] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"303.103203ms","start":"2026-04-08T06:15:53.61451Z","end":"2026-04-08T06:15:53.917613Z","steps":["trace[952966062] 'process raft request' (duration: 302.816701ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:15:53.917813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:15:53.614478Z","time spent":"303.247044ms","remote":"127.0.0.1:41312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T06:16:01.918128Z","caller":"traceutil/trace.go:171","msg":"trace[568456356] transaction","detail":"{read_only:false; response_revision:1331; number_of_response:1; }","duration":"404.861233ms","start":"2026-04-08T06:16:01.513241Z","end":"2026-04-08T06:16:01.918102Z","steps":["trace[568456356] 'process raft request' (duration: 404.704531ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:16:01.918664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:16:01.513214Z","time spent":"404.998095ms","remote":"127.0.0.1:41312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T06:16:08.777745Z","caller":"traceutil/trace.go:171","msg":"trace[1429243432] linearizableReadLoop","detail":"{readStateIndex:1417; appliedIndex:1416; }","duration":"100.518614ms","start":"2026-04-08T06:16:08.677204Z","end":"2026-04-08T06:16:08.777723Z","steps":["trace[1429243432] 'read index received' (duration: 89.044687ms)","trace[1429243432] 'applied index is now lower than readState.Index' (duration: 11.472977ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:16:08.777842Z","caller":"traceutil/trace.go:171","msg":"trace[611220565] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"106.827998ms","start":"2026-04-08T06:16:08.67098Z","end":"2026-04-08T06:16:08.777808Z","steps":["trace[611220565] 'process raft request' (duration: 95.31191ms)","trace[611220565] 'compare' (duration: 11.340907ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:16:08.778012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.757196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T06:16:08.778132Z","caller":"traceutil/trace.go:171","msg":"trace[584061884] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1361; }","duration":"100.926648ms","start":"2026-04-08T06:16:08.677174Z","end":"2026-04-08T06:16:08.778101Z","steps":["trace[584061884] 'agreement among raft nodes before linearized reading' (duration: 100.687726ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:16:22.393402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.475711ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T06:16:22.393484Z","caller":"traceutil/trace.go:171","msg":"trace[1529903692] transaction","detail":"{read_only:false; response_revision:1403; number_of_response:1; }","duration":"192.181086ms","start":"2026-04-08T06:16:22.20129Z","end":"2026-04-08T06:16:22.393471Z","steps":["trace[1529903692] 'process raft request' (duration: 86.573284ms)","trace[1529903692] 'compare' (duration: 105.39424ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:16:22.436679Z","caller":"traceutil/trace.go:171","msg":"trace[649196382] linearizableReadLoop","detail":"{readStateIndex:1462; appliedIndex:1460; }","duration":"214.211391ms","start":"2026-04-08T06:16:22.222456Z","end":"2026-04-08T06:16:22.436668Z","steps":["trace[649196382] 'read index received' (duration: 65.417788ms)","trace[649196382] 'applied index is now lower than readState.Index' (duration: 148.793093ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:16:22.436766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.297615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-public/\" range_end:\"/registry/daemonsets/kube-public0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T06:16:22.436767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.301902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-public/\" range_end:\"/registry/deployments/kube-public0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T06:16:22.436745Z","caller":"traceutil/trace.go:171","msg":"trace[1362839195] transaction","detail":"{read_only:false; response_revision:1404; number_of_response:1; }","duration":"227.991757ms","start":"2026-04-08T06:16:22.208718Z","end":"2026-04-08T06:16:22.436709Z","steps":["trace[1362839195] 'process raft request' (duration: 227.896406ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:16:22.436798Z","caller":"traceutil/trace.go:171","msg":"trace[129679081] range","detail":"{range_begin:/registry/deployments/kube-public/; range_end:/registry/deployments/kube-public0; response_count:0; response_revision:1404; }","duration":"214.371162ms","start":"2026-04-08T06:16:22.222419Z","end":"2026-04-08T06:16:22.43679Z","steps":["trace[129679081] 'agreement among raft nodes before linearized reading' (duration: 214.306992ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:16:22.436879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.311753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:1 size:4641"} {"level":"info","ts":"2026-04-08T06:16:22.436796Z","caller":"traceutil/trace.go:171","msg":"trace[1902748763] range","detail":"{range_begin:/registry/daemonsets/kube-public/; range_end:/registry/daemonsets/kube-public0; response_count:0; response_revision:1404; }","duration":"212.360755ms","start":"2026-04-08T06:16:22.224426Z","end":"2026-04-08T06:16:22.436787Z","steps":["trace[1902748763] 'agreement among raft nodes before linearized reading' (duration: 212.320165ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:16:22.436933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.299684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T06:16:22.436953Z","caller":"traceutil/trace.go:171","msg":"trace[1787197069] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1404; }","duration":"163.359726ms","start":"2026-04-08T06:16:22.273588Z","end":"2026-04-08T06:16:22.436948Z","steps":["trace[1787197069] 'agreement among raft nodes before linearized reading' (duration: 163.329605ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:16:22.436907Z","caller":"traceutil/trace.go:171","msg":"trace[488124806] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/envoy-gateway; range_end:; response_count:1; response_revision:1404; }","duration":"128.372923ms","start":"2026-04-08T06:16:22.308525Z","end":"2026-04-08T06:16:22.436898Z","steps":["trace[488124806] 'agreement among raft nodes before linearized reading' (duration: 128.287962ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:16:22.437042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.552146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/envoy\" ","response":"range_response_count:1 size:4434"} {"level":"info","ts":"2026-04-08T06:16:22.437075Z","caller":"traceutil/trace.go:171","msg":"trace[1122781305] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/envoy; range_end:; response_count:1; response_revision:1404; }","duration":"206.619507ms","start":"2026-04-08T06:16:22.230449Z","end":"2026-04-08T06:16:22.437068Z","steps":["trace[1122781305] 'agreement among raft nodes before linearized reading' (duration: 206.489396ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:16:26.421076Z","caller":"traceutil/trace.go:171","msg":"trace[1266023931] linearizableReadLoop","detail":"{readStateIndex:1475; appliedIndex:1474; }","duration":"147.521457ms","start":"2026-04-08T06:16:26.273527Z","end":"2026-04-08T06:16:26.421048Z","steps":["trace[1266023931] 'read index received' (duration: 81.65µs)","trace[1266023931] 'applied index is now lower than readState.Index' (duration: 147.438797ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:16:26.421137Z","caller":"traceutil/trace.go:171","msg":"trace[358838669] transaction","detail":"{read_only:false; response_revision:1416; number_of_response:1; }","duration":"147.609309ms","start":"2026-04-08T06:16:26.273493Z","end":"2026-04-08T06:16:26.421102Z","steps":["trace[358838669] 'process raft request' (duration: 147.374547ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:16:26.421241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.699008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T06:16:26.421276Z","caller":"traceutil/trace.go:171","msg":"trace[1486866472] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1416; }","duration":"147.78018ms","start":"2026-04-08T06:16:26.273486Z","end":"2026-04-08T06:16:26.421266Z","steps":["trace[1486866472] 'agreement among raft nodes before linearized reading' (duration: 147.705499ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:16:26.421316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.448429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5164"} {"level":"info","ts":"2026-04-08T06:16:26.421383Z","caller":"traceutil/trace.go:171","msg":"trace[2027771405] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:1416; }","duration":"134.52939ms","start":"2026-04-08T06:16:26.286837Z","end":"2026-04-08T06:16:26.421367Z","steps":["trace[2027771405] 'agreement among raft nodes before linearized reading' (duration: 134.392819ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:16:26.421418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.409682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-rjbrz\" ","response":"range_response_count:1 size:4908"} {"level":"info","ts":"2026-04-08T06:16:26.421491Z","caller":"traceutil/trace.go:171","msg":"trace[1322288377] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-rjbrz; range_end:; response_count:1; response_revision:1416; }","duration":"132.497603ms","start":"2026-04-08T06:16:26.288978Z","end":"2026-04-08T06:16:26.421475Z","steps":["trace[1322288377] 'agreement among raft nodes before linearized reading' (duration: 132.305641ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:16:35.096022Z","caller":"traceutil/trace.go:171","msg":"trace[1547380657] linearizableReadLoop","detail":"{readStateIndex:1506; appliedIndex:1505; }","duration":"114.690577ms","start":"2026-04-08T06:16:34.981309Z","end":"2026-04-08T06:16:35.095999Z","steps":["trace[1547380657] 'read index received' (duration: 114.497655ms)","trace[1547380657] 'applied index is now lower than readState.Index' (duration: 192.012µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:16:35.096132Z","caller":"traceutil/trace.go:171","msg":"trace[1479800785] transaction","detail":"{read_only:false; response_revision:1445; number_of_response:1; }","duration":"115.400733ms","start":"2026-04-08T06:16:34.98072Z","end":"2026-04-08T06:16:35.09612Z","steps":["trace[1479800785] 'process raft request' (duration: 115.145351ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:16:35.096457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.11566ms","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-08T06:16:35.096555Z","caller":"traceutil/trace.go:171","msg":"trace[1777912928] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1445; }","duration":"115.223531ms","start":"2026-04-08T06:16:34.981306Z","end":"2026-04-08T06:16:35.09653Z","steps":["trace[1777912928] 'agreement among raft nodes before linearized reading' (duration: 114.990099ms)"],"step_count":1}