{"level":"warn","ts":"2026-02-18T02:42:43.008434Z","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-18T02:42:43.008651Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.88: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.19.213.88:2380","--initial-cluster=instance=https://199.19.213.88:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.88:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.88: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-18T02:42:43.008781Z","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-18T02:42:43.008836Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.88:2380"]} {"level":"info","ts":"2026-02-18T02:42:43.008901Z","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-18T02:42:43.010695Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.88:2379"]} {"level":"info","ts":"2026-02-18T02:42:43.010835Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.88:2380"],"listen-peer-urls":["https://199.19.213.88:2380"],"advertise-client-urls":["https://199.19.213.88:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.88:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.88: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-18T02:42:43.104523Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"93.395679ms"} {"level":"info","ts":"2026-02-18T02:42:43.13545Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f209534991425602","cluster-id":"7e9462d56ed655d"} {"level":"info","ts":"2026-02-18T02:42:43.135514Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f209534991425602 switched to configuration voters=()"} {"level":"info","ts":"2026-02-18T02:42:43.13553Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f209534991425602 became follower at term 0"} {"level":"info","ts":"2026-02-18T02:42:43.135537Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f209534991425602 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-18T02:42:43.135543Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f209534991425602 became follower at term 1"} {"level":"info","ts":"2026-02-18T02:42:43.13557Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f209534991425602 switched to configuration voters=(17440562607403718146)"} {"level":"warn","ts":"2026-02-18T02:42:43.164398Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-18T02:42:43.169023Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-18T02:42:43.175318Z","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-18T02:42:43.182511Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f209534991425602","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-18T02:42:43.182611Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f209534991425602","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-18T02:42:43.182924Z","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-18T02:42:43.183011Z","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-18T02:42:43.183035Z","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-18T02:42:43.195841Z","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-18T02:42:43.195999Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f209534991425602","initial-advertise-peer-urls":["https://199.19.213.88:2380"],"listen-peer-urls":["https://199.19.213.88:2380"],"advertise-client-urls":["https://199.19.213.88:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.88:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-18T02:42:43.196016Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-18T02:42:43.196097Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.88:2380"} {"level":"info","ts":"2026-02-18T02:42:43.196105Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.88:2380"} {"level":"info","ts":"2026-02-18T02:42:43.199331Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f209534991425602 switched to configuration voters=(17440562607403718146)"} {"level":"info","ts":"2026-02-18T02:42:43.199518Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"7e9462d56ed655d","local-member-id":"f209534991425602","added-peer-id":"f209534991425602","added-peer-peer-urls":["https://199.19.213.88:2380"]} {"level":"info","ts":"2026-02-18T02:42:43.437678Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f209534991425602 is starting a new election at term 1"} {"level":"info","ts":"2026-02-18T02:42:43.437775Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f209534991425602 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-18T02:42:43.437833Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f209534991425602 received MsgPreVoteResp from f209534991425602 at term 1"} {"level":"info","ts":"2026-02-18T02:42:43.43786Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f209534991425602 became candidate at term 2"} {"level":"info","ts":"2026-02-18T02:42:43.437892Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f209534991425602 received MsgVoteResp from f209534991425602 at term 2"} {"level":"info","ts":"2026-02-18T02:42:43.437914Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f209534991425602 became leader at term 2"} {"level":"info","ts":"2026-02-18T02:42:43.437931Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f209534991425602 elected leader f209534991425602 at term 2"} {"level":"info","ts":"2026-02-18T02:42:43.446056Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f209534991425602","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.88:2379]}","request-path":"/0/members/f209534991425602/attributes","cluster-id":"7e9462d56ed655d","publish-timeout":"7s"} {"level":"info","ts":"2026-02-18T02:42:43.446211Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T02:42:43.446651Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T02:42:43.446816Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T02:42:43.451954Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-18T02:42:43.451972Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-18T02:42:43.460475Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.88:2379"} {"level":"info","ts":"2026-02-18T02:42:43.46078Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"7e9462d56ed655d","local-member-id":"f209534991425602","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T02:42:43.461557Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T02:42:43.461886Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T02:42:43.463919Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-18T02:42:46.280869Z","caller":"traceutil/trace.go:171","msg":"trace[1039465058] transaction","detail":"{read_only:false; response_revision:76; number_of_response:1; }","duration":"112.992589ms","start":"2026-02-18T02:42:46.167859Z","end":"2026-02-18T02:42:46.280852Z","steps":["trace[1039465058] 'process raft request' (duration: 34.326192ms)","trace[1039465058] 'compare' (duration: 78.412364ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:42:46.281228Z","caller":"traceutil/trace.go:171","msg":"trace[1176130841] linearizableReadLoop","detail":"{readStateIndex:80; appliedIndex:79; }","duration":"112.170133ms","start":"2026-02-18T02:42:46.16857Z","end":"2026-02-18T02:42:46.28074Z","steps":["trace[1176130841] 'read index received' (duration: 33.553437ms)","trace[1176130841] 'applied index is now lower than readState.Index' (duration: 78.615686ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:42:46.281387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.780398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-18T02:42:46.28142Z","caller":"traceutil/trace.go:171","msg":"trace[870657158] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:76; }","duration":"112.862628ms","start":"2026-02-18T02:42:46.16855Z","end":"2026-02-18T02:42:46.281412Z","steps":["trace[870657158] 'agreement among raft nodes before linearized reading' (duration: 112.744787ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:42:51.803908Z","caller":"traceutil/trace.go:171","msg":"trace[1255158525] transaction","detail":"{read_only:false; response_revision:333; number_of_response:1; }","duration":"427.292917ms","start":"2026-02-18T02:42:51.376582Z","end":"2026-02-18T02:42:51.803874Z","steps":["trace[1255158525] 'process raft request' (duration: 427.093785ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:42:51.804649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:42:51.376564Z","time spent":"427.444568ms","remote":"127.0.0.1:56628","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-18T02:42:52.611387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.202415ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T02:42:52.611529Z","caller":"traceutil/trace.go:171","msg":"trace[12684648] linearizableReadLoop","detail":"{readStateIndex:347; appliedIndex:345; }","duration":"781.973572ms","start":"2026-02-18T02:42:51.82954Z","end":"2026-02-18T02:42:52.611513Z","steps":["trace[12684648] 'read index received' (duration: 455.429424ms)","trace[12684648] 'applied index is now lower than readState.Index' (duration: 326.543428ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:42:52.611599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"782.072162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-18T02:42:52.611619Z","caller":"traceutil/trace.go:171","msg":"trace[511115465] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:335; }","duration":"782.140343ms","start":"2026-02-18T02:42:51.829471Z","end":"2026-02-18T02:42:52.611612Z","steps":["trace[511115465] 'agreement among raft nodes before linearized reading' (duration: 782.077722ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:42:52.611643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:42:51.829445Z","time spent":"782.191954ms","remote":"127.0.0.1:56628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-18T02:42:52.611845Z","caller":"traceutil/trace.go:171","msg":"trace[1804538045] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"1.214944935s","start":"2026-02-18T02:42:51.39689Z","end":"2026-02-18T02:42:52.611835Z","steps":["trace[1804538045] 'process raft request' (duration: 1.214566912s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:42:52.611884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:42:51.396873Z","time spent":"1.214992715s","remote":"127.0.0.1:56562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":190,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T02:42:52.611959Z","caller":"traceutil/trace.go:171","msg":"trace[168964494] transaction","detail":"{read_only:false; response_revision:334; number_of_response:1; }","duration":"1.234136175s","start":"2026-02-18T02:42:51.377794Z","end":"2026-02-18T02:42:52.61193Z","steps":["trace[168964494] 'process raft request' (duration: 907.218795ms)","trace[168964494] 'compare' (duration: 326.092304ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:42:52.612085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:42:51.377779Z","time spent":"1.234240977s","remote":"127.0.0.1:56628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:43:15.201929Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6197688036174676850,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T02:43:15.216245Z","caller":"traceutil/trace.go:171","msg":"trace[2066306528] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"621.659338ms","start":"2026-02-18T02:43:14.594567Z","end":"2026-02-18T02:43:15.216227Z","steps":["trace[2066306528] 'process raft request' (duration: 621.531647ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:15.216896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:43:14.594549Z","time spent":"622.285802ms","remote":"127.0.0.1:56394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T02:43:15.224951Z","caller":"traceutil/trace.go:171","msg":"trace[589154494] linearizableReadLoop","detail":"{readStateIndex:562; appliedIndex:559; }","duration":"523.388573ms","start":"2026-02-18T02:43:14.701548Z","end":"2026-02-18T02:43:15.224936Z","steps":["trace[589154494] 'read index received' (duration: 514.572004ms)","trace[589154494] 'applied index is now lower than readState.Index' (duration: 8.815999ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:43:15.225035Z","caller":"traceutil/trace.go:171","msg":"trace[1426161030] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"228.517627ms","start":"2026-02-18T02:43:14.996512Z","end":"2026-02-18T02:43:15.22503Z","steps":["trace[1426161030] 'process raft request' (duration: 228.402066ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:43:15.225144Z","caller":"traceutil/trace.go:171","msg":"trace[1042522798] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"430.820565ms","start":"2026-02-18T02:43:14.7943Z","end":"2026-02-18T02:43:15.22512Z","steps":["trace[1042522798] 'process raft request' (duration: 430.534353ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:15.225232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.671945ms","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-18T02:43:15.225255Z","caller":"traceutil/trace.go:171","msg":"trace[49411955] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:543; }","duration":"523.728095ms","start":"2026-02-18T02:43:14.701522Z","end":"2026-02-18T02:43:15.22525Z","steps":["trace[49411955] 'agreement among raft nodes before linearized reading' (duration: 523.659615ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:15.225275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:43:14.701509Z","time spent":"523.761875ms","remote":"127.0.0.1:56628","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-18T02:43:15.225381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.655977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-18T02:43:15.2254Z","caller":"traceutil/trace.go:171","msg":"trace[347457980] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:543; }","duration":"516.687707ms","start":"2026-02-18T02:43:14.708708Z","end":"2026-02-18T02:43:15.225396Z","steps":["trace[347457980] 'agreement among raft nodes before linearized reading' (duration: 516.644097ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:15.225412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:43:14.708701Z","time spent":"516.707818ms","remote":"127.0.0.1:56628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-18T02:43:15.225361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:43:14.794277Z","time spent":"430.952806ms","remote":"127.0.0.1:56394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T02:43:22.043215Z","caller":"traceutil/trace.go:171","msg":"trace[1116148963] linearizableReadLoop","detail":"{readStateIndex:620; appliedIndex:619; }","duration":"346.348273ms","start":"2026-02-18T02:43:21.696853Z","end":"2026-02-18T02:43:22.043202Z","steps":["trace[1116148963] 'read index received' (duration: 346.234082ms)","trace[1116148963] 'applied index is now lower than readState.Index' (duration: 113.001µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:43:22.043369Z","caller":"traceutil/trace.go:171","msg":"trace[895719504] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"485.005372ms","start":"2026-02-18T02:43:21.558357Z","end":"2026-02-18T02:43:22.043362Z","steps":["trace[895719504] 'process raft request' (duration: 484.7614ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:22.043433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:43:21.55831Z","time spent":"485.083262ms","remote":"127.0.0.1:56628","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-18T02:43:22.043546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.690785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:43:22.043562Z","caller":"traceutil/trace.go:171","msg":"trace[2011425769] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:600; }","duration":"346.730825ms","start":"2026-02-18T02:43:21.696827Z","end":"2026-02-18T02:43:22.043557Z","steps":["trace[2011425769] 'agreement among raft nodes before linearized reading' (duration: 346.693565ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:22.043575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:43:21.696815Z","time spent":"346.757385ms","remote":"127.0.0.1:56362","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-18T02:43:22.043704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.47627ms","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-18T02:43:22.043741Z","caller":"traceutil/trace.go:171","msg":"trace[1215414193] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:600; }","duration":"293.54815ms","start":"2026-02-18T02:43:21.750189Z","end":"2026-02-18T02:43:22.043737Z","steps":["trace[1215414193] 'agreement among raft nodes before linearized reading' (duration: 293.460669ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:22.590408Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6197688036174676994,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T02:43:22.820141Z","caller":"traceutil/trace.go:171","msg":"trace[707130964] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"773.104632ms","start":"2026-02-18T02:43:22.046995Z","end":"2026-02-18T02:43:22.820099Z","steps":["trace[707130964] 'process raft request' (duration: 678.983596ms)","trace[707130964] 'compare' (duration: 93.347661ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:43:22.820287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:43:22.046986Z","time spent":"773.222973ms","remote":"127.0.0.1:56628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:43:22.820519Z","caller":"traceutil/trace.go:171","msg":"trace[494299219] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"773.393024ms","start":"2026-02-18T02:43:22.047116Z","end":"2026-02-18T02:43:22.820509Z","steps":["trace[494299219] 'process raft request' (duration: 772.72052ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:22.820564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:43:22.047112Z","time spent":"773.430264ms","remote":"127.0.0.1:56628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:43:22.888503Z","caller":"traceutil/trace.go:171","msg":"trace[1185504580] linearizableReadLoop","detail":"{readStateIndex:623; appliedIndex:620; }","duration":"798.755908ms","start":"2026-02-18T02:43:22.089724Z","end":"2026-02-18T02:43:22.88848Z","steps":["trace[1185504580] 'read index received' (duration: 636.263691ms)","trace[1185504580] 'applied index is now lower than readState.Index' (duration: 162.491247ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:43:22.888603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"798.862069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:43:22.888647Z","caller":"traceutil/trace.go:171","msg":"trace[1332654642] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:602; }","duration":"798.942469ms","start":"2026-02-18T02:43:22.089696Z","end":"2026-02-18T02:43:22.888639Z","steps":["trace[1332654642] 'agreement among raft nodes before linearized reading' (duration: 798.859889ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:22.888672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:43:22.089675Z","time spent":"798.991249ms","remote":"127.0.0.1:56366","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-18T02:43:24.548499Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6197688036174677025,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T02:43:25.048828Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6197688036174677025,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T02:43:25.211437Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.164072445s","expected-duration":"1s"} {"level":"info","ts":"2026-02-18T02:43:25.211904Z","caller":"traceutil/trace.go:171","msg":"trace[237430152] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:628; }","duration":"1.164156015s","start":"2026-02-18T02:43:24.04771Z","end":"2026-02-18T02:43:25.211866Z","steps":["trace[237430152] 'read index received' (duration: 1.163819633s)","trace[237430152] 'applied index is now lower than readState.Index' (duration: 335.432µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:43:25.212019Z","caller":"traceutil/trace.go:171","msg":"trace[48763260] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"1.225951918s","start":"2026-02-18T02:43:23.986058Z","end":"2026-02-18T02:43:25.212009Z","steps":["trace[48763260] 'process raft request' (duration: 1.225582696s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:25.212124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:43:23.986042Z","time spent":"1.226005409s","remote":"127.0.0.1:56546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:43:25.212179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.121046739s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:43:25.21221Z","caller":"traceutil/trace.go:171","msg":"trace[658798181] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:608; }","duration":"1.121083929s","start":"2026-02-18T02:43:24.091114Z","end":"2026-02-18T02:43:25.212198Z","steps":["trace[658798181] 'agreement among raft nodes before linearized reading' (duration: 1.121025059s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:25.212231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:43:24.091078Z","time spent":"1.121149s","remote":"127.0.0.1:56362","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-18T02:43:25.212361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.164648537s","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-18T02:43:25.212374Z","caller":"traceutil/trace.go:171","msg":"trace[1589453226] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:608; }","duration":"1.164680047s","start":"2026-02-18T02:43:24.04769Z","end":"2026-02-18T02:43:25.21237Z","steps":["trace[1589453226] 'agreement among raft nodes before linearized reading' (duration: 1.164651217s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:25.212386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:43:24.047673Z","time spent":"1.164710679s","remote":"127.0.0.1:56628","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-18T02:43:25.212502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.070315ms","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-18T02:43:25.212548Z","caller":"traceutil/trace.go:171","msg":"trace[305416119] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:608; }","duration":"386.134875ms","start":"2026-02-18T02:43:24.826401Z","end":"2026-02-18T02:43:25.212536Z","steps":["trace[305416119] 'agreement among raft nodes before linearized reading' (duration: 386.042405ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:25.212559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.121215ms","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-02-18T02:43:25.212582Z","caller":"traceutil/trace.go:171","msg":"trace[2083285688] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:608; }","duration":"386.150416ms","start":"2026-02-18T02:43:24.826427Z","end":"2026-02-18T02:43:25.212578Z","steps":["trace[2083285688] 'agreement among raft nodes before linearized reading' (duration: 386.113805ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:25.212586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:43:24.826388Z","time spent":"386.190636ms","remote":"127.0.0.1:56628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-18T02:43:25.212595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:43:24.826425Z","time spent":"386.167376ms","remote":"127.0.0.1:56628","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-02-18T02:43:25.375878Z","caller":"traceutil/trace.go:171","msg":"trace[1979962387] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"154.410059ms","start":"2026-02-18T02:43:25.221451Z","end":"2026-02-18T02:43:25.375861Z","steps":["trace[1979962387] 'process raft request' (duration: 74.15418ms)","trace[1979962387] 'compare' (duration: 80.186448ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:43:25.376947Z","caller":"traceutil/trace.go:171","msg":"trace[1283121518] linearizableReadLoop","detail":"{readStateIndex:632; appliedIndex:629; }","duration":"149.480399ms","start":"2026-02-18T02:43:25.22746Z","end":"2026-02-18T02:43:25.37694Z","steps":["trace[1283121518] 'read index received' (duration: 68.152743ms)","trace[1283121518] 'applied index is now lower than readState.Index' (duration: 81.327116ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:43:25.377017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.546719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:43:25.377031Z","caller":"traceutil/trace.go:171","msg":"trace[220668335] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:611; }","duration":"149.596759ms","start":"2026-02-18T02:43:25.22743Z","end":"2026-02-18T02:43:25.377027Z","steps":["trace[220668335] 'agreement among raft nodes before linearized reading' (duration: 149.533559ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:43:25.377133Z","caller":"traceutil/trace.go:171","msg":"trace[29789550] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"155.412116ms","start":"2026-02-18T02:43:25.221715Z","end":"2026-02-18T02:43:25.377127Z","steps":["trace[29789550] 'process raft request' (duration: 155.177485ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:43:25.377192Z","caller":"traceutil/trace.go:171","msg":"trace[664976957] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"152.783969ms","start":"2026-02-18T02:43:25.224404Z","end":"2026-02-18T02:43:25.377188Z","steps":["trace[664976957] 'process raft request' (duration: 152.520358ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:43:38.211188Z","caller":"traceutil/trace.go:171","msg":"trace[1206100294] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"111.416349ms","start":"2026-02-18T02:43:38.099749Z","end":"2026-02-18T02:43:38.211165Z","steps":["trace[1206100294] 'process raft request' (duration: 111.268767ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:43:39.567356Z","caller":"traceutil/trace.go:171","msg":"trace[1753500969] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"121.998793ms","start":"2026-02-18T02:43:39.445341Z","end":"2026-02-18T02:43:39.56734Z","steps":["trace[1753500969] 'process raft request' (duration: 121.876352ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:39.56784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.967382ms","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-18T02:43:39.567864Z","caller":"traceutil/trace.go:171","msg":"trace[1309220939] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:742; }","duration":"115.018722ms","start":"2026-02-18T02:43:39.452839Z","end":"2026-02-18T02:43:39.567858Z","steps":["trace[1309220939] 'agreement among raft nodes before linearized reading' (duration: 114.925192ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:43:39.567542Z","caller":"traceutil/trace.go:171","msg":"trace[1368775467] linearizableReadLoop","detail":"{readStateIndex:767; appliedIndex:766; }","duration":"114.652449ms","start":"2026-02-18T02:43:39.45286Z","end":"2026-02-18T02:43:39.567512Z","steps":["trace[1368775467] 'read index received' (duration: 114.274316ms)","trace[1368775467] 'applied index is now lower than readState.Index' (duration: 375.643µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:43:39.568799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.143564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-18T02:43:39.56882Z","caller":"traceutil/trace.go:171","msg":"trace[441572722] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:742; }","duration":"115.176564ms","start":"2026-02-18T02:43:39.453638Z","end":"2026-02-18T02:43:39.568814Z","steps":["trace[441572722] 'agreement among raft nodes before linearized reading' (duration: 115.099724ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:43:45.73264Z","caller":"traceutil/trace.go:171","msg":"trace[1713026726] linearizableReadLoop","detail":"{readStateIndex:791; appliedIndex:790; }","duration":"129.766877ms","start":"2026-02-18T02:43:45.602858Z","end":"2026-02-18T02:43:45.732625Z","steps":["trace[1713026726] 'read index received' (duration: 129.652143ms)","trace[1713026726] 'applied index is now lower than readState.Index' (duration: 113.964µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:43:45.73273Z","caller":"traceutil/trace.go:171","msg":"trace[29370829] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"137.302117ms","start":"2026-02-18T02:43:45.595422Z","end":"2026-02-18T02:43:45.732724Z","steps":["trace[29370829] 'process raft request' (duration: 137.092832ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:45.732893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.086192ms","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-18T02:43:45.732941Z","caller":"traceutil/trace.go:171","msg":"trace[671433059] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:765; }","duration":"129.152883ms","start":"2026-02-18T02:43:45.603776Z","end":"2026-02-18T02:43:45.732929Z","steps":["trace[671433059] 'agreement among raft nodes before linearized reading' (duration: 129.02012ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:43:45.733104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.231026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-18T02:43:45.733135Z","caller":"traceutil/trace.go:171","msg":"trace[32257137] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:765; }","duration":"130.278937ms","start":"2026-02-18T02:43:45.602846Z","end":"2026-02-18T02:43:45.733125Z","steps":["trace[32257137] 'agreement among raft nodes before linearized reading' (duration: 130.187875ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:43:45.849639Z","caller":"traceutil/trace.go:171","msg":"trace[1564437487] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"112.64455ms","start":"2026-02-18T02:43:45.736975Z","end":"2026-02-18T02:43:45.84962Z","steps":["trace[1564437487] 'process raft request' (duration: 86.37689ms)","trace[1564437487] 'compare' (duration: 26.189019ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:43:45.850992Z","caller":"traceutil/trace.go:171","msg":"trace[610942754] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"111.743162ms","start":"2026-02-18T02:43:45.73924Z","end":"2026-02-18T02:43:45.850983Z","steps":["trace[610942754] 'process raft request' (duration: 111.715202ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:43:45.851162Z","caller":"traceutil/trace.go:171","msg":"trace[1461143662] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"114.055141ms","start":"2026-02-18T02:43:45.737101Z","end":"2026-02-18T02:43:45.851156Z","steps":["trace[1461143662] 'process raft request' (duration: 113.780765ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:43:45.851227Z","caller":"traceutil/trace.go:171","msg":"trace[1295124525] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"112.034708ms","start":"2026-02-18T02:43:45.739188Z","end":"2026-02-18T02:43:45.851223Z","steps":["trace[1295124525] 'process raft request' (duration: 111.740202ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:44:27.266988Z","caller":"traceutil/trace.go:171","msg":"trace[393656881] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"171.728951ms","start":"2026-02-18T02:44:27.095233Z","end":"2026-02-18T02:44:27.266962Z","steps":["trace[393656881] 'process raft request' (duration: 171.236614ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:44:28.000361Z","caller":"traceutil/trace.go:171","msg":"trace[572546491] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"103.326779ms","start":"2026-02-18T02:44:27.896979Z","end":"2026-02-18T02:44:28.000305Z","steps":["trace[572546491] 'process raft request' (duration: 103.205767ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:44:28.294716Z","caller":"traceutil/trace.go:171","msg":"trace[1105945673] linearizableReadLoop","detail":"{readStateIndex:928; appliedIndex:927; }","duration":"217.521069ms","start":"2026-02-18T02:44:28.077164Z","end":"2026-02-18T02:44:28.294685Z","steps":["trace[1105945673] 'read index received' (duration: 58.790616ms)","trace[1105945673] 'applied index is now lower than readState.Index' (duration: 158.729143ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:44:28.295118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.929714ms","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-02-18T02:44:28.295196Z","caller":"traceutil/trace.go:171","msg":"trace[436677081] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:893; }","duration":"218.015566ms","start":"2026-02-18T02:44:28.077161Z","end":"2026-02-18T02:44:28.295176Z","steps":["trace[436677081] 'agreement among raft nodes before linearized reading' (duration: 217.777162ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:44:28.295245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.564854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T02:44:28.295307Z","caller":"traceutil/trace.go:171","msg":"trace[1934300244] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:893; }","duration":"204.658125ms","start":"2026-02-18T02:44:28.090628Z","end":"2026-02-18T02:44:28.295286Z","steps":["trace[1934300244] 'agreement among raft nodes before linearized reading' (duration: 204.551784ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:44:28.295411Z","caller":"traceutil/trace.go:171","msg":"trace[37485886] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"221.267373ms","start":"2026-02-18T02:44:28.074128Z","end":"2026-02-18T02:44:28.295395Z","steps":["trace[37485886] 'process raft request' (duration: 140.243068ms)","trace[37485886] 'compare' (duration: 80.141862ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:44:28.295666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.100136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-18T02:44:28.295719Z","caller":"traceutil/trace.go:171","msg":"trace[2021572943] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:893; }","duration":"172.183248ms","start":"2026-02-18T02:44:28.12352Z","end":"2026-02-18T02:44:28.295704Z","steps":["trace[2021572943] 'agreement among raft nodes before linearized reading' (duration: 172.008095ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:44:28.694184Z","caller":"traceutil/trace.go:171","msg":"trace[792459137] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"390.64664ms","start":"2026-02-18T02:44:28.303514Z","end":"2026-02-18T02:44:28.694161Z","steps":["trace[792459137] 'process raft request' (duration: 336.314319ms)","trace[792459137] 'compare' (duration: 54.182829ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T02:44:28.694309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:44:28.303506Z","time spent":"390.727302ms","remote":"127.0.0.1:56628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T02:44:30.1485Z","caller":"traceutil/trace.go:171","msg":"trace[1271593151] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"142.269024ms","start":"2026-02-18T02:44:30.006216Z","end":"2026-02-18T02:44:30.148485Z","steps":["trace[1271593151] 'process raft request' (duration: 142.079192ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:44:30.815295Z","caller":"traceutil/trace.go:171","msg":"trace[1481483059] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"115.372584ms","start":"2026-02-18T02:44:30.699905Z","end":"2026-02-18T02:44:30.815277Z","steps":["trace[1481483059] 'process raft request' (duration: 115.276542ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T02:44:31.546504Z","caller":"traceutil/trace.go:171","msg":"trace[626944279] linearizableReadLoop","detail":"{readStateIndex:936; appliedIndex:935; }","duration":"162.074088ms","start":"2026-02-18T02:44:31.384409Z","end":"2026-02-18T02:44:31.546484Z","steps":["trace[626944279] 'read index received' (duration: 161.912056ms)","trace[626944279] 'applied index is now lower than readState.Index' (duration: 161.002µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T02:44:31.54724Z","caller":"traceutil/trace.go:171","msg":"trace[2058942245] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"334.947948ms","start":"2026-02-18T02:44:31.212269Z","end":"2026-02-18T02:44:31.547217Z","steps":["trace[2058942245] 'process raft request' (duration: 334.071796ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:44:31.547329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.903809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T02:44:31.548877Z","caller":"traceutil/trace.go:171","msg":"trace[1190610088] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:901; }","duration":"164.493862ms","start":"2026-02-18T02:44:31.384371Z","end":"2026-02-18T02:44:31.548865Z","steps":["trace[1190610088] 'agreement among raft nodes before linearized reading' (duration: 162.91013ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:44:31.549522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:44:31.212252Z","time spent":"336.854085ms","remote":"127.0.0.1:56628","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-18T02:44:50.905231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.109395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-18T02:44:50.905301Z","caller":"traceutil/trace.go:171","msg":"trace[492949047] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:991; }","duration":"360.201097ms","start":"2026-02-18T02:44:50.545083Z","end":"2026-02-18T02:44:50.905284Z","steps":["trace[492949047] 'range keys from in-memory index tree' (duration: 359.963864ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:44:50.905342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:44:50.545077Z","time spent":"360.256368ms","remote":"127.0.0.1:56628","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-18T02:44:50.905579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.543471ms","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-18T02:44:50.905606Z","caller":"traceutil/trace.go:171","msg":"trace[33436688] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:991; }","duration":"360.601982ms","start":"2026-02-18T02:44:50.544996Z","end":"2026-02-18T02:44:50.905598Z","steps":["trace[33436688] 'range keys from in-memory index tree' (duration: 360.376429ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:44:50.905633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:44:50.544978Z","time spent":"360.647602ms","remote":"127.0.0.1:56628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-18T02:44:51.643712Z","caller":"traceutil/trace.go:171","msg":"trace[662012511] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"481.161776ms","start":"2026-02-18T02:44:51.162535Z","end":"2026-02-18T02:44:51.643697Z","steps":["trace[662012511] 'process raft request' (duration: 480.7629ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:44:51.643814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T02:44:51.162515Z","time spent":"481.238096ms","remote":"127.0.0.1:56540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5217,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T02:45:02.635404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.069782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:disruption-controller\" ","response":"range_response_count:1 size:759"} {"level":"info","ts":"2026-02-18T02:45:02.635483Z","caller":"traceutil/trace.go:171","msg":"trace[1909544521] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:disruption-controller; range_end:; response_count:1; response_revision:1030; }","duration":"142.177133ms","start":"2026-02-18T02:45:02.493285Z","end":"2026-02-18T02:45:02.635462Z","steps":["trace[1909544521] 'range keys from in-memory index tree' (duration: 141.983821ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T02:45:02.635449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.444815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:service-account-controller\" ","response":"range_response_count:1 size:677"} {"level":"info","ts":"2026-02-18T02:45:02.635555Z","caller":"traceutil/trace.go:171","msg":"trace[49156104] range","detail":"{range_begin:/registry/clusterroles/system:controller:service-account-controller; range_end:; response_count:1; response_revision:1030; }","duration":"113.580638ms","start":"2026-02-18T02:45:02.521955Z","end":"2026-02-18T02:45:02.635535Z","steps":["trace[49156104] 'range keys from in-memory index tree' (duration: 113.343935ms)"],"step_count":1}