{"level":"warn","ts":"2026-02-25T02:06:19.604623Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-25T02:06:19.604735Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.131: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.131:2380","--initial-cluster=instance=https://199.204.45.131:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.131:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.131:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-25T02:06:19.605055Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-25T02:06:19.605167Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.131:2380"]} {"level":"info","ts":"2026-02-25T02:06:19.605273Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-25T02:06:19.606192Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.131:2379"]} {"level":"info","ts":"2026-02-25T02:06:19.606371Z","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.131:2380"],"listen-peer-urls":["https://199.204.45.131:2380"],"advertise-client-urls":["https://199.204.45.131:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.131:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.131:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-25T02:06:19.614487Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.73294ms"} {"level":"info","ts":"2026-02-25T02:06:19.622946Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2fdf33955ed9ec6c","cluster-id":"e3dd6b4ed8cb907a"} {"level":"info","ts":"2026-02-25T02:06:19.623108Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2fdf33955ed9ec6c switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T02:06:19.623134Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2fdf33955ed9ec6c became follower at term 0"} {"level":"info","ts":"2026-02-25T02:06:19.623206Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2fdf33955ed9ec6c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T02:06:19.62326Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2fdf33955ed9ec6c became follower at term 1"} {"level":"info","ts":"2026-02-25T02:06:19.623351Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2fdf33955ed9ec6c switched to configuration voters=(3449532556223573100)"} {"level":"warn","ts":"2026-02-25T02:06:19.632155Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T02:06:19.635667Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T02:06:19.637069Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-02-25T02:06:19.641637Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2fdf33955ed9ec6c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T02:06:19.641856Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-25T02:06:19.642505Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-25T02:06:19.64253Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-25T02:06:19.643763Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2fdf33955ed9ec6c switched to configuration voters=(3449532556223573100)"} {"level":"info","ts":"2026-02-25T02:06:19.642468Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2fdf33955ed9ec6c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T02:06:19.644306Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e3dd6b4ed8cb907a","local-member-id":"2fdf33955ed9ec6c","added-peer-id":"2fdf33955ed9ec6c","added-peer-peer-urls":["https://199.204.45.131:2380"]} {"level":"info","ts":"2026-02-25T02:06:19.646548Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-25T02:06:19.646681Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.131:2380"} {"level":"info","ts":"2026-02-25T02:06:19.64672Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.131:2380"} {"level":"info","ts":"2026-02-25T02:06:19.647438Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2fdf33955ed9ec6c","initial-advertise-peer-urls":["https://199.204.45.131:2380"],"listen-peer-urls":["https://199.204.45.131:2380"],"advertise-client-urls":["https://199.204.45.131:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.131:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T02:06:19.647486Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T02:06:20.423836Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2fdf33955ed9ec6c is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T02:06:20.4239Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2fdf33955ed9ec6c became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T02:06:20.423929Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2fdf33955ed9ec6c received MsgPreVoteResp from 2fdf33955ed9ec6c at term 1"} {"level":"info","ts":"2026-02-25T02:06:20.423948Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2fdf33955ed9ec6c became candidate at term 2"} {"level":"info","ts":"2026-02-25T02:06:20.42396Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2fdf33955ed9ec6c received MsgVoteResp from 2fdf33955ed9ec6c at term 2"} {"level":"info","ts":"2026-02-25T02:06:20.423992Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2fdf33955ed9ec6c became leader at term 2"} {"level":"info","ts":"2026-02-25T02:06:20.424008Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2fdf33955ed9ec6c elected leader 2fdf33955ed9ec6c at term 2"} {"level":"info","ts":"2026-02-25T02:06:20.425039Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2fdf33955ed9ec6c","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.131:2379]}","request-path":"/0/members/2fdf33955ed9ec6c/attributes","cluster-id":"e3dd6b4ed8cb907a","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T02:06:20.42509Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T02:06:20.425222Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T02:06:20.425537Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T02:06:20.425647Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T02:06:20.425688Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T02:06:20.426661Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e3dd6b4ed8cb907a","local-member-id":"2fdf33955ed9ec6c","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T02:06:20.426807Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T02:06:20.426861Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T02:06:20.428245Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.131:2379"} {"level":"info","ts":"2026-02-25T02:06:20.42923Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-25T02:06:43.663999Z","caller":"traceutil/trace.go:171","msg":"trace[1609942234] linearizableReadLoop","detail":"{readStateIndex:434; appliedIndex:433; }","duration":"256.141931ms","start":"2026-02-25T02:06:43.407827Z","end":"2026-02-25T02:06:43.663969Z","steps":["trace[1609942234] 'read index received' (duration: 255.958225ms)","trace[1609942234] 'applied index is now lower than readState.Index' (duration: 182.596µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:06:43.66428Z","caller":"traceutil/trace.go:171","msg":"trace[492498983] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"698.883534ms","start":"2026-02-25T02:06:42.965358Z","end":"2026-02-25T02:06:43.664242Z","steps":["trace[492498983] 'process raft request' (duration: 698.483512ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:06:43.664339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.376108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.131\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-25T02:06:43.664471Z","caller":"traceutil/trace.go:171","msg":"trace[964350656] range","detail":"{range_begin:/registry/masterleases/199.204.45.131; range_end:; response_count:1; response_revision:418; }","duration":"256.658267ms","start":"2026-02-25T02:06:43.407786Z","end":"2026-02-25T02:06:43.664444Z","steps":["trace[964350656] 'agreement among raft nodes before linearized reading' (duration: 256.324566ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:06:43.664899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:06:42.965342Z","time spent":"699.027939ms","remote":"127.0.0.1:54150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:06:49.072283Z","caller":"traceutil/trace.go:171","msg":"trace[317038078] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"548.924395ms","start":"2026-02-25T02:06:48.523333Z","end":"2026-02-25T02:06:49.072257Z","steps":["trace[317038078] 'process raft request' (duration: 548.798462ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:06:49.072442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:06:48.523315Z","time spent":"549.053149ms","remote":"127.0.0.1:54150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:06:49.080644Z","caller":"traceutil/trace.go:171","msg":"trace[145715751] linearizableReadLoop","detail":"{readStateIndex:484; appliedIndex:482; }","duration":"188.03944ms","start":"2026-02-25T02:06:48.892585Z","end":"2026-02-25T02:06:49.080625Z","steps":["trace[145715751] 'read index received' (duration: 179.586931ms)","trace[145715751] 'applied index is now lower than readState.Index' (duration: 8.451269ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:06:49.080771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.166954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4021"} {"level":"info","ts":"2026-02-25T02:06:49.080853Z","caller":"traceutil/trace.go:171","msg":"trace[298001615] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:466; }","duration":"188.279538ms","start":"2026-02-25T02:06:48.892554Z","end":"2026-02-25T02:06:49.080833Z","steps":["trace[298001615] 'agreement among raft nodes before linearized reading' (duration: 188.133303ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:06:50.069288Z","caller":"traceutil/trace.go:171","msg":"trace[1816018643] linearizableReadLoop","detail":"{readStateIndex:486; appliedIndex:485; }","duration":"175.707076ms","start":"2026-02-25T02:06:49.893562Z","end":"2026-02-25T02:06:50.069269Z","steps":["trace[1816018643] 'read index received' (duration: 175.680416ms)","trace[1816018643] 'applied index is now lower than readState.Index' (duration: 26.04µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:06:50.069433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.859711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:06:50.069402Z","caller":"traceutil/trace.go:171","msg":"trace[1641232024] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"373.607512ms","start":"2026-02-25T02:06:49.695755Z","end":"2026-02-25T02:06:50.069363Z","steps":["trace[1641232024] 'process raft request' (duration: 373.420007ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:06:50.069453Z","caller":"traceutil/trace.go:171","msg":"trace[1223541785] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:468; }","duration":"176.115759ms","start":"2026-02-25T02:06:49.893332Z","end":"2026-02-25T02:06:50.069448Z","steps":["trace[1223541785] 'agreement among raft nodes before linearized reading' (duration: 176.020626ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:06:50.069596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:06:49.695729Z","time spent":"373.752217ms","remote":"127.0.0.1:54150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:06:50.46973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.849513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-02-25T02:06:50.469742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.472825ms","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-02-25T02:06:50.469821Z","caller":"traceutil/trace.go:171","msg":"trace[1133935849] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:468; }","duration":"209.954966ms","start":"2026-02-25T02:06:50.259843Z","end":"2026-02-25T02:06:50.469798Z","steps":["trace[1133935849] 'range keys from in-memory index tree' (duration: 209.664537ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:06:50.469836Z","caller":"traceutil/trace.go:171","msg":"trace[622327489] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:468; }","duration":"254.631949ms","start":"2026-02-25T02:06:50.215182Z","end":"2026-02-25T02:06:50.469814Z","steps":["trace[622327489] 'range keys from in-memory index tree' (duration: 254.367742ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:06:55.664472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.091676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-25T02:06:55.664529Z","caller":"traceutil/trace.go:171","msg":"trace[2005356891] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:499; }","duration":"542.180748ms","start":"2026-02-25T02:06:55.122335Z","end":"2026-02-25T02:06:55.664516Z","steps":["trace[2005356891] 'range keys from in-memory index tree' (duration: 541.90953ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:06:55.664552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:06:55.122317Z","time spent":"542.22989ms","remote":"127.0.0.1:54150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T02:06:55.664605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.580445589s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-25T02:06:55.664472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.771702056s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:06:55.664658Z","caller":"traceutil/trace.go:171","msg":"trace[2128217093] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:499; }","duration":"1.580554752s","start":"2026-02-25T02:06:54.08409Z","end":"2026-02-25T02:06:55.664645Z","steps":["trace[2128217093] 'range keys from in-memory index tree' (duration: 1.580217682s)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:06:55.664665Z","caller":"traceutil/trace.go:171","msg":"trace[1021560075] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:499; }","duration":"1.771941833s","start":"2026-02-25T02:06:53.89271Z","end":"2026-02-25T02:06:55.664652Z","steps":["trace[1021560075] 'range keys from in-memory index tree' (duration: 1.771627434s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:06:55.66469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:06:54.084059Z","time spent":"1.580622234s","remote":"127.0.0.1:54150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T02:06:55.664696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:06:53.892695Z","time spent":"1.771992394s","remote":"127.0.0.1:53854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T02:06:55.66473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.140146183s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-25T02:06:55.664754Z","caller":"traceutil/trace.go:171","msg":"trace[1297074599] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:499; }","duration":"1.140169484s","start":"2026-02-25T02:06:54.524576Z","end":"2026-02-25T02:06:55.664745Z","steps":["trace[1297074599] 'range keys from in-memory index tree' (duration: 1.14005394s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:06:55.664767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:06:54.524517Z","time spent":"1.140246176s","remote":"127.0.0.1:54150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T02:06:55.664793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.118536756s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-25T02:06:55.664837Z","caller":"traceutil/trace.go:171","msg":"trace[121307818] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:499; }","duration":"1.118913507s","start":"2026-02-25T02:06:54.545901Z","end":"2026-02-25T02:06:55.664814Z","steps":["trace[121307818] 'range keys from in-memory index tree' (duration: 1.118313609s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:06:55.664885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:06:54.545888Z","time spent":"1.118985529s","remote":"127.0.0.1:54150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-25T02:06:55.738375Z","caller":"traceutil/trace.go:171","msg":"trace[395914802] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"473.221121ms","start":"2026-02-25T02:06:55.265129Z","end":"2026-02-25T02:06:55.73835Z","steps":["trace[395914802] 'process raft request' (duration: 473.015344ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:06:55.738498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:06:55.265109Z","time spent":"473.328974ms","remote":"127.0.0.1:54150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:06:59.065654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.510495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:06:59.065711Z","caller":"traceutil/trace.go:171","msg":"trace[1793760732] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:511; }","duration":"172.612298ms","start":"2026-02-25T02:06:58.893085Z","end":"2026-02-25T02:06:59.065698Z","steps":["trace[1793760732] 'range keys from in-memory index tree' (duration: 172.421562ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:06:59.433068Z","caller":"traceutil/trace.go:171","msg":"trace[721701792] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"350.633488ms","start":"2026-02-25T02:06:59.082416Z","end":"2026-02-25T02:06:59.43305Z","steps":["trace[721701792] 'process raft request' (duration: 350.496894ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:06:59.433202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:06:59.082392Z","time spent":"350.73534ms","remote":"127.0.0.1:54074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:07:00.184792Z","caller":"traceutil/trace.go:171","msg":"trace[1538632316] linearizableReadLoop","detail":"{readStateIndex:533; appliedIndex:532; }","duration":"425.44254ms","start":"2026-02-25T02:06:59.759327Z","end":"2026-02-25T02:07:00.184769Z","steps":["trace[1538632316] 'read index received' (duration: 425.319756ms)","trace[1538632316] 'applied index is now lower than readState.Index' (duration: 121.334µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:07:00.184946Z","caller":"traceutil/trace.go:171","msg":"trace[639942271] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"425.865312ms","start":"2026-02-25T02:06:59.759022Z","end":"2026-02-25T02:07:00.184887Z","steps":["trace[639942271] 'process raft request' (duration: 425.626775ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:00.18502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.641376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-25T02:07:00.185063Z","caller":"traceutil/trace.go:171","msg":"trace[1314008542] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:513; }","duration":"425.744529ms","start":"2026-02-25T02:06:59.759308Z","end":"2026-02-25T02:07:00.185053Z","steps":["trace[1314008542] 'agreement among raft nodes before linearized reading' (duration: 425.551554ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:00.185098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:06:59.7593Z","time spent":"425.7894ms","remote":"127.0.0.1:54150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T02:07:00.185196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:06:59.759008Z","time spent":"426.053508ms","remote":"127.0.0.1:54150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:07:00.199834Z","caller":"traceutil/trace.go:171","msg":"trace[310547879] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"439.242044ms","start":"2026-02-25T02:06:59.760565Z","end":"2026-02-25T02:07:00.199807Z","steps":["trace[310547879] 'process raft request' (duration: 438.656916ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:00.199954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:06:59.760556Z","time spent":"439.337526ms","remote":"127.0.0.1:54150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:07:00.199959Z","caller":"traceutil/trace.go:171","msg":"trace[2004211089] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"438.302006ms","start":"2026-02-25T02:06:59.761618Z","end":"2026-02-25T02:07:00.19992Z","steps":["trace[2004211089] 'process raft request' (duration: 438.08513ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:00.20018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:06:59.761611Z","time spent":"438.494241ms","remote":"127.0.0.1:54150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:07:02.70276Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17036163643665017745,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:07:03.203498Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17036163643665017745,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:07:03.703897Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17036163643665017745,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:07:03.912885Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.715452766s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T02:07:03.91335Z","caller":"traceutil/trace.go:171","msg":"trace[958897793] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"1.716015273s","start":"2026-02-25T02:07:02.197314Z","end":"2026-02-25T02:07:03.913329Z","steps":["trace[958897793] 'process raft request' (duration: 1.715827577s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:03.913486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:02.197297Z","time spent":"1.716120615s","remote":"127.0.0.1:54150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:07:04.565582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.361785ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:07:04.565691Z","caller":"traceutil/trace.go:171","msg":"trace[1697393836] linearizableReadLoop","detail":"{readStateIndex:538; appliedIndex:536; }","duration":"2.363247563s","start":"2026-02-25T02:07:02.202429Z","end":"2026-02-25T02:07:04.565677Z","steps":["trace[1697393836] 'read index received' (duration: 1.710671307s)","trace[1697393836] 'applied index is now lower than readState.Index' (duration: 652.574706ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:07:04.565773Z","caller":"traceutil/trace.go:171","msg":"trace[1238796119] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"1.922836098s","start":"2026-02-25T02:07:02.642926Z","end":"2026-02-25T02:07:04.565762Z","steps":["trace[1238796119] 'process raft request' (duration: 1.571068583s)","trace[1238796119] 'compare' (duration: 350.8788ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:07:04.565834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:02.642903Z","time spent":"1.922901411s","remote":"127.0.0.1:54150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:07:04.566257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.363814509s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-25T02:07:04.56624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.930162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:07:04.566313Z","caller":"traceutil/trace.go:171","msg":"trace[1944755594] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:518; }","duration":"2.363896092s","start":"2026-02-25T02:07:02.202401Z","end":"2026-02-25T02:07:04.566298Z","steps":["trace[1944755594] 'agreement among raft nodes before linearized reading' (duration: 2.363711636s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:04.566296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.363182931s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-25T02:07:04.566345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:02.202383Z","time spent":"2.363955413s","remote":"127.0.0.1:54150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T02:07:04.566334Z","caller":"traceutil/trace.go:171","msg":"trace[1662784743] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:518; }","duration":"674.069906ms","start":"2026-02-25T02:07:03.892238Z","end":"2026-02-25T02:07:04.566308Z","steps":["trace[1662784743] 'agreement among raft nodes before linearized reading' (duration: 673.86813ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:04.566382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.157834133s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.131\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-25T02:07:04.566466Z","caller":"traceutil/trace.go:171","msg":"trace[1785919649] range","detail":"{range_begin:/registry/masterleases/199.204.45.131; range_end:; response_count:1; response_revision:518; }","duration":"1.157982206s","start":"2026-02-25T02:07:03.408466Z","end":"2026-02-25T02:07:04.566448Z","steps":["trace[1785919649] 'agreement among raft nodes before linearized reading' (duration: 1.157813601s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:04.566443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:03.892217Z","time spent":"674.213641ms","remote":"127.0.0.1:53854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T02:07:04.566518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.324451539s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-25T02:07:04.566577Z","caller":"traceutil/trace.go:171","msg":"trace[343153071] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:518; }","duration":"2.324546221s","start":"2026-02-25T02:07:02.242017Z","end":"2026-02-25T02:07:04.566563Z","steps":["trace[343153071] 'agreement among raft nodes before linearized reading' (duration: 2.324416138s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:04.566626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:02.241998Z","time spent":"2.324616413s","remote":"127.0.0.1:54150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T02:07:04.566522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:03.408448Z","time spent":"1.158062359s","remote":"127.0.0.1:53908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.131\" "} {"level":"info","ts":"2026-02-25T02:07:04.566388Z","caller":"traceutil/trace.go:171","msg":"trace[518846023] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:518; }","duration":"2.363295505s","start":"2026-02-25T02:07:02.203064Z","end":"2026-02-25T02:07:04.56636Z","steps":["trace[518846023] 'agreement among raft nodes before linearized reading' (duration: 2.363036457s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:04.566806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:02.203057Z","time spent":"2.363735907s","remote":"127.0.0.1:54150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-25T02:07:04.964428Z","caller":"traceutil/trace.go:171","msg":"trace[501382916] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"393.787594ms","start":"2026-02-25T02:07:04.57057Z","end":"2026-02-25T02:07:04.964357Z","steps":["trace[501382916] 'process raft request' (duration: 393.613719ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:04.964615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:04.57055Z","time spent":"393.951008ms","remote":"127.0.0.1:54150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:07:04.964651Z","caller":"traceutil/trace.go:171","msg":"trace[1190186848] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"392.486646ms","start":"2026-02-25T02:07:04.572145Z","end":"2026-02-25T02:07:04.964632Z","steps":["trace[1190186848] 'process raft request' (duration: 392.173287ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:04.964758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:04.572132Z","time spent":"392.567169ms","remote":"127.0.0.1:54150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:07:04.964902Z","caller":"traceutil/trace.go:171","msg":"trace[424488595] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"388.958434ms","start":"2026-02-25T02:07:04.575934Z","end":"2026-02-25T02:07:04.964893Z","steps":["trace[424488595] 'process raft request' (duration: 388.597104ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:04.964954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:04.575919Z","time spent":"389.011696ms","remote":"127.0.0.1:54150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:07:04.967153Z","caller":"traceutil/trace.go:171","msg":"trace[352191906] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"336.951999ms","start":"2026-02-25T02:07:04.630191Z","end":"2026-02-25T02:07:04.967143Z","steps":["trace[352191906] 'process raft request' (duration: 336.849856ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:04.967213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:04.630171Z","time spent":"337.013061ms","remote":"127.0.0.1:53908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:07:07.905409Z","caller":"traceutil/trace.go:171","msg":"trace[760277257] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"679.828195ms","start":"2026-02-25T02:07:07.225546Z","end":"2026-02-25T02:07:07.905375Z","steps":["trace[760277257] 'process raft request' (duration: 679.617539ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:07.906152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:07.225528Z","time spent":"680.008211ms","remote":"127.0.0.1:53924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T02:07:07.911208Z","caller":"traceutil/trace.go:171","msg":"trace[1795473404] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"485.136931ms","start":"2026-02-25T02:07:07.426029Z","end":"2026-02-25T02:07:07.911166Z","steps":["trace[1795473404] 'process raft request' (duration: 484.74772ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:07.911427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:07.425993Z","time spent":"485.355407ms","remote":"127.0.0.1:53924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T02:07:07.911506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.287037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:07:07.91158Z","caller":"traceutil/trace.go:171","msg":"trace[984115498] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:561; }","duration":"287.4056ms","start":"2026-02-25T02:07:07.624155Z","end":"2026-02-25T02:07:07.911561Z","steps":["trace[984115498] 'agreement among raft nodes before linearized reading' (duration: 287.206035ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:07.91123Z","caller":"traceutil/trace.go:171","msg":"trace[432141289] linearizableReadLoop","detail":"{readStateIndex:583; appliedIndex:581; }","duration":"286.901136ms","start":"2026-02-25T02:07:07.624216Z","end":"2026-02-25T02:07:07.911117Z","steps":["trace[432141289] 'read index received' (duration: 280.957427ms)","trace[432141289] 'applied index is now lower than readState.Index' (duration: 5.940699ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:07:13.417523Z","caller":"traceutil/trace.go:171","msg":"trace[1902814732] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:656; }","duration":"165.559735ms","start":"2026-02-25T02:07:13.251943Z","end":"2026-02-25T02:07:13.417503Z","steps":["trace[1902814732] 'read index received' (duration: 144.646683ms)","trace[1902814732] 'applied index is now lower than readState.Index' (duration: 20.912322ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:07:13.417612Z","caller":"traceutil/trace.go:171","msg":"trace[1138307311] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"165.73856ms","start":"2026-02-25T02:07:13.251866Z","end":"2026-02-25T02:07:13.417605Z","steps":["trace[1138307311] 'process raft request' (duration: 144.809998ms)","trace[1138307311] 'compare' (duration: 20.749137ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:07:13.417777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.803351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:07:13.417802Z","caller":"traceutil/trace.go:171","msg":"trace[1364518910] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:633; }","duration":"165.857313ms","start":"2026-02-25T02:07:13.251939Z","end":"2026-02-25T02:07:13.417796Z","steps":["trace[1364518910] 'agreement among raft nodes before linearized reading' (duration: 165.820682ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:13.417845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.836763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:07:13.417923Z","caller":"traceutil/trace.go:171","msg":"trace[1527545144] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:633; }","duration":"165.930745ms","start":"2026-02-25T02:07:13.251973Z","end":"2026-02-25T02:07:13.417904Z","steps":["trace[1527545144] 'agreement among raft nodes before linearized reading' (duration: 165.818812ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:13.417944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.778151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:07:13.417962Z","caller":"traceutil/trace.go:171","msg":"trace[825871682] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:633; }","duration":"166.015287ms","start":"2026-02-25T02:07:13.251941Z","end":"2026-02-25T02:07:13.417956Z","steps":["trace[825871682] 'agreement among raft nodes before linearized reading' (duration: 165.75784ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:19.617095Z","caller":"traceutil/trace.go:171","msg":"trace[1390638355] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"148.271881ms","start":"2026-02-25T02:08:19.468807Z","end":"2026-02-25T02:08:19.617079Z","steps":["trace[1390638355] 'process raft request' (duration: 148.154689ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:19.698874Z","caller":"traceutil/trace.go:171","msg":"trace[1391057352] linearizableReadLoop","detail":"{readStateIndex:960; appliedIndex:957; }","duration":"225.828129ms","start":"2026-02-25T02:08:19.47302Z","end":"2026-02-25T02:08:19.698848Z","steps":["trace[1391057352] 'read index received' (duration: 143.894157ms)","trace[1391057352] 'applied index is now lower than readState.Index' (duration: 81.933092ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:08:19.698901Z","caller":"traceutil/trace.go:171","msg":"trace[1206300179] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"227.94039ms","start":"2026-02-25T02:08:19.470939Z","end":"2026-02-25T02:08:19.69888Z","steps":["trace[1206300179] 'process raft request' (duration: 226.959346ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:19.698912Z","caller":"traceutil/trace.go:171","msg":"trace[1505006783] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"227.813566ms","start":"2026-02-25T02:08:19.471084Z","end":"2026-02-25T02:08:19.698898Z","steps":["trace[1505006783] 'process raft request' (duration: 227.740574ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:19.699036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.001063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-25T02:08:19.699122Z","caller":"traceutil/trace.go:171","msg":"trace[200600205] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:919; }","duration":"226.080435ms","start":"2026-02-25T02:08:19.472977Z","end":"2026-02-25T02:08:19.699057Z","steps":["trace[200600205] 'agreement among raft nodes before linearized reading' (duration: 225.957122ms)"],"step_count":1}