{"level":"warn","ts":"2026-02-01T18:59:27.542884Z","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-01T18:59:27.542992Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.52: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://162.253.55.52:2380","--initial-cluster=instance=https://162.253.55.52:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.52:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.52: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-01T18:59:27.543115Z","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-01T18:59:27.543127Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.52:2380"]} {"level":"info","ts":"2026-02-01T18:59:27.543162Z","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-01T18:59:27.543955Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.52:2379"]} {"level":"info","ts":"2026-02-01T18:59:27.544052Z","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://162.253.55.52:2380"],"listen-peer-urls":["https://162.253.55.52:2380"],"advertise-client-urls":["https://162.253.55.52:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.52:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.52: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-01T18:59:27.570169Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"25.898263ms"} {"level":"info","ts":"2026-02-01T18:59:27.600013Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b11e7fc5714be64b","cluster-id":"9349e4f32a52a90a"} {"level":"info","ts":"2026-02-01T18:59:27.600133Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b switched to configuration voters=()"} {"level":"info","ts":"2026-02-01T18:59:27.600163Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became follower at term 0"} {"level":"info","ts":"2026-02-01T18:59:27.600183Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b11e7fc5714be64b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-01T18:59:27.6002Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became follower at term 1"} {"level":"info","ts":"2026-02-01T18:59:27.600258Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b switched to configuration voters=(12762778880000648779)"} {"level":"warn","ts":"2026-02-01T18:59:27.610998Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-01T18:59:27.612576Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-01T18:59:27.6137Z","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-01T18:59:27.617022Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b11e7fc5714be64b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-01T18:59:27.621355Z","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-01T18:59:27.6214Z","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-01T18:59:27.62141Z","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-01T18:59:27.621717Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b11e7fc5714be64b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-01T18:59:27.622526Z","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-01T18:59:27.622703Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.52:2380"} {"level":"info","ts":"2026-02-01T18:59:27.622734Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.52:2380"} {"level":"info","ts":"2026-02-01T18:59:27.622866Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b11e7fc5714be64b","initial-advertise-peer-urls":["https://162.253.55.52:2380"],"listen-peer-urls":["https://162.253.55.52:2380"],"advertise-client-urls":["https://162.253.55.52:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.52:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-01T18:59:27.622901Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-01T18:59:27.624297Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b switched to configuration voters=(12762778880000648779)"} {"level":"info","ts":"2026-02-01T18:59:27.624365Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9349e4f32a52a90a","local-member-id":"b11e7fc5714be64b","added-peer-id":"b11e7fc5714be64b","added-peer-peer-urls":["https://162.253.55.52:2380"]} {"level":"info","ts":"2026-02-01T18:59:28.001248Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b is starting a new election at term 1"} {"level":"info","ts":"2026-02-01T18:59:28.001288Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became pre-candidate at term 1"} {"level":"info","ts":"2026-02-01T18:59:28.001308Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b received MsgPreVoteResp from b11e7fc5714be64b at term 1"} {"level":"info","ts":"2026-02-01T18:59:28.001319Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became candidate at term 2"} {"level":"info","ts":"2026-02-01T18:59:28.001325Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b received MsgVoteResp from b11e7fc5714be64b at term 2"} {"level":"info","ts":"2026-02-01T18:59:28.00141Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became leader at term 2"} {"level":"info","ts":"2026-02-01T18:59:28.001453Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b11e7fc5714be64b elected leader b11e7fc5714be64b at term 2"} {"level":"info","ts":"2026-02-01T18:59:28.002832Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b11e7fc5714be64b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.52:2379]}","request-path":"/0/members/b11e7fc5714be64b/attributes","cluster-id":"9349e4f32a52a90a","publish-timeout":"7s"} {"level":"info","ts":"2026-02-01T18:59:28.002935Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-01T18:59:28.003531Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-01T18:59:28.010819Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.52:2379"} {"level":"info","ts":"2026-02-01T18:59:28.011089Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-01T18:59:28.013408Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-01T18:59:28.023144Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-01T18:59:28.023227Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-01T18:59:28.042028Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9349e4f32a52a90a","local-member-id":"b11e7fc5714be64b","cluster-version":"3.5"} {"level":"info","ts":"2026-02-01T18:59:28.042302Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-01T18:59:28.042414Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-01T18:59:30.442337Z","caller":"traceutil/trace.go:171","msg":"trace[391652605] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"143.583388ms","start":"2026-02-01T18:59:30.298711Z","end":"2026-02-01T18:59:30.442294Z","steps":["trace[391652605] 'process raft request' (duration: 61.801657ms)","trace[391652605] 'compare' (duration: 81.628677ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:59:30.457698Z","caller":"traceutil/trace.go:171","msg":"trace[827919027] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"157.787768ms","start":"2026-02-01T18:59:30.299888Z","end":"2026-02-01T18:59:30.457676Z","steps":["trace[827919027] 'process raft request' (duration: 157.645504ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:59:30.458787Z","caller":"traceutil/trace.go:171","msg":"trace[1046483560] linearizableReadLoop","detail":"{readStateIndex:12; appliedIndex:10; }","duration":"158.735071ms","start":"2026-02-01T18:59:30.30004Z","end":"2026-02-01T18:59:30.458775Z","steps":["trace[1046483560] 'read index received' (duration: 60.485894ms)","trace[1046483560] 'applied index is now lower than readState.Index' (duration: 98.248237ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:59:30.460822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.750662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-01T18:59:30.475937Z","caller":"traceutil/trace.go:171","msg":"trace[340677410] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:0; response_revision:19; }","duration":"175.889743ms","start":"2026-02-01T18:59:30.300029Z","end":"2026-02-01T18:59:30.475919Z","steps":["trace[340677410] 'agreement among raft nodes before linearized reading' (duration: 160.681781ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:59:30.460872Z","caller":"traceutil/trace.go:171","msg":"trace[1602227327] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"150.384931ms","start":"2026-02-01T18:59:30.310476Z","end":"2026-02-01T18:59:30.460861Z","steps":["trace[1602227327] 'process raft request' (duration: 147.137679ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:59:30.460998Z","caller":"traceutil/trace.go:171","msg":"trace[2147197922] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"135.487626ms","start":"2026-02-01T18:59:30.325502Z","end":"2026-02-01T18:59:30.46099Z","steps":["trace[2147197922] 'process raft request' (duration: 132.146352ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:59:30.46108Z","caller":"traceutil/trace.go:171","msg":"trace[1749050287] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"135.405643ms","start":"2026-02-01T18:59:30.325668Z","end":"2026-02-01T18:59:30.461073Z","steps":["trace[1749050287] 'process raft request' (duration: 132.863559ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:59:30.461104Z","caller":"traceutil/trace.go:171","msg":"trace[973376403] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"134.646403ms","start":"2026-02-01T18:59:30.326452Z","end":"2026-02-01T18:59:30.461099Z","steps":["trace[973376403] 'process raft request' (duration: 132.11204ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:59:30.461128Z","caller":"traceutil/trace.go:171","msg":"trace[909096117] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"134.348326ms","start":"2026-02-01T18:59:30.326773Z","end":"2026-02-01T18:59:30.461121Z","steps":["trace[909096117] 'process raft request' (duration: 131.818272ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:59:30.461149Z","caller":"traceutil/trace.go:171","msg":"trace[55173917] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"133.245769ms","start":"2026-02-01T18:59:30.327898Z","end":"2026-02-01T18:59:30.461144Z","steps":["trace[55173917] 'process raft request' (duration: 130.719265ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:59:30.461177Z","caller":"traceutil/trace.go:171","msg":"trace[1702148341] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"132.627482ms","start":"2026-02-01T18:59:30.328539Z","end":"2026-02-01T18:59:30.461167Z","steps":["trace[1702148341] 'process raft request' (duration: 130.101738ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:59:30.461253Z","caller":"traceutil/trace.go:171","msg":"trace[2030808181] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"132.600322ms","start":"2026-02-01T18:59:30.328645Z","end":"2026-02-01T18:59:30.461246Z","steps":["trace[2030808181] 'process raft request' (duration: 130.018027ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:59:30.461328Z","caller":"traceutil/trace.go:171","msg":"trace[607591468] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"122.59725ms","start":"2026-02-01T18:59:30.338723Z","end":"2026-02-01T18:59:30.461321Z","steps":["trace[607591468] 'process raft request' (duration: 119.969734ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:59:30.4614Z","caller":"traceutil/trace.go:171","msg":"trace[752499789] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"116.580359ms","start":"2026-02-01T18:59:30.344812Z","end":"2026-02-01T18:59:30.461392Z","steps":["trace[752499789] 'process raft request' (duration: 113.910132ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:59:30.46143Z","caller":"traceutil/trace.go:171","msg":"trace[1972010362] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"115.425108ms","start":"2026-02-01T18:59:30.345999Z","end":"2026-02-01T18:59:30.461424Z","steps":["trace[1972010362] 'process raft request' (duration: 112.746711ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:59:30.479627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.400314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-01T18:59:30.479836Z","caller":"traceutil/trace.go:171","msg":"trace[637520059] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:20; }","duration":"145.464306ms","start":"2026-02-01T18:59:30.33419Z","end":"2026-02-01T18:59:30.479654Z","steps":["trace[637520059] 'agreement among raft nodes before linearized reading' (duration: 145.176148ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:59:30.674377Z","caller":"traceutil/trace.go:171","msg":"trace[555202099] linearizableReadLoop","detail":"{readStateIndex:36; appliedIndex:35; }","duration":"128.023737ms","start":"2026-02-01T18:59:30.54633Z","end":"2026-02-01T18:59:30.674354Z","steps":["trace[555202099] 'read index received' (duration: 54.678799ms)","trace[555202099] 'applied index is now lower than readState.Index' (duration: 73.343888ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:59:30.674597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.240763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/default/\" range_end:\"/registry/resourcequotas/default0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-01T18:59:30.674686Z","caller":"traceutil/trace.go:171","msg":"trace[673811032] range","detail":"{range_begin:/registry/resourcequotas/default/; range_end:/registry/resourcequotas/default0; response_count:0; response_revision:32; }","duration":"128.366205ms","start":"2026-02-01T18:59:30.546307Z","end":"2026-02-01T18:59:30.674673Z","steps":["trace[673811032] 'agreement among raft nodes before linearized reading' (duration: 128.203991ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:59:30.674898Z","caller":"traceutil/trace.go:171","msg":"trace[528999318] transaction","detail":"{read_only:false; response_revision:32; number_of_response:1; }","duration":"135.534065ms","start":"2026-02-01T18:59:30.539336Z","end":"2026-02-01T18:59:30.67487Z","steps":["trace[528999318] 'process raft request' (duration: 61.568821ms)","trace[528999318] 'compare' (duration: 73.339638ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:59:30.675411Z","caller":"traceutil/trace.go:171","msg":"trace[2101453946] transaction","detail":"{read_only:false; response_revision:40; number_of_response:1; }","duration":"126.99649ms","start":"2026-02-01T18:59:30.548403Z","end":"2026-02-01T18:59:30.675399Z","steps":["trace[2101453946] 'process raft request' (duration: 126.965609ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:59:30.675697Z","caller":"traceutil/trace.go:171","msg":"trace[1543296785] transaction","detail":"{read_only:false; response_revision:33; number_of_response:1; }","duration":"129.316139ms","start":"2026-02-01T18:59:30.546371Z","end":"2026-02-01T18:59:30.675687Z","steps":["trace[1543296785] 'process raft request' (duration: 128.730745ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:59:30.675821Z","caller":"traceutil/trace.go:171","msg":"trace[1905589411] transaction","detail":"{read_only:false; response_revision:34; number_of_response:1; }","duration":"129.403112ms","start":"2026-02-01T18:59:30.546409Z","end":"2026-02-01T18:59:30.675812Z","steps":["trace[1905589411] 'process raft request' (duration: 128.775986ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:59:30.676024Z","caller":"traceutil/trace.go:171","msg":"trace[898531230] transaction","detail":"{read_only:false; response_revision:35; number_of_response:1; }","duration":"129.569206ms","start":"2026-02-01T18:59:30.546442Z","end":"2026-02-01T18:59:30.676011Z","steps":["trace[898531230] 'process raft request' (duration: 128.775136ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:59:30.676139Z","caller":"traceutil/trace.go:171","msg":"trace[1188146532] transaction","detail":"{read_only:false; response_revision:36; number_of_response:1; }","duration":"129.658268ms","start":"2026-02-01T18:59:30.546471Z","end":"2026-02-01T18:59:30.676129Z","steps":["trace[1188146532] 'process raft request' (duration: 128.799206ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:59:30.676233Z","caller":"traceutil/trace.go:171","msg":"trace[2062573033] transaction","detail":"{read_only:false; response_revision:37; number_of_response:1; }","duration":"129.686159ms","start":"2026-02-01T18:59:30.54654Z","end":"2026-02-01T18:59:30.676226Z","steps":["trace[2062573033] 'process raft request' (duration: 128.754365ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:59:30.676343Z","caller":"traceutil/trace.go:171","msg":"trace[1912516809] transaction","detail":"{read_only:false; response_revision:38; number_of_response:1; }","duration":"129.771631ms","start":"2026-02-01T18:59:30.546564Z","end":"2026-02-01T18:59:30.676335Z","steps":["trace[1912516809] 'process raft request' (duration: 128.753395ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:59:30.676488Z","caller":"traceutil/trace.go:171","msg":"trace[900421250] transaction","detail":"{read_only:false; response_revision:39; number_of_response:1; }","duration":"127.981666ms","start":"2026-02-01T18:59:30.548498Z","end":"2026-02-01T18:59:30.67648Z","steps":["trace[900421250] 'process raft request' (duration: 126.840137ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:59:31.111084Z","caller":"traceutil/trace.go:171","msg":"trace[1012300541] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"115.119531ms","start":"2026-02-01T18:59:30.99591Z","end":"2026-02-01T18:59:31.111029Z","steps":["trace[1012300541] 'process raft request' (duration: 114.386833ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:59:44.578856Z","caller":"traceutil/trace.go:171","msg":"trace[203736249] linearizableReadLoop","detail":"{readStateIndex:423; appliedIndex:422; }","duration":"135.912877ms","start":"2026-02-01T18:59:44.44292Z","end":"2026-02-01T18:59:44.578833Z","steps":["trace[203736249] 'read index received' (duration: 135.690741ms)","trace[203736249] 'applied index is now lower than readState.Index' (duration: 221.276µs)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:59:44.579138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.107562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4115"} {"level":"info","ts":"2026-02-01T18:59:44.579184Z","caller":"traceutil/trace.go:171","msg":"trace[2056726743] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:411; }","duration":"136.256856ms","start":"2026-02-01T18:59:44.442915Z","end":"2026-02-01T18:59:44.579172Z","steps":["trace[2056726743] 'agreement among raft nodes before linearized reading' (duration: 136.006839ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:59:44.579459Z","caller":"traceutil/trace.go:171","msg":"trace[2035584076] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"596.576573ms","start":"2026-02-01T18:59:43.982854Z","end":"2026-02-01T18:59:44.579431Z","steps":["trace[2035584076] 'process raft request' (duration: 595.809834ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:59:44.581232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:59:43.982832Z","time spent":"596.686355ms","remote":"127.0.0.1:35896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4241,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:59:44.732112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.598011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4725"} {"level":"info","ts":"2026-02-01T18:59:44.732208Z","caller":"traceutil/trace.go:171","msg":"trace[1713638967] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:411; }","duration":"142.771055ms","start":"2026-02-01T18:59:44.58942Z","end":"2026-02-01T18:59:44.732191Z","steps":["trace[1713638967] 'range keys from in-memory index tree' (duration: 142.529659ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:59:44.734581Z","caller":"traceutil/trace.go:171","msg":"trace[2088657173] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"142.378366ms","start":"2026-02-01T18:59:44.592188Z","end":"2026-02-01T18:59:44.734566Z","steps":["trace[2088657173] 'process raft request' (duration: 142.12683ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:59:45.708961Z","caller":"traceutil/trace.go:171","msg":"trace[49418814] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"163.112502ms","start":"2026-02-01T18:59:45.545816Z","end":"2026-02-01T18:59:45.708928Z","steps":["trace[49418814] 'process raft request' (duration: 162.912167ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:59:50.970086Z","caller":"traceutil/trace.go:171","msg":"trace[879740530] linearizableReadLoop","detail":"{readStateIndex:475; appliedIndex:474; }","duration":"130.364843ms","start":"2026-02-01T18:59:50.839701Z","end":"2026-02-01T18:59:50.970066Z","steps":["trace[879740530] 'read index received' (duration: 130.277321ms)","trace[879740530] 'applied index is now lower than readState.Index' (duration: 86.542µs)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:59:50.970221Z","caller":"traceutil/trace.go:171","msg":"trace[1493415510] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"131.336427ms","start":"2026-02-01T18:59:50.838848Z","end":"2026-02-01T18:59:50.970184Z","steps":["trace[1493415510] 'process raft request' (duration: 131.05693ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:59:50.970336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.618099ms","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-01T18:59:50.970386Z","caller":"traceutil/trace.go:171","msg":"trace[892406203] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:461; }","duration":"130.694521ms","start":"2026-02-01T18:59:50.839679Z","end":"2026-02-01T18:59:50.970373Z","steps":["trace[892406203] 'agreement among raft nodes before linearized reading' (duration: 130.612229ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:59:50.97459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.489273ms","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-01T18:59:50.974678Z","caller":"traceutil/trace.go:171","msg":"trace[1732535465] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:462; }","duration":"134.594726ms","start":"2026-02-01T18:59:50.840069Z","end":"2026-02-01T18:59:50.974664Z","steps":["trace[1732535465] 'agreement among raft nodes before linearized reading' (duration: 134.423551ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:00:01.496073Z","caller":"traceutil/trace.go:171","msg":"trace[682933560] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"479.2735ms","start":"2026-02-01T19:00:01.016774Z","end":"2026-02-01T19:00:01.496048Z","steps":["trace[682933560] 'process raft request' (duration: 479.185038ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:01.49617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:00:01.016746Z","time spent":"479.373252ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T19:00:01.496388Z","caller":"traceutil/trace.go:171","msg":"trace[2116531014] linearizableReadLoop","detail":"{readStateIndex:536; appliedIndex:536; }","duration":"470.542836ms","start":"2026-02-01T19:00:01.025839Z","end":"2026-02-01T19:00:01.496382Z","steps":["trace[2116531014] 'read index received' (duration: 470.540016ms)","trace[2116531014] 'applied index is now lower than readState.Index' (duration: 2.44µs)"],"step_count":2} {"level":"warn","ts":"2026-02-01T19:00:01.496471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.630959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-01T19:00:01.496486Z","caller":"traceutil/trace.go:171","msg":"trace[401174390] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:520; }","duration":"470.66512ms","start":"2026-02-01T19:00:01.025816Z","end":"2026-02-01T19:00:01.496481Z","steps":["trace[401174390] 'agreement among raft nodes before linearized reading' (duration: 470.601048ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:01.4965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:00:01.025792Z","time spent":"470.704862ms","remote":"127.0.0.1:35966","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-01T19:00:01.564313Z","caller":"traceutil/trace.go:171","msg":"trace[1680684998] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"286.236657ms","start":"2026-02-01T19:00:01.278057Z","end":"2026-02-01T19:00:01.564294Z","steps":["trace[1680684998] 'process raft request' (duration: 286.131405ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:01.564701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.302717ms","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-01T19:00:01.564736Z","caller":"traceutil/trace.go:171","msg":"trace[890908878] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:521; }","duration":"535.359329ms","start":"2026-02-01T19:00:01.029369Z","end":"2026-02-01T19:00:01.564728Z","steps":["trace[890908878] 'agreement among raft nodes before linearized reading' (duration: 535.265517ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:01.564757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:00:01.029362Z","time spent":"535.389219ms","remote":"127.0.0.1:35966","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-01T19:00:01.564859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.423462ms","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-01T19:00:01.564876Z","caller":"traceutil/trace.go:171","msg":"trace[1546227205] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:521; }","duration":"456.462992ms","start":"2026-02-01T19:00:01.108408Z","end":"2026-02-01T19:00:01.564871Z","steps":["trace[1546227205] 'agreement among raft nodes before linearized reading' (duration: 456.411431ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:01.564891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:00:01.108316Z","time spent":"456.572286ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-01T19:00:05.872111Z","caller":"traceutil/trace.go:171","msg":"trace[1044861381] linearizableReadLoop","detail":"{readStateIndex:548; appliedIndex:547; }","duration":"279.753408ms","start":"2026-02-01T19:00:05.592337Z","end":"2026-02-01T19:00:05.87209Z","steps":["trace[1044861381] 'read index received' (duration: 279.579854ms)","trace[1044861381] 'applied index is now lower than readState.Index' (duration: 172.784µs)"],"step_count":2} {"level":"info","ts":"2026-02-01T19:00:05.872218Z","caller":"traceutil/trace.go:171","msg":"trace[851859282] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"363.475373ms","start":"2026-02-01T19:00:05.508734Z","end":"2026-02-01T19:00:05.872209Z","steps":["trace[851859282] 'process raft request' (duration: 363.234866ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:05.872325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:00:05.508699Z","time spent":"363.544525ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T19:00:05.872362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.564234ms","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-01T19:00:05.872408Z","caller":"traceutil/trace.go:171","msg":"trace[1773390923] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:531; }","duration":"279.643147ms","start":"2026-02-01T19:00:05.592752Z","end":"2026-02-01T19:00:05.872395Z","steps":["trace[1773390923] 'agreement among raft nodes before linearized reading' (duration: 279.526854ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:05.8725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.161539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-01T19:00:05.872511Z","caller":"traceutil/trace.go:171","msg":"trace[169113195] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:531; }","duration":"280.196629ms","start":"2026-02-01T19:00:05.592311Z","end":"2026-02-01T19:00:05.872507Z","steps":["trace[169113195] 'agreement among raft nodes before linearized reading' (duration: 280.161058ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:05.872564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.682564ms","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-01T19:00:05.872575Z","caller":"traceutil/trace.go:171","msg":"trace[597645520] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:531; }","duration":"278.706044ms","start":"2026-02-01T19:00:05.593866Z","end":"2026-02-01T19:00:05.872572Z","steps":["trace[597645520] 'agreement among raft nodes before linearized reading' (duration: 278.673903ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:00:06.270764Z","caller":"traceutil/trace.go:171","msg":"trace[273118081] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"389.297513ms","start":"2026-02-01T19:00:05.881441Z","end":"2026-02-01T19:00:06.270739Z","steps":["trace[273118081] 'process raft request' (duration: 346.467617ms)","trace[273118081] 'compare' (duration: 42.467877ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T19:00:06.270908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:00:05.881429Z","time spent":"389.395535ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T19:00:06.294286Z","caller":"traceutil/trace.go:171","msg":"trace[2014137379] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"407.584739ms","start":"2026-02-01T19:00:05.886683Z","end":"2026-02-01T19:00:06.294268Z","steps":["trace[2014137379] 'process raft request' (duration: 407.558769ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:06.294435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:00:05.886674Z","time spent":"407.661791ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T19:00:06.294622Z","caller":"traceutil/trace.go:171","msg":"trace[1545943662] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"409.447825ms","start":"2026-02-01T19:00:05.885169Z","end":"2026-02-01T19:00:06.294616Z","steps":["trace[1545943662] 'process raft request' (duration: 408.967243ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:06.294961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:00:05.885143Z","time spent":"409.795933ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T19:00:10.390165Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594528889926120848,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-01T19:00:10.890641Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594528889926120848,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-01T19:00:10.903196Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.071526736s","expected-duration":"1s"} {"level":"info","ts":"2026-02-01T19:00:10.903546Z","caller":"traceutil/trace.go:171","msg":"trace[1915261968] linearizableReadLoop","detail":"{readStateIndex:560; appliedIndex:559; }","duration":"1.015260814s","start":"2026-02-01T19:00:09.888261Z","end":"2026-02-01T19:00:10.903522Z","steps":["trace[1915261968] 'read index received' (duration: 1.015036608s)","trace[1915261968] 'applied index is now lower than readState.Index' (duration: 223.576µs)"],"step_count":2} {"level":"warn","ts":"2026-02-01T19:00:10.904022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.015744465s","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-01T19:00:10.90406Z","caller":"traceutil/trace.go:171","msg":"trace[1676579106] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:542; }","duration":"1.01590181s","start":"2026-02-01T19:00:09.888144Z","end":"2026-02-01T19:00:10.904046Z","steps":["trace[1676579106] 'agreement among raft nodes before linearized reading' (duration: 1.015721345s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:10.90409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:00:09.888127Z","time spent":"1.01595459s","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-01T19:00:10.904406Z","caller":"traceutil/trace.go:171","msg":"trace[229546017] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"1.07286944s","start":"2026-02-01T19:00:09.831527Z","end":"2026-02-01T19:00:10.904396Z","steps":["trace[229546017] 'process raft request' (duration: 1.071854295s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:10.904495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:00:09.8315Z","time spent":"1.072929021s","remote":"127.0.0.1:35896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T19:00:10.905402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.030952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-01T19:00:10.905646Z","caller":"traceutil/trace.go:171","msg":"trace[1751058069] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:542; }","duration":"617.300369ms","start":"2026-02-01T19:00:10.288328Z","end":"2026-02-01T19:00:10.905629Z","steps":["trace[1751058069] 'agreement among raft nodes before linearized reading' (duration: 616.860998ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:10.905868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:00:10.288311Z","time spent":"617.544394ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-01T19:00:10.994738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.216747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T19:00:10.995562Z","caller":"traceutil/trace.go:171","msg":"trace[1549700971] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:542; }","duration":"706.34497ms","start":"2026-02-01T19:00:10.288467Z","end":"2026-02-01T19:00:10.994812Z","steps":["trace[1549700971] 'agreement among raft nodes before linearized reading' (duration: 629.298973ms)","trace[1549700971] 'range keys from in-memory index tree' (duration: 76.909764ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T19:00:10.995648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:00:10.288462Z","time spent":"707.16323ms","remote":"127.0.0.1:35660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-01T19:00:10.998872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.251835ms","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-01T19:00:10.998925Z","caller":"traceutil/trace.go:171","msg":"trace[401866952] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:542; }","duration":"687.314136ms","start":"2026-02-01T19:00:10.311589Z","end":"2026-02-01T19:00:10.998903Z","steps":["trace[401866952] 'agreement among raft nodes before linearized reading' (duration: 606.146867ms)","trace[401866952] 'range keys from in-memory index tree' (duration: 76.857864ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T19:00:10.99896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:00:10.311578Z","time spent":"687.374848ms","remote":"127.0.0.1:35966","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":"warn","ts":"2026-02-01T19:00:10.999151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.775204ms","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-01T19:00:10.99918Z","caller":"traceutil/trace.go:171","msg":"trace[2005165458] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:542; }","duration":"686.835724ms","start":"2026-02-01T19:00:10.312336Z","end":"2026-02-01T19:00:10.999172Z","steps":["trace[2005165458] 'agreement among raft nodes before linearized reading' (duration: 605.421449ms)","trace[2005165458] 'range keys from in-memory index tree' (duration: 80.86915ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T19:00:10.999205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:00:10.312312Z","time spent":"686.888416ms","remote":"127.0.0.1:35966","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-01T19:00:13.398825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.148769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-01T19:00:13.398897Z","caller":"traceutil/trace.go:171","msg":"trace[1453692883] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:559; }","duration":"423.263162ms","start":"2026-02-01T19:00:12.975618Z","end":"2026-02-01T19:00:13.398881Z","steps":["trace[1453692883] 'range keys from in-memory index tree' (duration: 423.050377ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:13.398934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:00:12.975602Z","time spent":"423.324263ms","remote":"127.0.0.1:35798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":375,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-01T19:00:13.399139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.300465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-01T19:00:13.399207Z","caller":"traceutil/trace.go:171","msg":"trace[1791413618] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:559; }","duration":"394.390447ms","start":"2026-02-01T19:00:13.004799Z","end":"2026-02-01T19:00:13.399189Z","steps":["trace[1791413618] 'range keys from in-memory index tree' (duration: 394.165191ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:13.399251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:00:13.004793Z","time spent":"394.450688ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-01T19:00:13.399454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.119227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-01T19:00:13.399475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.051855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T19:00:13.399493Z","caller":"traceutil/trace.go:171","msg":"trace[1290272108] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:559; }","duration":"393.163228ms","start":"2026-02-01T19:00:13.006317Z","end":"2026-02-01T19:00:13.39948Z","steps":["trace[1290272108] 'range keys from in-memory index tree' (duration: 393.045394ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:00:13.399509Z","caller":"traceutil/trace.go:171","msg":"trace[349825606] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:559; }","duration":"112.079026ms","start":"2026-02-01T19:00:13.287416Z","end":"2026-02-01T19:00:13.399495Z","steps":["trace[349825606] 'range keys from in-memory index tree' (duration: 112.009403ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:13.399525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:00:13.006311Z","time spent":"393.207149ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-01T19:00:13.399719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.52387ms","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-01T19:00:13.399748Z","caller":"traceutil/trace.go:171","msg":"trace[341884523] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:559; }","duration":"387.559302ms","start":"2026-02-01T19:00:13.012179Z","end":"2026-02-01T19:00:13.399739Z","steps":["trace[341884523] 'range keys from in-memory index tree' (duration: 387.464209ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:13.399774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:00:13.012175Z","time spent":"387.594112ms","remote":"127.0.0.1:35966","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":"warn","ts":"2026-02-01T19:00:13.399792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.241395ms","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-01T19:00:13.399822Z","caller":"traceutil/trace.go:171","msg":"trace[299233106] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:559; }","duration":"385.278966ms","start":"2026-02-01T19:00:13.014534Z","end":"2026-02-01T19:00:13.399813Z","steps":["trace[299233106] 'range keys from in-memory index tree' (duration: 385.178003ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:13.399848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:00:13.01453Z","time spent":"385.312346ms","remote":"127.0.0.1:35966","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-01T19:00:16.141494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.188912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-02-01T19:00:16.141573Z","caller":"traceutil/trace.go:171","msg":"trace[976529960] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:616; }","duration":"106.333706ms","start":"2026-02-01T19:00:16.035218Z","end":"2026-02-01T19:00:16.141552Z","steps":["trace[976529960] 'range keys from in-memory index tree' (duration: 106.020578ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:00:18.393727Z","caller":"traceutil/trace.go:171","msg":"trace[1771320238] linearizableReadLoop","detail":"{readStateIndex:655; appliedIndex:652; }","duration":"162.874596ms","start":"2026-02-01T19:00:18.23082Z","end":"2026-02-01T19:00:18.393695Z","steps":["trace[1771320238] 'read index received' (duration: 157.853583ms)","trace[1771320238] 'applied index is now lower than readState.Index' (duration: 5.020293ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T19:00:18.393829Z","caller":"traceutil/trace.go:171","msg":"trace[1871712182] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"164.533176ms","start":"2026-02-01T19:00:18.229288Z","end":"2026-02-01T19:00:18.393821Z","steps":["trace[1871712182] 'process raft request' (duration: 164.350081ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:00:18.393939Z","caller":"traceutil/trace.go:171","msg":"trace[387043925] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"164.70544ms","start":"2026-02-01T19:00:18.229214Z","end":"2026-02-01T19:00:18.39392Z","steps":["trace[387043925] 'process raft request' (duration: 164.241359ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:18.394122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.282965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T19:00:18.394161Z","caller":"traceutil/trace.go:171","msg":"trace[675311891] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:634; }","duration":"163.361068ms","start":"2026-02-01T19:00:18.230792Z","end":"2026-02-01T19:00:18.394153Z","steps":["trace[675311891] 'agreement among raft nodes before linearized reading' (duration: 163.289726ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:18.394231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.363607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T19:00:18.394257Z","caller":"traceutil/trace.go:171","msg":"trace[1277505031] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:634; }","duration":"163.409399ms","start":"2026-02-01T19:00:18.23084Z","end":"2026-02-01T19:00:18.394249Z","steps":["trace[1277505031] 'agreement among raft nodes before linearized reading' (duration: 163.354348ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:18.394344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.25391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T19:00:18.394359Z","caller":"traceutil/trace.go:171","msg":"trace[1548213442] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:0; response_revision:634; }","duration":"162.28416ms","start":"2026-02-01T19:00:18.23207Z","end":"2026-02-01T19:00:18.394354Z","steps":["trace[1548213442] 'agreement among raft nodes before linearized reading' (duration: 162.25888ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:25.711891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.927175ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T19:00:25.712898Z","caller":"traceutil/trace.go:171","msg":"trace[686843784] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:693; }","duration":"532.95137ms","start":"2026-02-01T19:00:25.179925Z","end":"2026-02-01T19:00:25.712876Z","steps":["trace[686843784] 'range keys from in-memory index tree' (duration: 531.891105ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:25.714692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.057889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T19:00:25.714849Z","caller":"traceutil/trace.go:171","msg":"trace[515541324] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:693; }","duration":"428.274184ms","start":"2026-02-01T19:00:25.28656Z","end":"2026-02-01T19:00:25.714834Z","steps":["trace[515541324] 'range keys from in-memory index tree' (duration: 427.974927ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:25.714958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:00:25.286542Z","time spent":"428.401047ms","remote":"127.0.0.1:35660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-01T19:00:25.715004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"888.004137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-whjf2\" ","response":"range_response_count:1 size:4732"} {"level":"warn","ts":"2026-02-01T19:00:25.71586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.304848ms","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-01T19:00:25.716058Z","caller":"traceutil/trace.go:171","msg":"trace[189103519] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:693; }","duration":"227.543604ms","start":"2026-02-01T19:00:25.488499Z","end":"2026-02-01T19:00:25.716042Z","steps":["trace[189103519] 'range keys from in-memory index tree' (duration: 227.156255ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:00:25.716095Z","caller":"traceutil/trace.go:171","msg":"trace[1224083574] range","detail":"{range_begin:/registry/pods/kube-system/coredns-77cccfdc44-whjf2; range_end:; response_count:1; response_revision:693; }","duration":"888.632502ms","start":"2026-02-01T19:00:24.826938Z","end":"2026-02-01T19:00:25.715571Z","steps":["trace[1224083574] 'range keys from in-memory index tree' (duration: 887.266229ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:25.716335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:00:24.826919Z","time spent":"889.404071ms","remote":"127.0.0.1:35896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4756,"request content":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-whjf2\" "} {"level":"warn","ts":"2026-02-01T19:00:25.716447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.888019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-01T19:00:25.716488Z","caller":"traceutil/trace.go:171","msg":"trace[1603533121] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:693; }","duration":"224.95092ms","start":"2026-02-01T19:00:25.491529Z","end":"2026-02-01T19:00:25.71648Z","steps":["trace[1603533121] 'range keys from in-memory index tree' (duration: 224.690825ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:25.716757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.75754ms","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-01T19:00:25.717037Z","caller":"traceutil/trace.go:171","msg":"trace[92107242] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:693; }","duration":"185.064148ms","start":"2026-02-01T19:00:25.531964Z","end":"2026-02-01T19:00:25.717028Z","steps":["trace[92107242] 'range keys from in-memory index tree' (duration: 184.682008ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:25.717268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.093689ms","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-01T19:00:25.717323Z","caller":"traceutil/trace.go:171","msg":"trace[1750350229] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:693; }","duration":"185.168591ms","start":"2026-02-01T19:00:25.532148Z","end":"2026-02-01T19:00:25.717316Z","steps":["trace[1750350229] 'range keys from in-memory index tree' (duration: 185.040707ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:00:25.961655Z","caller":"traceutil/trace.go:171","msg":"trace[321191666] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"292.542522ms","start":"2026-02-01T19:00:25.669094Z","end":"2026-02-01T19:00:25.961637Z","steps":["trace[321191666] 'process raft request' (duration: 292.330226ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:00:25.962041Z","caller":"traceutil/trace.go:171","msg":"trace[25655418] linearizableReadLoop","detail":"{readStateIndex:717; appliedIndex:717; }","duration":"240.835189ms","start":"2026-02-01T19:00:25.721189Z","end":"2026-02-01T19:00:25.962024Z","steps":["trace[25655418] 'read index received' (duration: 240.832679ms)","trace[25655418] 'applied index is now lower than readState.Index' (duration: 2.05µs)"],"step_count":2} {"level":"warn","ts":"2026-02-01T19:00:25.962094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.89884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T19:00:25.962115Z","caller":"traceutil/trace.go:171","msg":"trace[1555360873] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:694; }","duration":"241.581827ms","start":"2026-02-01T19:00:25.720525Z","end":"2026-02-01T19:00:25.962107Z","steps":["trace[1555360873] 'agreement among raft nodes before linearized reading' (duration: 241.541326ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:00:25.968415Z","caller":"traceutil/trace.go:171","msg":"trace[215488314] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"242.16337ms","start":"2026-02-01T19:00:25.726243Z","end":"2026-02-01T19:00:25.968407Z","steps":["trace[215488314] 'process raft request' (duration: 241.953615ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:00:25.969325Z","caller":"traceutil/trace.go:171","msg":"trace[863360765] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"242.94337ms","start":"2026-02-01T19:00:25.726375Z","end":"2026-02-01T19:00:25.969318Z","steps":["trace[863360765] 'process raft request' (duration: 241.890614ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:00:25.969596Z","caller":"traceutil/trace.go:171","msg":"trace[632242787] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"241.531677ms","start":"2026-02-01T19:00:25.728058Z","end":"2026-02-01T19:00:25.969589Z","steps":["trace[632242787] 'process raft request' (duration: 241.482276ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:00:25.969647Z","caller":"traceutil/trace.go:171","msg":"trace[113199420] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"243.186687ms","start":"2026-02-01T19:00:25.726435Z","end":"2026-02-01T19:00:25.969622Z","steps":["trace[113199420] 'process raft request' (duration: 241.848393ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:00:25.970128Z","caller":"traceutil/trace.go:171","msg":"trace[1614579430] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"243.513644ms","start":"2026-02-01T19:00:25.726606Z","end":"2026-02-01T19:00:25.97012Z","steps":["trace[1614579430] 'process raft request' (duration: 242.91964ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:00:25.970211Z","caller":"traceutil/trace.go:171","msg":"trace[1139909724] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"243.571645ms","start":"2026-02-01T19:00:25.726631Z","end":"2026-02-01T19:00:25.970203Z","steps":["trace[1139909724] 'process raft request' (duration: 242.873009ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:00:32.230957Z","caller":"traceutil/trace.go:171","msg":"trace[469445185] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"243.130785ms","start":"2026-02-01T19:00:31.987802Z","end":"2026-02-01T19:00:32.230933Z","steps":["trace[469445185] 'process raft request' (duration: 243.001081ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:00:32.231543Z","caller":"traceutil/trace.go:171","msg":"trace[542940694] linearizableReadLoop","detail":"{readStateIndex:758; appliedIndex:758; }","duration":"240.407809ms","start":"2026-02-01T19:00:31.991124Z","end":"2026-02-01T19:00:32.231532Z","steps":["trace[542940694] 'read index received' (duration: 240.402999ms)","trace[542940694] 'applied index is now lower than readState.Index' (duration: 3.96µs)"],"step_count":2} {"level":"warn","ts":"2026-02-01T19:00:32.231699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.555122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-01T19:00:32.231737Z","caller":"traceutil/trace.go:171","msg":"trace[1375109320] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:734; }","duration":"240.614324ms","start":"2026-02-01T19:00:31.991111Z","end":"2026-02-01T19:00:32.231726Z","steps":["trace[1375109320] 'agreement among raft nodes before linearized reading' (duration: 240.486321ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:00:32.389443Z","caller":"traceutil/trace.go:171","msg":"trace[1769499222] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"354.005312ms","start":"2026-02-01T19:00:32.035421Z","end":"2026-02-01T19:00:32.389427Z","steps":["trace[1769499222] 'process raft request' (duration: 323.756334ms)","trace[1769499222] 'compare' (duration: 30.097295ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T19:00:32.389598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:00:32.035402Z","time spent":"354.109754ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T19:00:32.390102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.246234ms","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-01T19:00:32.390146Z","caller":"traceutil/trace.go:171","msg":"trace[1430812979] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:735; }","duration":"385.299835ms","start":"2026-02-01T19:00:32.004836Z","end":"2026-02-01T19:00:32.390136Z","steps":["trace[1430812979] 'agreement among raft nodes before linearized reading' (duration: 385.180521ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:32.390183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:00:32.004831Z","time spent":"385.346007ms","remote":"127.0.0.1:35966","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-01T19:00:32.390423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.700315ms","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-01T19:00:32.39046Z","caller":"traceutil/trace.go:171","msg":"trace[178316152] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:735; }","duration":"387.762656ms","start":"2026-02-01T19:00:32.002689Z","end":"2026-02-01T19:00:32.390452Z","steps":["trace[178316152] 'agreement among raft nodes before linearized reading' (duration: 387.676534ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:32.390491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:00:32.002667Z","time spent":"387.818988ms","remote":"127.0.0.1:35966","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-01T19:00:32.391161Z","caller":"traceutil/trace.go:171","msg":"trace[1994706387] linearizableReadLoop","detail":"{readStateIndex:759; appliedIndex:758; }","duration":"157.808652ms","start":"2026-02-01T19:00:32.231578Z","end":"2026-02-01T19:00:32.389386Z","steps":["trace[1994706387] 'read index received' (duration: 127.606425ms)","trace[1994706387] 'applied index is now lower than readState.Index' (duration: 30.198747ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T19:00:32.39142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.785351ms","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-01T19:00:32.391455Z","caller":"traceutil/trace.go:171","msg":"trace[980653233] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:735; }","duration":"395.864993ms","start":"2026-02-01T19:00:31.995578Z","end":"2026-02-01T19:00:32.391443Z","steps":["trace[980653233] 'agreement among raft nodes before linearized reading' (duration: 395.686948ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:32.391492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:00:31.995574Z","time spent":"395.910154ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-01T19:00:32.400825Z","caller":"traceutil/trace.go:171","msg":"trace[1950977674] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"164.984078ms","start":"2026-02-01T19:00:32.235827Z","end":"2026-02-01T19:00:32.400811Z","steps":["trace[1950977674] 'process raft request' (duration: 164.769613ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:32.40104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.969147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T19:00:32.40107Z","caller":"traceutil/trace.go:171","msg":"trace[1287927671] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:736; }","duration":"113.033769ms","start":"2026-02-01T19:00:32.288028Z","end":"2026-02-01T19:00:32.401062Z","steps":["trace[1287927671] 'agreement among raft nodes before linearized reading' (duration: 112.64974ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:32.642597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.411148ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T19:00:32.642663Z","caller":"traceutil/trace.go:171","msg":"trace[946150683] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"237.869897ms","start":"2026-02-01T19:00:32.404782Z","end":"2026-02-01T19:00:32.642652Z","steps":["trace[946150683] 'process raft request' (duration: 106.244844ms)","trace[946150683] 'compare' (duration: 131.331456ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T19:00:32.947429Z","caller":"traceutil/trace.go:171","msg":"trace[726578061] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"538.135416ms","start":"2026-02-01T19:00:32.409278Z","end":"2026-02-01T19:00:32.947413Z","steps":["trace[726578061] 'process raft request' (duration: 502.022835ms)","trace[726578061] 'compare' (duration: 35.860315ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T19:00:32.947512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:00:32.409267Z","time spent":"538.201088ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T19:00:32.947752Z","caller":"traceutil/trace.go:171","msg":"trace[2050639284] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"536.879256ms","start":"2026-02-01T19:00:32.410868Z","end":"2026-02-01T19:00:32.947747Z","steps":["trace[2050639284] 'process raft request' (duration: 536.505846ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:32.947777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:00:32.410861Z","time spent":"536.902536ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T19:00:32.947896Z","caller":"traceutil/trace.go:171","msg":"trace[13171792] linearizableReadLoop","detail":"{readStateIndex:763; appliedIndex:761; }","duration":"148.229048ms","start":"2026-02-01T19:00:32.79917Z","end":"2026-02-01T19:00:32.947399Z","steps":["trace[13171792] 'read index received' (duration: 112.116667ms)","trace[13171792] 'applied index is now lower than readState.Index' (duration: 36.111851ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T19:00:32.947977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.805132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.52\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-01T19:00:32.947991Z","caller":"traceutil/trace.go:171","msg":"trace[929510969] range","detail":"{range_begin:/registry/masterleases/162.253.55.52; range_end:; response_count:1; response_revision:739; }","duration":"148.820873ms","start":"2026-02-01T19:00:32.799166Z","end":"2026-02-01T19:00:32.947987Z","steps":["trace[929510969] 'agreement among raft nodes before linearized reading' (duration: 148.743081ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:34.427269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.72669ms","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-01T19:00:34.427327Z","caller":"traceutil/trace.go:171","msg":"trace[1917027668] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:740; }","duration":"191.817993ms","start":"2026-02-01T19:00:34.235496Z","end":"2026-02-01T19:00:34.427314Z","steps":["trace[1917027668] 'range keys from in-memory index tree' (duration: 191.638758ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:34.427295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.14086229s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T19:00:34.427417Z","caller":"traceutil/trace.go:171","msg":"trace[1527335031] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:740; }","duration":"1.141006174s","start":"2026-02-01T19:00:33.286383Z","end":"2026-02-01T19:00:34.427389Z","steps":["trace[1527335031] 'count revisions from in-memory index tree' (duration: 1.140814929s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:34.427463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:00:33.286371Z","time spent":"1.141081355s","remote":"127.0.0.1:35850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-02-01T19:00:34.427622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.669755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T19:00:34.427643Z","caller":"traceutil/trace.go:171","msg":"trace[647367573] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:740; }","duration":"138.739427ms","start":"2026-02-01T19:00:34.288897Z","end":"2026-02-01T19:00:34.427637Z","steps":["trace[647367573] 'range keys from in-memory index tree' (duration: 138.600183ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:00:35.026045Z","caller":"traceutil/trace.go:171","msg":"trace[1795500637] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"590.358091ms","start":"2026-02-01T19:00:34.435671Z","end":"2026-02-01T19:00:35.026029Z","steps":["trace[1795500637] 'process raft request' (duration: 590.266328ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:35.026144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:00:34.435654Z","time spent":"590.438342ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T19:00:35.250598Z","caller":"traceutil/trace.go:171","msg":"trace[687312049] linearizableReadLoop","detail":"{readStateIndex:767; appliedIndex:766; }","duration":"605.106181ms","start":"2026-02-01T19:00:34.645468Z","end":"2026-02-01T19:00:35.250575Z","steps":["trace[687312049] 'read index received' (duration: 380.933039ms)","trace[687312049] 'applied index is now lower than readState.Index' (duration: 224.171482ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T19:00:35.250706Z","caller":"traceutil/trace.go:171","msg":"trace[1151590917] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"814.8054ms","start":"2026-02-01T19:00:34.435875Z","end":"2026-02-01T19:00:35.250681Z","steps":["trace[1151590917] 'process raft request' (duration: 790.289571ms)","trace[1151590917] 'compare' (duration: 24.234862ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T19:00:35.250842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:00:34.435863Z","time spent":"814.902822ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T19:00:35.250867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.376628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-01T19:00:35.250902Z","caller":"traceutil/trace.go:171","msg":"trace[2044034377] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:742; }","duration":"605.451499ms","start":"2026-02-01T19:00:34.64544Z","end":"2026-02-01T19:00:35.250891Z","steps":["trace[2044034377] 'agreement among raft nodes before linearized reading' (duration: 605.260044ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:35.250933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:00:34.645427Z","time spent":"605.499511ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-01T19:00:35.251153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.2968ms","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-01T19:00:35.251184Z","caller":"traceutil/trace.go:171","msg":"trace[919872779] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:742; }","duration":"300.349671ms","start":"2026-02-01T19:00:34.950826Z","end":"2026-02-01T19:00:35.251176Z","steps":["trace[919872779] 'agreement among raft nodes before linearized reading' (duration: 300.267979ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:35.25121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:00:34.950809Z","time spent":"300.395042ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-01T19:00:35.251341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.418599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-01T19:00:35.251372Z","caller":"traceutil/trace.go:171","msg":"trace[1859581001] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:742; }","duration":"299.454329ms","start":"2026-02-01T19:00:34.951908Z","end":"2026-02-01T19:00:35.251362Z","steps":["trace[1859581001] 'agreement among raft nodes before linearized reading' (duration: 299.365357ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:35.48773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.016496ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T19:00:35.487823Z","caller":"traceutil/trace.go:171","msg":"trace[1634247896] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"230.546318ms","start":"2026-02-01T19:00:35.257259Z","end":"2026-02-01T19:00:35.487806Z","steps":["trace[1634247896] 'process raft request' (duration: 95.368028ms)","trace[1634247896] 'compare' (duration: 134.926414ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T19:00:35.525207Z","caller":"traceutil/trace.go:171","msg":"trace[1252026789] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"267.604963ms","start":"2026-02-01T19:00:35.257584Z","end":"2026-02-01T19:00:35.525189Z","steps":["trace[1252026789] 'process raft request' (duration: 267.577722ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:00:35.52534Z","caller":"traceutil/trace.go:171","msg":"trace[2063301523] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"267.812758ms","start":"2026-02-01T19:00:35.257511Z","end":"2026-02-01T19:00:35.525324Z","steps":["trace[2063301523] 'process raft request' (duration: 267.566172ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:00:36.361446Z","caller":"traceutil/trace.go:171","msg":"trace[2145147029] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"169.360904ms","start":"2026-02-01T19:00:36.192068Z","end":"2026-02-01T19:00:36.361429Z","steps":["trace[2145147029] 'process raft request' (duration: 169.235801ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:00:36.511638Z","caller":"traceutil/trace.go:171","msg":"trace[1488183734] linearizableReadLoop","detail":"{readStateIndex:772; appliedIndex:771; }","duration":"145.203974ms","start":"2026-02-01T19:00:36.366415Z","end":"2026-02-01T19:00:36.511618Z","steps":["trace[1488183734] 'read index received' (duration: 87.892195ms)","trace[1488183734] 'applied index is now lower than readState.Index' (duration: 57.310929ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T19:00:36.51186Z","caller":"traceutil/trace.go:171","msg":"trace[1561231503] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"201.5449ms","start":"2026-02-01T19:00:36.310303Z","end":"2026-02-01T19:00:36.511848Z","steps":["trace[1561231503] 'process raft request' (duration: 144.047986ms)","trace[1561231503] 'compare' (duration: 57.173946ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T19:00:36.512035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.605524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T19:00:36.512067Z","caller":"traceutil/trace.go:171","msg":"trace[392901930] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:747; }","duration":"145.660806ms","start":"2026-02-01T19:00:36.366395Z","end":"2026-02-01T19:00:36.512055Z","steps":["trace[392901930] 'agreement among raft nodes before linearized reading' (duration: 145.605455ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:36.512151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.519158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T19:00:36.512174Z","caller":"traceutil/trace.go:171","msg":"trace[1156998662] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:747; }","duration":"119.544919ms","start":"2026-02-01T19:00:36.392622Z","end":"2026-02-01T19:00:36.512167Z","steps":["trace[1156998662] 'agreement among raft nodes before linearized reading' (duration: 119.508518ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:47.969545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.647161ms","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-01T19:00:47.969621Z","caller":"traceutil/trace.go:171","msg":"trace[1765014347] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:783; }","duration":"363.776524ms","start":"2026-02-01T19:00:47.605831Z","end":"2026-02-01T19:00:47.969608Z","steps":["trace[1765014347] 'range keys from in-memory index tree' (duration: 363.509858ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:47.969653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:00:47.605816Z","time spent":"363.830505ms","remote":"127.0.0.1:35966","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-01T19:00:47.969709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.496041ms","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-01T19:00:47.969754Z","caller":"traceutil/trace.go:171","msg":"trace[1423881866] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:783; }","duration":"364.582284ms","start":"2026-02-01T19:00:47.605161Z","end":"2026-02-01T19:00:47.969743Z","steps":["trace[1423881866] 'range keys from in-memory index tree' (duration: 364.394579ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:47.969777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:00:47.605149Z","time spent":"364.622634ms","remote":"127.0.0.1:35966","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":"warn","ts":"2026-02-01T19:00:50.241484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.940699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T19:00:50.241559Z","caller":"traceutil/trace.go:171","msg":"trace[35801155] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:788; }","duration":"411.064752ms","start":"2026-02-01T19:00:49.830477Z","end":"2026-02-01T19:00:50.241541Z","steps":["trace[35801155] 'count revisions from in-memory index tree' (duration: 410.834867ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:50.241603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:00:49.830458Z","time spent":"411.134824ms","remote":"127.0.0.1:35946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":29,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-02-01T19:00:50.242037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.532352ms","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-01T19:00:50.242068Z","caller":"traceutil/trace.go:171","msg":"trace[2095806667] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:788; }","duration":"263.595603ms","start":"2026-02-01T19:00:49.978463Z","end":"2026-02-01T19:00:50.242058Z","steps":["trace[2095806667] 'range keys from in-memory index tree' (duration: 263.406389ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:50.24226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.269217ms","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-01T19:00:50.242287Z","caller":"traceutil/trace.go:171","msg":"trace[327724406] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:788; }","duration":"261.309558ms","start":"2026-02-01T19:00:49.980969Z","end":"2026-02-01T19:00:50.242278Z","steps":["trace[327724406] 'range keys from in-memory index tree' (duration: 261.190195ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:00:50.488448Z","caller":"traceutil/trace.go:171","msg":"trace[882678306] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"241.41279ms","start":"2026-02-01T19:00:50.247017Z","end":"2026-02-01T19:00:50.48843Z","steps":["trace[882678306] 'process raft request' (duration: 241.306867ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:00:50.519509Z","caller":"traceutil/trace.go:171","msg":"trace[990815209] linearizableReadLoop","detail":"{readStateIndex:818; appliedIndex:817; }","duration":"231.944872ms","start":"2026-02-01T19:00:50.287547Z","end":"2026-02-01T19:00:50.519492Z","steps":["trace[990815209] 'read index received' (duration: 201.471229ms)","trace[990815209] 'applied index is now lower than readState.Index' (duration: 30.472953ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T19:00:50.519823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.269329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T19:00:50.520082Z","caller":"traceutil/trace.go:171","msg":"trace[1274757684] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:790; }","duration":"232.525256ms","start":"2026-02-01T19:00:50.287494Z","end":"2026-02-01T19:00:50.520019Z","steps":["trace[1274757684] 'agreement among raft nodes before linearized reading' (duration: 232.146327ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:00:50.5208Z","caller":"traceutil/trace.go:171","msg":"trace[284653580] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"273.123913ms","start":"2026-02-01T19:00:50.247663Z","end":"2026-02-01T19:00:50.520787Z","steps":["trace[284653580] 'process raft request' (duration: 271.720939ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:51.943582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.105959ms","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-01T19:00:51.943851Z","caller":"traceutil/trace.go:171","msg":"trace[1583780828] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:792; }","duration":"314.412377ms","start":"2026-02-01T19:00:51.62942Z","end":"2026-02-01T19:00:51.943832Z","steps":["trace[1583780828] 'range keys from in-memory index tree' (duration: 313.849662ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:51.943998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:00:51.629401Z","time spent":"314.583281ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-01T19:00:51.94477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.447385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-01T19:00:51.945005Z","caller":"traceutil/trace.go:171","msg":"trace[1793494605] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:792; }","duration":"194.945622ms","start":"2026-02-01T19:00:51.750042Z","end":"2026-02-01T19:00:51.944988Z","steps":["trace[1793494605] 'count revisions from in-memory index tree' (duration: 193.379244ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:52.770837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.182684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T19:00:52.770885Z","caller":"traceutil/trace.go:171","msg":"trace[173216250] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:793; }","duration":"482.267095ms","start":"2026-02-01T19:00:52.288607Z","end":"2026-02-01T19:00:52.770874Z","steps":["trace[173216250] 'range keys from in-memory index tree' (duration: 482.113562ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:52.770906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:00:52.28859Z","time spent":"482.311857ms","remote":"127.0.0.1:35676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-01T19:00:52.771094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.17383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T19:00:52.771117Z","caller":"traceutil/trace.go:171","msg":"trace[1716719078] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:793; }","duration":"373.230652ms","start":"2026-02-01T19:00:52.397877Z","end":"2026-02-01T19:00:52.771108Z","steps":["trace[1716719078] 'count revisions from in-memory index tree' (duration: 373.050947ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:52.771134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:00:52.397855Z","time spent":"373.273322ms","remote":"127.0.0.1:36082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-01T19:00:52.771123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.168061ms","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-01T19:00:52.771167Z","caller":"traceutil/trace.go:171","msg":"trace[1231114550] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:793; }","duration":"245.245432ms","start":"2026-02-01T19:00:52.525909Z","end":"2026-02-01T19:00:52.771155Z","steps":["trace[1231114550] 'range keys from in-memory index tree' (duration: 245.024157ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:52.771273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.872102ms","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-01T19:00:52.771288Z","caller":"traceutil/trace.go:171","msg":"trace[2089046869] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:793; }","duration":"278.918233ms","start":"2026-02-01T19:00:52.492365Z","end":"2026-02-01T19:00:52.771284Z","steps":["trace[2089046869] 'range keys from in-memory index tree' (duration: 278.729748ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:00:52.833286Z","caller":"traceutil/trace.go:171","msg":"trace[161712310] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"163.133295ms","start":"2026-02-01T19:00:52.669948Z","end":"2026-02-01T19:00:52.833081Z","steps":["trace[161712310] 'process raft request' (duration: 162.891659ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:00:53.689389Z","caller":"traceutil/trace.go:171","msg":"trace[1497952287] linearizableReadLoop","detail":"{readStateIndex:824; appliedIndex:821; }","duration":"888.394929ms","start":"2026-02-01T19:00:52.800974Z","end":"2026-02-01T19:00:53.689369Z","steps":["trace[1497952287] 'read index received' (duration: 31.989799ms)","trace[1497952287] 'applied index is now lower than readState.Index' (duration: 856.40416ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T19:00:53.689635Z","caller":"traceutil/trace.go:171","msg":"trace[1212538960] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"915.562913ms","start":"2026-02-01T19:00:52.774059Z","end":"2026-02-01T19:00:53.689622Z","steps":["trace[1212538960] 'process raft request' (duration: 887.756774ms)","trace[1212538960] 'compare' (duration: 27.259846ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T19:00:53.689713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.320821ms","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-01T19:00:53.689721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:00:52.774046Z","time spent":"915.616934ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T19:00:53.689753Z","caller":"traceutil/trace.go:171","msg":"trace[1525015097] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"915.551443ms","start":"2026-02-01T19:00:52.774196Z","end":"2026-02-01T19:00:53.689747Z","steps":["trace[1525015097] 'process raft request' (duration: 915.104393ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:53.689782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:00:52.774191Z","time spent":"915.573984ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T19:00:53.689861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"888.886971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.52\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-01T19:00:53.689874Z","caller":"traceutil/trace.go:171","msg":"trace[1542900113] range","detail":"{range_begin:/registry/masterleases/162.253.55.52; range_end:; response_count:1; response_revision:796; }","duration":"888.923582ms","start":"2026-02-01T19:00:52.800945Z","end":"2026-02-01T19:00:53.689869Z","steps":["trace[1542900113] 'agreement among raft nodes before linearized reading' (duration: 888.888741ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:00:53.689735Z","caller":"traceutil/trace.go:171","msg":"trace[1646489835] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:796; }","duration":"606.376553ms","start":"2026-02-01T19:00:53.083352Z","end":"2026-02-01T19:00:53.689729Z","steps":["trace[1646489835] 'agreement among raft nodes before linearized reading' (duration: 606.296821ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:53.689888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:00:52.800932Z","time spent":"888.952252ms","remote":"127.0.0.1:35692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/162.253.55.52\" "} {"level":"warn","ts":"2026-02-01T19:00:53.689898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:00:53.083332Z","time spent":"606.557738ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-01T19:00:53.689979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.151469ms","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-01T19:00:53.689992Z","caller":"traceutil/trace.go:171","msg":"trace[1464689926] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:796; }","duration":"358.18954ms","start":"2026-02-01T19:00:53.331798Z","end":"2026-02-01T19:00:53.689988Z","steps":["trace[1464689926] 'agreement among raft nodes before linearized reading' (duration: 358.152479ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:53.690003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:00:53.331783Z","time spent":"358.21719ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-01T19:00:53.690098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.551213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T19:00:53.690126Z","caller":"traceutil/trace.go:171","msg":"trace[10259644] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:796; }","duration":"401.609554ms","start":"2026-02-01T19:00:53.288507Z","end":"2026-02-01T19:00:53.690116Z","steps":["trace[10259644] 'agreement among raft nodes before linearized reading' (duration: 401.566413ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:00:53.690149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:00:53.288489Z","time spent":"401.655416ms","remote":"127.0.0.1:35660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-01T19:00:54.004471Z","caller":"traceutil/trace.go:171","msg":"trace[998418] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"248.748817ms","start":"2026-02-01T19:00:53.755708Z","end":"2026-02-01T19:00:54.004457Z","steps":["trace[998418] 'process raft request' (duration: 189.02138ms)","trace[998418] 'compare' (duration: 58.401005ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T19:00:54.189131Z","caller":"traceutil/trace.go:171","msg":"trace[1911238261] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"180.524133ms","start":"2026-02-01T19:00:54.008591Z","end":"2026-02-01T19:00:54.189115Z","steps":["trace[1911238261] 'process raft request' (duration: 175.791129ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:01:06.048634Z","caller":"traceutil/trace.go:171","msg":"trace[415893356] linearizableReadLoop","detail":"{readStateIndex:917; appliedIndex:916; }","duration":"305.826391ms","start":"2026-02-01T19:01:05.74279Z","end":"2026-02-01T19:01:06.048617Z","steps":["trace[415893356] 'read index received' (duration: 305.691206ms)","trace[415893356] 'applied index is now lower than readState.Index' (duration: 134.685µs)"],"step_count":2} {"level":"info","ts":"2026-02-01T19:01:06.048721Z","caller":"traceutil/trace.go:171","msg":"trace[485537795] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"306.413185ms","start":"2026-02-01T19:01:05.74229Z","end":"2026-02-01T19:01:06.048703Z","steps":["trace[485537795] 'process raft request' (duration: 306.207539ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:06.048804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.995065ms","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-01T19:01:06.048825Z","caller":"traceutil/trace.go:171","msg":"trace[1168039692] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:884; }","duration":"306.041495ms","start":"2026-02-01T19:01:05.742778Z","end":"2026-02-01T19:01:06.048819Z","steps":["trace[1168039692] 'agreement among raft nodes before linearized reading' (duration: 305.928823ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:06.048846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:05.742762Z","time spent":"306.078777ms","remote":"127.0.0.1:35966","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-01T19:01:06.048847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:05.74228Z","time spent":"306.502007ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T19:01:06.048968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.621481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-01T19:01:06.048983Z","caller":"traceutil/trace.go:171","msg":"trace[1051656517] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:884; }","duration":"208.659932ms","start":"2026-02-01T19:01:05.840318Z","end":"2026-02-01T19:01:06.048978Z","steps":["trace[1051656517] 'agreement among raft nodes before linearized reading' (duration: 208.614981ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:06.049053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.494035ms","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-01T19:01:06.049067Z","caller":"traceutil/trace.go:171","msg":"trace[1867188808] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:884; }","duration":"200.532756ms","start":"2026-02-01T19:01:05.84853Z","end":"2026-02-01T19:01:06.049062Z","steps":["trace[1867188808] 'agreement among raft nodes before linearized reading' (duration: 200.493395ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:01:06.181286Z","caller":"traceutil/trace.go:171","msg":"trace[88267308] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"127.464758ms","start":"2026-02-01T19:01:06.053805Z","end":"2026-02-01T19:01:06.181269Z","steps":["trace[88267308] 'process raft request' (duration: 126.045393ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:01:06.263008Z","caller":"traceutil/trace.go:171","msg":"trace[918045111] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"206.441758ms","start":"2026-02-01T19:01:06.056553Z","end":"2026-02-01T19:01:06.262994Z","steps":["trace[918045111] 'process raft request' (duration: 206.062619ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:01:06.263158Z","caller":"traceutil/trace.go:171","msg":"trace[518037378] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"206.557901ms","start":"2026-02-01T19:01:06.056593Z","end":"2026-02-01T19:01:06.26315Z","steps":["trace[518037378] 'process raft request' (duration: 206.10527ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:06.413337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.926396ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T19:01:06.413431Z","caller":"traceutil/trace.go:171","msg":"trace[817727221] linearizableReadLoop","detail":"{readStateIndex:921; appliedIndex:920; }","duration":"124.399314ms","start":"2026-02-01T19:01:06.289015Z","end":"2026-02-01T19:01:06.413414Z","steps":["trace[817727221] 'read index received' (duration: 4.285534ms)","trace[817727221] 'applied index is now lower than readState.Index' (duration: 120.11282ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T19:01:06.413509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.491756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T19:01:06.413531Z","caller":"traceutil/trace.go:171","msg":"trace[887557899] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:888; }","duration":"124.513917ms","start":"2026-02-01T19:01:06.28901Z","end":"2026-02-01T19:01:06.413523Z","steps":["trace[887557899] 'agreement among raft nodes before linearized reading' (duration: 124.447325ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:01:06.413878Z","caller":"traceutil/trace.go:171","msg":"trace[638687039] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"145.337248ms","start":"2026-02-01T19:01:06.268525Z","end":"2026-02-01T19:01:06.413862Z","steps":["trace[638687039] 'process raft request' (duration: 24.815488ms)","trace[638687039] 'compare' (duration: 119.796983ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T19:01:07.465772Z","caller":"traceutil/trace.go:171","msg":"trace[1501353763] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"148.988406ms","start":"2026-02-01T19:01:07.316748Z","end":"2026-02-01T19:01:07.465736Z","steps":["trace[1501353763] 'process raft request' (duration: 148.862833ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:01:08.662294Z","caller":"traceutil/trace.go:171","msg":"trace[1367951478] linearizableReadLoop","detail":"{readStateIndex:923; appliedIndex:922; }","duration":"477.387919ms","start":"2026-02-01T19:01:08.184885Z","end":"2026-02-01T19:01:08.662273Z","steps":["trace[1367951478] 'read index received' (duration: 477.227844ms)","trace[1367951478] 'applied index is now lower than readState.Index' (duration: 159.224µs)"],"step_count":2} {"level":"info","ts":"2026-02-01T19:01:08.66255Z","caller":"traceutil/trace.go:171","msg":"trace[197883584] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"604.958649ms","start":"2026-02-01T19:01:08.05758Z","end":"2026-02-01T19:01:08.662539Z","steps":["trace[197883584] 'process raft request' (duration: 604.584469ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:08.662639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:08.057567Z","time spent":"605.010889ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T19:01:08.663012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.118416ms","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-01T19:01:08.663085Z","caller":"traceutil/trace.go:171","msg":"trace[1875740680] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:890; }","duration":"478.158877ms","start":"2026-02-01T19:01:08.18488Z","end":"2026-02-01T19:01:08.663039Z","steps":["trace[1875740680] 'agreement among raft nodes before linearized reading' (duration: 478.036404ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:08.663118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:08.184865Z","time spent":"478.246239ms","remote":"127.0.0.1:35966","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-01T19:01:08.663333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.6041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-01T19:01:08.663369Z","caller":"traceutil/trace.go:171","msg":"trace[973794964] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:890; }","duration":"395.668101ms","start":"2026-02-01T19:01:08.267692Z","end":"2026-02-01T19:01:08.66336Z","steps":["trace[973794964] 'agreement among raft nodes before linearized reading' (duration: 395.571629ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:08.663395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:08.26768Z","time spent":"395.709723ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-01T19:01:08.663647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.484828ms","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-01T19:01:08.663684Z","caller":"traceutil/trace.go:171","msg":"trace[1805748702] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:890; }","duration":"245.547309ms","start":"2026-02-01T19:01:08.418128Z","end":"2026-02-01T19:01:08.663676Z","steps":["trace[1805748702] 'agreement among raft nodes before linearized reading' (duration: 245.453847ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:08.664138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.549597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T19:01:08.66418Z","caller":"traceutil/trace.go:171","msg":"trace[224436682] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:890; }","duration":"375.616329ms","start":"2026-02-01T19:01:08.288554Z","end":"2026-02-01T19:01:08.664171Z","steps":["trace[224436682] 'agreement among raft nodes before linearized reading' (duration: 375.533717ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:08.664209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:08.288543Z","time spent":"375.6589ms","remote":"127.0.0.1:35676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-01T19:01:08.664399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.159743ms","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-01T19:01:08.664426Z","caller":"traceutil/trace.go:171","msg":"trace[344048243] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:890; }","duration":"396.205064ms","start":"2026-02-01T19:01:08.268212Z","end":"2026-02-01T19:01:08.664417Z","steps":["trace[344048243] 'agreement among raft nodes before linearized reading' (duration: 396.127132ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:08.66445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:08.268203Z","time spent":"396.241965ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-01T19:01:08.897254Z","caller":"traceutil/trace.go:171","msg":"trace[837055835] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"223.760355ms","start":"2026-02-01T19:01:08.673478Z","end":"2026-02-01T19:01:08.897238Z","steps":["trace[837055835] 'process raft request' (duration: 223.736034ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:01:08.897606Z","caller":"traceutil/trace.go:171","msg":"trace[1081507916] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"224.319369ms","start":"2026-02-01T19:01:08.673278Z","end":"2026-02-01T19:01:08.897597Z","steps":["trace[1081507916] 'process raft request' (duration: 171.203301ms)","trace[1081507916] 'compare' (duration: 52.574884ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T19:01:08.897744Z","caller":"traceutil/trace.go:171","msg":"trace[1118971984] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"224.305799ms","start":"2026-02-01T19:01:08.673432Z","end":"2026-02-01T19:01:08.897738Z","steps":["trace[1118971984] 'process raft request' (duration: 223.715174ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:01:08.897823Z","caller":"traceutil/trace.go:171","msg":"trace[504666065] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"224.360049ms","start":"2026-02-01T19:01:08.673456Z","end":"2026-02-01T19:01:08.897816Z","steps":["trace[504666065] 'process raft request' (duration: 223.737444ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:01:13.256942Z","caller":"traceutil/trace.go:171","msg":"trace[1692772722] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"575.585042ms","start":"2026-02-01T19:01:12.681343Z","end":"2026-02-01T19:01:13.256928Z","steps":["trace[1692772722] 'process raft request' (duration: 575.50387ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:13.257037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:12.681334Z","time spent":"575.648924ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T19:01:13.257308Z","caller":"traceutil/trace.go:171","msg":"trace[1793315424] linearizableReadLoop","detail":"{readStateIndex:934; appliedIndex:934; }","duration":"455.138824ms","start":"2026-02-01T19:01:12.802164Z","end":"2026-02-01T19:01:13.257303Z","steps":["trace[1793315424] 'read index received' (duration: 455.136764ms)","trace[1793315424] 'applied index is now lower than readState.Index' (duration: 1.6µs)"],"step_count":2} {"level":"warn","ts":"2026-02-01T19:01:13.25738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.214545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.52\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-01T19:01:13.257395Z","caller":"traceutil/trace.go:171","msg":"trace[643529639] range","detail":"{range_begin:/registry/masterleases/162.253.55.52; range_end:; response_count:1; response_revision:900; }","duration":"455.250157ms","start":"2026-02-01T19:01:12.802139Z","end":"2026-02-01T19:01:13.257389Z","steps":["trace[643529639] 'agreement among raft nodes before linearized reading' (duration: 455.190825ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:13.257408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:12.802126Z","time spent":"455.278297ms","remote":"127.0.0.1:35692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/162.253.55.52\" "} {"level":"info","ts":"2026-02-01T19:01:13.285352Z","caller":"traceutil/trace.go:171","msg":"trace[552878842] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"148.751039ms","start":"2026-02-01T19:01:13.136592Z","end":"2026-02-01T19:01:13.285344Z","steps":["trace[552878842] 'process raft request' (duration: 148.695528ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:13.285591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.822304ms","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-01T19:01:13.285609Z","caller":"traceutil/trace.go:171","msg":"trace[1000976383] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:901; }","duration":"353.853635ms","start":"2026-02-01T19:01:12.93175Z","end":"2026-02-01T19:01:13.285604Z","steps":["trace[1000976383] 'agreement among raft nodes before linearized reading' (duration: 353.799014ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:13.285622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:12.931745Z","time spent":"353.873426ms","remote":"127.0.0.1:35966","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-01T19:01:13.286659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.3014ms","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-01T19:01:13.286676Z","caller":"traceutil/trace.go:171","msg":"trace[421149007] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:901; }","duration":"355.325821ms","start":"2026-02-01T19:01:12.931346Z","end":"2026-02-01T19:01:13.286672Z","steps":["trace[421149007] 'agreement among raft nodes before linearized reading' (duration: 355.27626ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:13.28669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:12.931344Z","time spent":"355.343241ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-01T19:01:13.286772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.432443ms","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-01T19:01:13.286784Z","caller":"traceutil/trace.go:171","msg":"trace[1385463837] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:901; }","duration":"355.453374ms","start":"2026-02-01T19:01:12.931327Z","end":"2026-02-01T19:01:13.28678Z","steps":["trace[1385463837] 'agreement among raft nodes before linearized reading' (duration: 355.419604ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:13.286795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:12.931322Z","time spent":"355.470525ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-01T19:01:13.286861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.477084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-01T19:01:13.286886Z","caller":"traceutil/trace.go:171","msg":"trace[761438681] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:901; }","duration":"360.528575ms","start":"2026-02-01T19:01:12.926354Z","end":"2026-02-01T19:01:13.286882Z","steps":["trace[761438681] 'agreement among raft nodes before linearized reading' (duration: 360.482814ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:13.2869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:12.926339Z","time spent":"360.557916ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-01T19:01:13.468065Z","caller":"traceutil/trace.go:171","msg":"trace[1854262806] linearizableReadLoop","detail":"{readStateIndex:941; appliedIndex:936; }","duration":"173.182088ms","start":"2026-02-01T19:01:13.294869Z","end":"2026-02-01T19:01:13.468051Z","steps":["trace[1854262806] 'read index received' (duration: 101.501533ms)","trace[1854262806] 'applied index is now lower than readState.Index' (duration: 71.680025ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T19:01:13.468217Z","caller":"traceutil/trace.go:171","msg":"trace[780849224] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"178.721641ms","start":"2026-02-01T19:01:13.289489Z","end":"2026-02-01T19:01:13.46821Z","steps":["trace[780849224] 'process raft request' (duration: 106.876542ms)","trace[780849224] 'compare' (duration: 71.541782ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T19:01:13.468302Z","caller":"traceutil/trace.go:171","msg":"trace[1864029366] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"178.773703ms","start":"2026-02-01T19:01:13.289524Z","end":"2026-02-01T19:01:13.468298Z","steps":["trace[1864029366] 'process raft request' (duration: 178.453995ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:01:13.468351Z","caller":"traceutil/trace.go:171","msg":"trace[1507051220] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"177.374968ms","start":"2026-02-01T19:01:13.290972Z","end":"2026-02-01T19:01:13.468347Z","steps":["trace[1507051220] 'process raft request' (duration: 177.02583ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:01:13.468396Z","caller":"traceutil/trace.go:171","msg":"trace[1971917076] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"175.270987ms","start":"2026-02-01T19:01:13.293122Z","end":"2026-02-01T19:01:13.468393Z","steps":["trace[1971917076] 'process raft request' (duration: 174.892748ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:01:13.468441Z","caller":"traceutil/trace.go:171","msg":"trace[699597080] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"173.597907ms","start":"2026-02-01T19:01:13.294839Z","end":"2026-02-01T19:01:13.468436Z","steps":["trace[699597080] 'process raft request' (duration: 173.193868ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:13.468528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.645879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T19:01:13.468544Z","caller":"traceutil/trace.go:171","msg":"trace[1813641884] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:906; }","duration":"173.68023ms","start":"2026-02-01T19:01:13.294859Z","end":"2026-02-01T19:01:13.46854Z","steps":["trace[1813641884] 'agreement among raft nodes before linearized reading' (duration: 173.642209ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:13.46862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.790578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T19:01:13.468632Z","caller":"traceutil/trace.go:171","msg":"trace[848936299] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:906; }","duration":"155.806169ms","start":"2026-02-01T19:01:13.312822Z","end":"2026-02-01T19:01:13.468628Z","steps":["trace[848936299] 'agreement among raft nodes before linearized reading' (duration: 155.783588ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:01:15.536471Z","caller":"traceutil/trace.go:171","msg":"trace[1513195254] linearizableReadLoop","detail":"{readStateIndex:942; appliedIndex:941; }","duration":"246.701897ms","start":"2026-02-01T19:01:15.289755Z","end":"2026-02-01T19:01:15.536457Z","steps":["trace[1513195254] 'read index received' (duration: 246.605055ms)","trace[1513195254] 'applied index is now lower than readState.Index' (duration: 96.142µs)"],"step_count":2} {"level":"info","ts":"2026-02-01T19:01:15.536544Z","caller":"traceutil/trace.go:171","msg":"trace[1531900499] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"273.245575ms","start":"2026-02-01T19:01:15.263294Z","end":"2026-02-01T19:01:15.53654Z","steps":["trace[1531900499] 'process raft request' (duration: 273.066321ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:15.536871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.104397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T19:01:15.536888Z","caller":"traceutil/trace.go:171","msg":"trace[1285180190] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:907; }","duration":"247.168669ms","start":"2026-02-01T19:01:15.289714Z","end":"2026-02-01T19:01:15.536883Z","steps":["trace[1285180190] 'agreement among raft nodes before linearized reading' (duration: 247.131887ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:01:15.737944Z","caller":"traceutil/trace.go:171","msg":"trace[931824019] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"188.840984ms","start":"2026-02-01T19:01:15.549078Z","end":"2026-02-01T19:01:15.737919Z","steps":["trace[931824019] 'process raft request' (duration: 96.721237ms)","trace[931824019] 'compare' (duration: 92.016955ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T19:01:16.512804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.605292ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T19:01:16.512955Z","caller":"traceutil/trace.go:171","msg":"trace[1061481852] linearizableReadLoop","detail":"{readStateIndex:946; appliedIndex:943; }","duration":"386.133763ms","start":"2026-02-01T19:01:16.126811Z","end":"2026-02-01T19:01:16.512945Z","steps":["trace[1061481852] 'read index received' (duration: 2.351957ms)","trace[1061481852] 'applied index is now lower than readState.Index' (duration: 383.781316ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T19:01:16.513006Z","caller":"traceutil/trace.go:171","msg":"trace[757601429] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"962.724998ms","start":"2026-02-01T19:01:15.550274Z","end":"2026-02-01T19:01:16.512999Z","steps":["trace[757601429] 'process raft request' (duration: 578.87063ms)","trace[757601429] 'compare' (duration: 383.258954ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T19:01:16.513033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:15.550268Z","time spent":"962.749879ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T19:01:16.513154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.243784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-01T19:01:16.513167Z","caller":"traceutil/trace.go:171","msg":"trace[1504948208] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:911; }","duration":"334.293955ms","start":"2026-02-01T19:01:16.178869Z","end":"2026-02-01T19:01:16.513163Z","steps":["trace[1504948208] 'agreement among raft nodes before linearized reading' (duration: 334.268534ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:16.513181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:16.17885Z","time spent":"334.327955ms","remote":"127.0.0.1:35880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":31,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-02-01T19:01:16.513213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.378749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-01T19:01:16.513271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.811651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T19:01:16.513284Z","caller":"traceutil/trace.go:171","msg":"trace[1889426759] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:911; }","duration":"190.847713ms","start":"2026-02-01T19:01:16.322433Z","end":"2026-02-01T19:01:16.51328Z","steps":["trace[1889426759] 'agreement among raft nodes before linearized reading' (duration: 190.826882ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:01:16.513278Z","caller":"traceutil/trace.go:171","msg":"trace[1664745682] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:911; }","duration":"386.47912ms","start":"2026-02-01T19:01:16.126783Z","end":"2026-02-01T19:01:16.513262Z","steps":["trace[1664745682] 'agreement among raft nodes before linearized reading' (duration: 386.321047ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:16.51332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:16.126769Z","time spent":"386.541522ms","remote":"127.0.0.1:35752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":112,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-02-01T19:01:16.513478Z","caller":"traceutil/trace.go:171","msg":"trace[1130717049] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"963.136908ms","start":"2026-02-01T19:01:15.550336Z","end":"2026-02-01T19:01:16.513473Z","steps":["trace[1130717049] 'process raft request' (duration: 962.585595ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:16.513505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:15.550333Z","time spent":"963.157549ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T19:01:16.51356Z","caller":"traceutil/trace.go:171","msg":"trace[1138528956] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"963.24485ms","start":"2026-02-01T19:01:15.550312Z","end":"2026-02-01T19:01:16.513557Z","steps":["trace[1138528956] 'process raft request' (duration: 962.554614ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:16.513581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:15.550308Z","time spent":"963.261492ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T19:01:17.936155Z","caller":"traceutil/trace.go:171","msg":"trace[1584833228] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"175.544334ms","start":"2026-02-01T19:01:17.760594Z","end":"2026-02-01T19:01:17.936139Z","steps":["trace[1584833228] 'process raft request' (duration: 129.752203ms)","trace[1584833228] 'compare' (duration: 45.565516ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T19:01:23.615645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.331352678s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T19:01:23.615704Z","caller":"traceutil/trace.go:171","msg":"trace[696303908] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:924; }","duration":"1.331440941s","start":"2026-02-01T19:01:22.284251Z","end":"2026-02-01T19:01:23.615692Z","steps":["trace[696303908] 'range keys from in-memory index tree' (duration: 1.331305307s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:23.615726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:22.284238Z","time spent":"1.331484091s","remote":"127.0.0.1:35660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-01T19:01:23.615939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.080424899s","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-01T19:01:23.615974Z","caller":"traceutil/trace.go:171","msg":"trace[1402717364] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:924; }","duration":"1.080472941s","start":"2026-02-01T19:01:22.535475Z","end":"2026-02-01T19:01:23.615948Z","steps":["trace[1402717364] 'range keys from in-memory index tree' (duration: 1.080322547s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:23.615986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:22.535456Z","time spent":"1.080526852s","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-01T19:01:23.616071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.079240853s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-01T19:01:23.616083Z","caller":"traceutil/trace.go:171","msg":"trace[1460089304] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:924; }","duration":"1.079270943s","start":"2026-02-01T19:01:22.536808Z","end":"2026-02-01T19:01:23.616079Z","steps":["trace[1460089304] 'range keys from in-memory index tree' (duration: 1.079180571s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:23.616096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:22.536799Z","time spent":"1.079292464s","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-01T19:01:23.616186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.07709768s","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-01T19:01:23.616198Z","caller":"traceutil/trace.go:171","msg":"trace[1007359718] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:924; }","duration":"1.077168973s","start":"2026-02-01T19:01:22.539025Z","end":"2026-02-01T19:01:23.616194Z","steps":["trace[1007359718] 'range keys from in-memory index tree' (duration: 1.076996738s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:23.616213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:22.539003Z","time spent":"1.077207373s","remote":"127.0.0.1:35966","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-01T19:01:23.616319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"792.047891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.52\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-01T19:01:23.616331Z","caller":"traceutil/trace.go:171","msg":"trace[154545778] range","detail":"{range_begin:/registry/masterleases/162.253.55.52; range_end:; response_count:1; response_revision:924; }","duration":"792.117343ms","start":"2026-02-01T19:01:22.82421Z","end":"2026-02-01T19:01:23.616327Z","steps":["trace[154545778] 'range keys from in-memory index tree' (duration: 791.937539ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:23.616342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:22.824187Z","time spent":"792.152184ms","remote":"127.0.0.1:35692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/162.253.55.52\" "} {"level":"warn","ts":"2026-02-01T19:01:24.111302Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594528889926121703,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-01T19:01:24.482254Z","caller":"traceutil/trace.go:171","msg":"trace[23478775] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"1.191109884s","start":"2026-02-01T19:01:23.291128Z","end":"2026-02-01T19:01:24.482238Z","steps":["trace[23478775] 'process raft request' (duration: 1.190889379s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:24.482375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:23.291107Z","time spent":"1.191208527s","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T19:01:24.505789Z","caller":"traceutil/trace.go:171","msg":"trace[1142134514] linearizableReadLoop","detail":"{readStateIndex:965; appliedIndex:960; }","duration":"895.190032ms","start":"2026-02-01T19:01:23.610585Z","end":"2026-02-01T19:01:24.505775Z","steps":["trace[1142134514] 'read index received' (duration: 871.481522ms)","trace[1142134514] 'applied index is now lower than readState.Index' (duration: 23.70804ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T19:01:24.505939Z","caller":"traceutil/trace.go:171","msg":"trace[1267638776] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"448.973934ms","start":"2026-02-01T19:01:24.056959Z","end":"2026-02-01T19:01:24.505933Z","steps":["trace[1267638776] 'process raft request' (duration: 448.661986ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:24.506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:24.056947Z","time spent":"449.010915ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T19:01:24.506092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:24.057097Z","time spent":"448.994675ms","remote":"127.0.0.1:35692","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-01T19:01:24.506161Z","caller":"traceutil/trace.go:171","msg":"trace[1883084540] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"437.632481ms","start":"2026-02-01T19:01:24.068524Z","end":"2026-02-01T19:01:24.506157Z","steps":["trace[1883084540] 'process raft request' (duration: 437.199281ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:24.506194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:24.068498Z","time spent":"437.681583ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T19:01:24.506252Z","caller":"traceutil/trace.go:171","msg":"trace[1249732534] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"427.344963ms","start":"2026-02-01T19:01:24.078903Z","end":"2026-02-01T19:01:24.506248Z","steps":["trace[1249732534] 'process raft request' (duration: 426.851712ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:24.506279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:24.078888Z","time spent":"427.377094ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T19:01:24.506389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"895.804176ms","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-01T19:01:24.506409Z","caller":"traceutil/trace.go:171","msg":"trace[1787075289] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:928; }","duration":"895.864898ms","start":"2026-02-01T19:01:23.61054Z","end":"2026-02-01T19:01:24.506404Z","steps":["trace[1787075289] 'agreement among raft nodes before linearized reading' (duration: 895.787426ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:24.506423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:23.610524Z","time spent":"895.896138ms","remote":"127.0.0.1:35966","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":"warn","ts":"2026-02-01T19:01:24.506488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.764367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-01T19:01:24.506506Z","caller":"traceutil/trace.go:171","msg":"trace[535348503] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:928; }","duration":"450.820148ms","start":"2026-02-01T19:01:24.055682Z","end":"2026-02-01T19:01:24.506503Z","steps":["trace[535348503] 'agreement among raft nodes before linearized reading' (duration: 450.788418ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:24.506519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:24.055669Z","time spent":"450.84741ms","remote":"127.0.0.1:35720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":11,"response size":31,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-02-01T19:01:24.506669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.66019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-01T19:01:24.506686Z","caller":"traceutil/trace.go:171","msg":"trace[889612034] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:928; }","duration":"139.710222ms","start":"2026-02-01T19:01:24.366972Z","end":"2026-02-01T19:01:24.506682Z","steps":["trace[889612034] 'agreement among raft nodes before linearized reading' (duration: 139.681171ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:24.506772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.755733ms","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-01T19:01:24.50679Z","caller":"traceutil/trace.go:171","msg":"trace[1313923775] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:928; }","duration":"449.781533ms","start":"2026-02-01T19:01:24.057004Z","end":"2026-02-01T19:01:24.506785Z","steps":["trace[1313923775] 'agreement among raft nodes before linearized reading' (duration: 449.740862ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:24.506802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:24.057Z","time spent":"449.799213ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-01T19:01:24.506847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.372007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T19:01:24.506861Z","caller":"traceutil/trace.go:171","msg":"trace[1787342249] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:928; }","duration":"450.399228ms","start":"2026-02-01T19:01:24.056458Z","end":"2026-02-01T19:01:24.506857Z","steps":["trace[1787342249] 'agreement among raft nodes before linearized reading' (duration: 450.377827ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:24.506872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:24.056451Z","time spent":"450.418189ms","remote":"127.0.0.1:35676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-01T19:01:24.961432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.029662ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T19:01:24.961536Z","caller":"traceutil/trace.go:171","msg":"trace[538048828] linearizableReadLoop","detail":"{readStateIndex:968; appliedIndex:966; }","duration":"435.960242ms","start":"2026-02-01T19:01:24.525564Z","end":"2026-02-01T19:01:24.961524Z","steps":["trace[538048828] 'read index received' (duration: 41.603642ms)","trace[538048828] 'applied index is now lower than readState.Index' (duration: 394.35599ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T19:01:24.961671Z","caller":"traceutil/trace.go:171","msg":"trace[985468939] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"448.940163ms","start":"2026-02-01T19:01:24.512724Z","end":"2026-02-01T19:01:24.961664Z","steps":["trace[985468939] 'process raft request' (duration: 305.630035ms)","trace[985468939] 'compare' (duration: 142.96567ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T19:01:24.961707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:24.512709Z","time spent":"448.981115ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T19:01:24.961827Z","caller":"traceutil/trace.go:171","msg":"trace[1151535250] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"440.780977ms","start":"2026-02-01T19:01:24.521042Z","end":"2026-02-01T19:01:24.961823Z","steps":["trace[1151535250] 'process raft request' (duration: 440.45573ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:24.961851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:24.521004Z","time spent":"440.833339ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T19:01:24.961914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.350211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T19:01:24.961927Z","caller":"traceutil/trace.go:171","msg":"trace[1743416486] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:931; }","duration":"436.362562ms","start":"2026-02-01T19:01:24.525559Z","end":"2026-02-01T19:01:24.961922Z","steps":["trace[1743416486] 'agreement among raft nodes before linearized reading' (duration: 436.339001ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:24.96194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:24.525518Z","time spent":"436.419843ms","remote":"127.0.0.1:35660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-01T19:01:24.962022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.079449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-02-01T19:01:24.962034Z","caller":"traceutil/trace.go:171","msg":"trace[806881067] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:931; }","duration":"393.11904ms","start":"2026-02-01T19:01:24.568911Z","end":"2026-02-01T19:01:24.96203Z","steps":["trace[806881067] 'agreement among raft nodes before linearized reading' (duration: 393.09408ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:24.962047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:24.568898Z","time spent":"393.146221ms","remote":"127.0.0.1:35864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-02-01T19:01:24.96217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.082944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-01T19:01:24.962184Z","caller":"traceutil/trace.go:171","msg":"trace[447131336] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:931; }","duration":"249.097505ms","start":"2026-02-01T19:01:24.713081Z","end":"2026-02-01T19:01:24.962179Z","steps":["trace[447131336] 'agreement among raft nodes before linearized reading' (duration: 249.072744ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:24.962255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.327935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T19:01:24.962266Z","caller":"traceutil/trace.go:171","msg":"trace[1732067947] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:931; }","duration":"366.375867ms","start":"2026-02-01T19:01:24.595887Z","end":"2026-02-01T19:01:24.962263Z","steps":["trace[1732067947] 'agreement among raft nodes before linearized reading' (duration: 366.357416ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:24.962278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:24.595858Z","time spent":"366.416468ms","remote":"127.0.0.1:55872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-02-01T19:01:25.540219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.147286ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T19:01:25.540313Z","caller":"traceutil/trace.go:171","msg":"trace[562917751] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:931; }","duration":"360.253999ms","start":"2026-02-01T19:01:25.180046Z","end":"2026-02-01T19:01:25.5403Z","steps":["trace[562917751] 'range keys from in-memory index tree' (duration: 360.140146ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:01:28.557624Z","caller":"traceutil/trace.go:171","msg":"trace[403611816] linearizableReadLoop","detail":"{readStateIndex:974; appliedIndex:973; }","duration":"269.26162ms","start":"2026-02-01T19:01:28.288341Z","end":"2026-02-01T19:01:28.557602Z","steps":["trace[403611816] 'read index received' (duration: 268.981463ms)","trace[403611816] 'applied index is now lower than readState.Index' (duration: 279.277µs)"],"step_count":2} {"level":"info","ts":"2026-02-01T19:01:28.558084Z","caller":"traceutil/trace.go:171","msg":"trace[214493523] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"406.817981ms","start":"2026-02-01T19:01:28.151253Z","end":"2026-02-01T19:01:28.558071Z","steps":["trace[214493523] 'process raft request' (duration: 406.133644ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:28.558847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:28.151244Z","time spent":"407.525488ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T19:01:28.559201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.854319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T19:01:28.559622Z","caller":"traceutil/trace.go:171","msg":"trace[2009781826] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:937; }","duration":"271.307409ms","start":"2026-02-01T19:01:28.288278Z","end":"2026-02-01T19:01:28.559585Z","steps":["trace[2009781826] 'agreement among raft nodes before linearized reading' (duration: 270.890329ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:29.058759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.212165ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-01T19:01:29.059402Z","caller":"traceutil/trace.go:171","msg":"trace[1943854615] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"492.283407ms","start":"2026-02-01T19:01:28.567098Z","end":"2026-02-01T19:01:29.059382Z","steps":["trace[1943854615] 'process raft request' (duration: 491.742843ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:29.059514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:28.567084Z","time spent":"492.371609ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T19:01:29.798305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"738.875161ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T19:01:29.798423Z","caller":"traceutil/trace.go:171","msg":"trace[852642555] linearizableReadLoop","detail":"{readStateIndex:978; appliedIndex:974; }","duration":"821.876639ms","start":"2026-02-01T19:01:28.976534Z","end":"2026-02-01T19:01:29.798411Z","steps":["trace[852642555] 'read index received' (duration: 55.891µs)","trace[852642555] 'applied index is now lower than readState.Index' (duration: 821.820268ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T19:01:29.798533Z","caller":"traceutil/trace.go:171","msg":"trace[1723026687] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"1.229569959s","start":"2026-02-01T19:01:28.568955Z","end":"2026-02-01T19:01:29.798525Z","steps":["trace[1723026687] 'process raft request' (duration: 490.416732ms)","trace[1723026687] 'compare' (duration: 738.219615ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T19:01:29.798563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:28.568939Z","time spent":"1.22960835s","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T19:01:29.798659Z","caller":"traceutil/trace.go:171","msg":"trace[403928177] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"1.228330329s","start":"2026-02-01T19:01:28.570323Z","end":"2026-02-01T19:01:29.798654Z","steps":["trace[403928177] 'process raft request' (duration: 1.228059213s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:29.798683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:28.57031Z","time spent":"1.228358972s","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T19:01:29.798794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"822.268019ms","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-01T19:01:29.79881Z","caller":"traceutil/trace.go:171","msg":"trace[1219955345] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:940; }","duration":"822.33394ms","start":"2026-02-01T19:01:28.976469Z","end":"2026-02-01T19:01:29.798803Z","steps":["trace[1219955345] 'agreement among raft nodes before linearized reading' (duration: 822.262418ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:29.798824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:28.976445Z","time spent":"822.376271ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-01T19:01:29.798903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"819.387209ms","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-01T19:01:29.798915Z","caller":"traceutil/trace.go:171","msg":"trace[2015178031] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:940; }","duration":"819.44341ms","start":"2026-02-01T19:01:28.979467Z","end":"2026-02-01T19:01:29.798911Z","steps":["trace[2015178031] 'agreement among raft nodes before linearized reading' (duration: 819.40988ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:29.798926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:28.979444Z","time spent":"819.479762ms","remote":"127.0.0.1:35966","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":"warn","ts":"2026-02-01T19:01:29.80159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"701.154205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T19:01:29.801615Z","caller":"traceutil/trace.go:171","msg":"trace[735708455] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:940; }","duration":"701.217026ms","start":"2026-02-01T19:01:29.100392Z","end":"2026-02-01T19:01:29.801609Z","steps":["trace[735708455] 'agreement among raft nodes before linearized reading' (duration: 698.637834ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:29.801648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:29.100377Z","time spent":"701.259667ms","remote":"127.0.0.1:36132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-02-01T19:01:33.149301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.857519ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-02-01T19:01:34.145292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.33992ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T19:01:34.145413Z","caller":"traceutil/trace.go:171","msg":"trace[2010053378] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"241.212755ms","start":"2026-02-01T19:01:33.904177Z","end":"2026-02-01T19:01:34.145389Z","steps":["trace[2010053378] 'process raft request' (duration: 129.693041ms)","trace[2010053378] 'compare' (duration: 111.116015ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T19:01:39.619591Z","caller":"traceutil/trace.go:171","msg":"trace[226336470] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"430.214033ms","start":"2026-02-01T19:01:39.189321Z","end":"2026-02-01T19:01:39.619535Z","steps":["trace[226336470] 'process raft request' (duration: 429.966957ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:01:39.619775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:01:39.1893Z","time spent":"430.381246ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T19:01:44.622817Z","caller":"traceutil/trace.go:171","msg":"trace[1842985856] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"166.611355ms","start":"2026-02-01T19:01:44.456181Z","end":"2026-02-01T19:01:44.622792Z","steps":["trace[1842985856] 'process raft request' (duration: 166.486482ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:04:40.612337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.198276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-01T19:04:40.612443Z","caller":"traceutil/trace.go:171","msg":"trace[314729385] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1768; }","duration":"128.400981ms","start":"2026-02-01T19:04:40.484024Z","end":"2026-02-01T19:04:40.612425Z","steps":["trace[314729385] 'count revisions from in-memory index tree' (duration: 128.105304ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:05:00.657668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.788641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-02-01T19:05:00.657732Z","caller":"traceutil/trace.go:171","msg":"trace[1458570109] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1872; }","duration":"142.887142ms","start":"2026-02-01T19:05:00.514828Z","end":"2026-02-01T19:05:00.657715Z","steps":["trace[1458570109] 'count revisions from in-memory index tree' (duration: 142.682048ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:05:01.465557Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594528889926124096,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-01T19:05:01.861283Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.017210974s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-01T19:05:02.07949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:05:01.150357Z","time spent":"929.125926ms","remote":"127.0.0.1:44950","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-01T19:05:02.080847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.658532ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T19:05:02.080909Z","caller":"traceutil/trace.go:171","msg":"trace[1207123403] linearizableReadLoop","detail":"{readStateIndex:1956; appliedIndex:1955; }","duration":"1.116378119s","start":"2026-02-01T19:05:00.964517Z","end":"2026-02-01T19:05:02.080895Z","steps":["trace[1207123403] 'read index received' (duration: 904.35361ms)","trace[1207123403] 'applied index is now lower than readState.Index' (duration: 212.023479ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T19:05:02.081525Z","caller":"traceutil/trace.go:171","msg":"trace[803547020] transaction","detail":"{read_only:false; response_revision:1875; number_of_response:1; }","duration":"1.237503847s","start":"2026-02-01T19:05:00.843985Z","end":"2026-02-01T19:05:02.081489Z","steps":["trace[803547020] 'process raft request' (duration: 1.025131308s)","trace[803547020] 'compare' (duration: 211.249442ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T19:05:02.081665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:05:00.843962Z","time spent":"1.237621759s","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T19:05:02.08197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.117452695s","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-01T19:05:02.082001Z","caller":"traceutil/trace.go:171","msg":"trace[110293453] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1875; }","duration":"1.117509457s","start":"2026-02-01T19:05:00.964484Z","end":"2026-02-01T19:05:02.081993Z","steps":["trace[110293453] 'agreement among raft nodes before linearized reading' (duration: 1.117384784s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:05:02.082022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:05:00.96447Z","time spent":"1.117547747s","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-01T19:05:02.082111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.095162206s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-01T19:05:02.082132Z","caller":"traceutil/trace.go:171","msg":"trace[187833525] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1875; }","duration":"1.095215038s","start":"2026-02-01T19:05:00.98691Z","end":"2026-02-01T19:05:02.082125Z","steps":["trace[187833525] 'agreement among raft nodes before linearized reading' (duration: 1.095163266s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:05:02.082151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:05:00.986891Z","time spent":"1.095255369s","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-01T19:05:02.082318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.900546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-01T19:05:02.082336Z","caller":"traceutil/trace.go:171","msg":"trace[1984358648] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1875; }","duration":"376.952736ms","start":"2026-02-01T19:05:01.705378Z","end":"2026-02-01T19:05:02.08233Z","steps":["trace[1984358648] 'agreement among raft nodes before linearized reading' (duration: 376.910115ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:05:02.082355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:05:01.705363Z","time spent":"376.987488ms","remote":"127.0.0.1:35782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":15,"response size":31,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-02-01T19:05:02.082528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"937.372488ms","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":"warn","ts":"2026-02-01T19:05:02.082562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.966403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-01T19:05:02.08257Z","caller":"traceutil/trace.go:171","msg":"trace[1647561821] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1875; }","duration":"937.44526ms","start":"2026-02-01T19:05:01.145113Z","end":"2026-02-01T19:05:02.082558Z","steps":["trace[1647561821] 'agreement among raft nodes before linearized reading' (duration: 937.305656ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T19:05:02.082583Z","caller":"traceutil/trace.go:171","msg":"trace[1331721417] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1875; }","duration":"462.019895ms","start":"2026-02-01T19:05:01.620557Z","end":"2026-02-01T19:05:02.082577Z","steps":["trace[1331721417] 'agreement among raft nodes before linearized reading' (duration: 461.957984ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:05:02.082603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:05:01.620539Z","time spent":"462.060405ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-02-01T19:05:02.082607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:05:01.145101Z","time spent":"937.49846ms","remote":"127.0.0.1:35966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-01T19:05:02.082654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"794.124717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T19:05:02.082668Z","caller":"traceutil/trace.go:171","msg":"trace[2042205399] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1875; }","duration":"794.180679ms","start":"2026-02-01T19:05:01.288483Z","end":"2026-02-01T19:05:02.082663Z","steps":["trace[2042205399] 'agreement among raft nodes before linearized reading' (duration: 794.158848ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T19:05:02.082685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T19:05:01.288471Z","time spent":"794.208739ms","remote":"127.0.0.1:35660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "}