{"level":"warn","ts":"2026-01-19T16:07:57.517001Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-19T16:07:57.517135Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.206:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.206:2380","--initial-cluster=instance=https://199.19.213.206:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.206:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.206: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-01-19T16:07:57.522269Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-19T16:07:57.5223Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.206:2380"]} {"level":"info","ts":"2026-01-19T16:07:57.522458Z","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-01-19T16:07:57.523435Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.206:2379"]} {"level":"info","ts":"2026-01-19T16:07:57.527262Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.206:2380"],"listen-peer-urls":["https://199.19.213.206:2380"],"advertise-client-urls":["https://199.19.213.206:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.206:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.206: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-01-19T16:07:57.626266Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"95.542188ms"} {"level":"info","ts":"2026-01-19T16:07:57.670696Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b696d7a595057bfe","cluster-id":"9b00ba6f3f3b7ac2"} {"level":"info","ts":"2026-01-19T16:07:57.670907Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b696d7a595057bfe switched to configuration voters=()"} {"level":"info","ts":"2026-01-19T16:07:57.670943Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b696d7a595057bfe became follower at term 0"} {"level":"info","ts":"2026-01-19T16:07:57.670981Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b696d7a595057bfe [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-01-19T16:07:57.671001Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b696d7a595057bfe became follower at term 1"} {"level":"info","ts":"2026-01-19T16:07:57.671043Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b696d7a595057bfe switched to configuration voters=(13156940467579223038)"} {"level":"warn","ts":"2026-01-19T16:07:57.694112Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-01-19T16:07:57.7122Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-01-19T16:07:57.738129Z","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-01-19T16:07:57.831103Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b696d7a595057bfe","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-01-19T16:07:57.831507Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b696d7a595057bfe","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-01-19T16:07:57.831594Z","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-01-19T16:07:57.831655Z","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-01-19T16:07:57.831674Z","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-01-19T16:07:57.856747Z","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-01-19T16:07:57.857035Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b696d7a595057bfe","initial-advertise-peer-urls":["https://199.19.213.206:2380"],"listen-peer-urls":["https://199.19.213.206:2380"],"advertise-client-urls":["https://199.19.213.206:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.206:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-01-19T16:07:57.85717Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.206:2380"} {"level":"info","ts":"2026-01-19T16:07:57.857207Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.206:2380"} {"level":"info","ts":"2026-01-19T16:07:57.857061Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-01-19T16:07:57.871284Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b696d7a595057bfe switched to configuration voters=(13156940467579223038)"} {"level":"info","ts":"2026-01-19T16:07:57.871404Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9b00ba6f3f3b7ac2","local-member-id":"b696d7a595057bfe","added-peer-id":"b696d7a595057bfe","added-peer-peer-urls":["https://199.19.213.206:2380"]} {"level":"info","ts":"2026-01-19T16:07:57.971595Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b696d7a595057bfe is starting a new election at term 1"} {"level":"info","ts":"2026-01-19T16:07:57.971637Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b696d7a595057bfe became pre-candidate at term 1"} {"level":"info","ts":"2026-01-19T16:07:57.971809Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b696d7a595057bfe received MsgPreVoteResp from b696d7a595057bfe at term 1"} {"level":"info","ts":"2026-01-19T16:07:57.971903Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b696d7a595057bfe became candidate at term 2"} {"level":"info","ts":"2026-01-19T16:07:57.971938Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b696d7a595057bfe received MsgVoteResp from b696d7a595057bfe at term 2"} {"level":"info","ts":"2026-01-19T16:07:57.972019Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b696d7a595057bfe became leader at term 2"} {"level":"info","ts":"2026-01-19T16:07:57.972081Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b696d7a595057bfe elected leader b696d7a595057bfe at term 2"} {"level":"info","ts":"2026-01-19T16:07:58.034238Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b696d7a595057bfe","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.206:2379]}","request-path":"/0/members/b696d7a595057bfe/attributes","cluster-id":"9b00ba6f3f3b7ac2","publish-timeout":"7s"} {"level":"info","ts":"2026-01-19T16:07:58.034423Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-19T16:07:58.034788Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-01-19T16:07:58.03492Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-19T16:07:58.035149Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-01-19T16:07:58.035182Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-01-19T16:07:58.037862Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-01-19T16:07:58.050781Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.206:2379"} {"level":"info","ts":"2026-01-19T16:07:58.061952Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9b00ba6f3f3b7ac2","local-member-id":"b696d7a595057bfe","cluster-version":"3.5"} {"level":"info","ts":"2026-01-19T16:07:58.062072Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-01-19T16:07:58.062125Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-01-19T16:08:00.099022Z","caller":"traceutil/trace.go:171","msg":"trace[130413609] linearizableReadLoop","detail":"{readStateIndex:7; appliedIndex:4; }","duration":"236.499264ms","start":"2026-01-19T16:07:59.862501Z","end":"2026-01-19T16:08:00.099001Z","steps":["trace[130413609] 'read index received' (duration: 92.435449ms)","trace[130413609] 'applied index is now lower than readState.Index' (duration: 144.063015ms)"],"step_count":2} {"level":"info","ts":"2026-01-19T16:08:00.099296Z","caller":"traceutil/trace.go:171","msg":"trace[1891163815] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"196.779442ms","start":"2026-01-19T16:07:59.902505Z","end":"2026-01-19T16:08:00.099284Z","steps":["trace[1891163815] 'process raft request' (duration: 196.332251ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:08:00.099571Z","caller":"traceutil/trace.go:171","msg":"trace[1252245055] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"196.208198ms","start":"2026-01-19T16:07:59.903349Z","end":"2026-01-19T16:08:00.099557Z","steps":["trace[1252245055] 'process raft request' (duration: 195.520152ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:08:00.099785Z","caller":"traceutil/trace.go:171","msg":"trace[175285601] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"242.28119ms","start":"2026-01-19T16:07:59.85747Z","end":"2026-01-19T16:08:00.099751Z","steps":["trace[175285601] 'process raft request' (duration: 198.188103ms)","trace[175285601] 'compare' (duration: 43.000911ms)"],"step_count":2} {"level":"info","ts":"2026-01-19T16:08:00.100297Z","caller":"traceutil/trace.go:171","msg":"trace[1762750355] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"237.799295ms","start":"2026-01-19T16:07:59.862459Z","end":"2026-01-19T16:08:00.100259Z","steps":["trace[1762750355] 'process raft request' (duration: 236.32998ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:08:00.100419Z","caller":"traceutil/trace.go:171","msg":"trace[1241904653] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"196.773541ms","start":"2026-01-19T16:07:59.903635Z","end":"2026-01-19T16:08:00.100409Z","steps":["trace[1241904653] 'process raft request' (duration: 195.326437ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:08:00.100478Z","caller":"traceutil/trace.go:171","msg":"trace[1309417698] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"197.032527ms","start":"2026-01-19T16:07:59.903438Z","end":"2026-01-19T16:08:00.100471Z","steps":["trace[1309417698] 'process raft request' (duration: 195.455041ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:08:00.100506Z","caller":"traceutil/trace.go:171","msg":"trace[136040696] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"197.018047ms","start":"2026-01-19T16:07:59.90348Z","end":"2026-01-19T16:08:00.100498Z","steps":["trace[136040696] 'process raft request' (duration: 195.43534ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:08:00.100559Z","caller":"traceutil/trace.go:171","msg":"trace[1376575130] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"197.008097ms","start":"2026-01-19T16:07:59.903538Z","end":"2026-01-19T16:08:00.100546Z","steps":["trace[1376575130] 'process raft request' (duration: 195.40219ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:00.101317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.678075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-19T16:08:00.103682Z","caller":"traceutil/trace.go:171","msg":"trace[977135791] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"241.159323ms","start":"2026-01-19T16:07:59.862488Z","end":"2026-01-19T16:08:00.103648Z","steps":["trace[977135791] 'agreement among raft nodes before linearized reading' (duration: 238.351677ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:00.101271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.586916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-19T16:08:00.103993Z","caller":"traceutil/trace.go:171","msg":"trace[1022206139] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:10; }","duration":"239.309779ms","start":"2026-01-19T16:07:59.864655Z","end":"2026-01-19T16:08:00.103965Z","steps":["trace[1022206139] 'agreement among raft nodes before linearized reading' (duration: 236.385791ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:00.101875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.981472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.206\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-19T16:08:00.104408Z","caller":"traceutil/trace.go:171","msg":"trace[1408604037] range","detail":"{range_begin:/registry/masterleases/199.19.213.206; range_end:; response_count:0; response_revision:10; }","duration":"241.791077ms","start":"2026-01-19T16:07:59.862479Z","end":"2026-01-19T16:08:00.10427Z","steps":["trace[1408604037] 'agreement among raft nodes before linearized reading' (duration: 238.872109ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:00.101837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.332886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-19T16:08:00.104835Z","caller":"traceutil/trace.go:171","msg":"trace[899099623] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"189.778017ms","start":"2026-01-19T16:07:59.91505Z","end":"2026-01-19T16:08:00.104828Z","steps":["trace[899099623] 'agreement among raft nodes before linearized reading' (duration: 186.383028ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:00.102005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.705393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-19T16:08:00.105436Z","caller":"traceutil/trace.go:171","msg":"trace[952685765] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"201.02065ms","start":"2026-01-19T16:07:59.904268Z","end":"2026-01-19T16:08:00.105289Z","steps":["trace[952685765] 'agreement among raft nodes before linearized reading' (duration: 197.698782ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:00.10226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.390438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-19T16:08:00.105801Z","caller":"traceutil/trace.go:171","msg":"trace[1399211573] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:10; }","duration":"238.993571ms","start":"2026-01-19T16:07:59.866759Z","end":"2026-01-19T16:08:00.105753Z","steps":["trace[1399211573] 'agreement among raft nodes before linearized reading' (duration: 235.380177ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:08:00.240194Z","caller":"traceutil/trace.go:171","msg":"trace[1255063851] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"120.440445ms","start":"2026-01-19T16:08:00.116956Z","end":"2026-01-19T16:08:00.237396Z","steps":["trace[1255063851] 'process raft request' (duration: 118.201353ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:08:00.241555Z","caller":"traceutil/trace.go:171","msg":"trace[2116818891] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"121.903329ms","start":"2026-01-19T16:08:00.119635Z","end":"2026-01-19T16:08:00.241538Z","steps":["trace[2116818891] 'process raft request' (duration: 115.610042ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:08:00.241811Z","caller":"traceutil/trace.go:171","msg":"trace[91277020] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"122.035472ms","start":"2026-01-19T16:08:00.119764Z","end":"2026-01-19T16:08:00.241799Z","steps":["trace[91277020] 'process raft request' (duration: 115.551041ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:08:00.242238Z","caller":"traceutil/trace.go:171","msg":"trace[2007641056] transaction","detail":"{read_only:false; number_of_response:0; response_revision:17; }","duration":"122.134895ms","start":"2026-01-19T16:08:00.120093Z","end":"2026-01-19T16:08:00.242228Z","steps":["trace[2007641056] 'process raft request' (duration: 115.245565ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:08:00.242647Z","caller":"traceutil/trace.go:171","msg":"trace[1377398282] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"119.967205ms","start":"2026-01-19T16:08:00.122668Z","end":"2026-01-19T16:08:00.242635Z","steps":["trace[1377398282] 'process raft request' (duration: 116.886133ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:00.243164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.40788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3179"} {"level":"info","ts":"2026-01-19T16:08:00.243356Z","caller":"traceutil/trace.go:171","msg":"trace[1766210268] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:19; }","duration":"128.476103ms","start":"2026-01-19T16:08:00.114721Z","end":"2026-01-19T16:08:00.243197Z","steps":["trace[1766210268] 'agreement among raft nodes before linearized reading' (duration: 128.393881ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:08:00.245177Z","caller":"traceutil/trace.go:171","msg":"trace[1356310492] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"131.056102ms","start":"2026-01-19T16:08:00.114106Z","end":"2026-01-19T16:08:00.245162Z","steps":["trace[1356310492] 'process raft request' (duration: 98.961845ms)","trace[1356310492] 'compare' (duration: 21.88615ms)"],"step_count":2} {"level":"info","ts":"2026-01-19T16:08:00.246222Z","caller":"traceutil/trace.go:171","msg":"trace[188004317] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"130.394597ms","start":"2026-01-19T16:08:00.115789Z","end":"2026-01-19T16:08:00.246184Z","steps":["trace[188004317] 'process raft request' (duration: 119.291999ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:08:00.240413Z","caller":"traceutil/trace.go:171","msg":"trace[1065139350] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"120.747273ms","start":"2026-01-19T16:08:00.11911Z","end":"2026-01-19T16:08:00.239857Z","steps":["trace[1065139350] 'process raft request' (duration: 116.101955ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:08:00.573398Z","caller":"traceutil/trace.go:171","msg":"trace[510810029] linearizableReadLoop","detail":"{readStateIndex:33; appliedIndex:31; }","duration":"249.05797ms","start":"2026-01-19T16:08:00.324319Z","end":"2026-01-19T16:08:00.573376Z","steps":["trace[510810029] 'read index received' (duration: 180.457313ms)","trace[510810029] 'applied index is now lower than readState.Index' (duration: 68.599657ms)"],"step_count":2} {"level":"info","ts":"2026-01-19T16:08:00.573426Z","caller":"traceutil/trace.go:171","msg":"trace[1576819199] transaction","detail":"{read_only:false; response_revision:28; number_of_response:1; }","duration":"303.739033ms","start":"2026-01-19T16:08:00.269653Z","end":"2026-01-19T16:08:00.573392Z","steps":["trace[1576819199] 'process raft request' (duration: 235.113645ms)","trace[1576819199] 'compare' (duration: 68.381443ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:08:00.573746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.90387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-19T16:08:00.574516Z","caller":"traceutil/trace.go:171","msg":"trace[484658220] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:29; }","duration":"253.696598ms","start":"2026-01-19T16:08:00.320798Z","end":"2026-01-19T16:08:00.574494Z","steps":["trace[484658220] 'agreement among raft nodes before linearized reading' (duration: 252.839908ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:08:00.573892Z","caller":"traceutil/trace.go:171","msg":"trace[1627001557] transaction","detail":"{read_only:false; response_revision:29; number_of_response:1; }","duration":"302.081405ms","start":"2026-01-19T16:08:00.2718Z","end":"2026-01-19T16:08:00.573881Z","steps":["trace[1627001557] 'process raft request' (duration: 301.530012ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:00.577849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:00.269638Z","time spent":"304.322728ms","remote":"127.0.0.1:45388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3004,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-01-19T16:08:00.577998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:00.271785Z","time spent":"303.235422ms","remote":"127.0.0.1:45802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-19T16:08:00.668106Z","caller":"traceutil/trace.go:171","msg":"trace[1114806655] transaction","detail":"{read_only:false; response_revision:35; number_of_response:1; }","duration":"334.569172ms","start":"2026-01-19T16:08:00.333514Z","end":"2026-01-19T16:08:00.668083Z","steps":["trace[1114806655] 'process raft request' (duration: 334.525841ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:00.66856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.93961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-19T16:08:00.668614Z","caller":"traceutil/trace.go:171","msg":"trace[300414058] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:35; }","duration":"182.997442ms","start":"2026-01-19T16:08:00.485604Z","end":"2026-01-19T16:08:00.668602Z","steps":["trace[300414058] 'agreement among raft nodes before linearized reading' (duration: 182.862479ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:00.669128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.459813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-19T16:08:00.66921Z","caller":"traceutil/trace.go:171","msg":"trace[386526071] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:35; }","duration":"183.574726ms","start":"2026-01-19T16:08:00.485621Z","end":"2026-01-19T16:08:00.669196Z","steps":["trace[386526071] 'agreement among raft nodes before linearized reading' (duration: 183.469773ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:00.669506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:00.333503Z","time spent":"334.686044ms","remote":"127.0.0.1:45802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":950,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-19T16:08:00.670215Z","caller":"traceutil/trace.go:171","msg":"trace[1029523887] transaction","detail":"{read_only:false; response_revision:30; number_of_response:1; }","duration":"343.230124ms","start":"2026-01-19T16:08:00.32697Z","end":"2026-01-19T16:08:00.6702Z","steps":["trace[1029523887] 'process raft request' (duration: 340.785127ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:00.670439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:00.326955Z","time spent":"343.344306ms","remote":"127.0.0.1:45402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":338,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-19T16:08:00.670914Z","caller":"traceutil/trace.go:171","msg":"trace[1144876466] transaction","detail":"{read_only:false; response_revision:31; number_of_response:1; }","duration":"338.216216ms","start":"2026-01-19T16:08:00.332684Z","end":"2026-01-19T16:08:00.670901Z","steps":["trace[1144876466] 'process raft request' (duration: 335.171275ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:00.671133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:00.332671Z","time spent":"338.279928ms","remote":"127.0.0.1:45802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":926,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-19T16:08:00.671378Z","caller":"traceutil/trace.go:171","msg":"trace[499114807] transaction","detail":"{read_only:false; response_revision:32; number_of_response:1; }","duration":"338.566275ms","start":"2026-01-19T16:08:00.332753Z","end":"2026-01-19T16:08:00.671319Z","steps":["trace[499114807] 'process raft request' (duration: 335.170516ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:00.671565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:00.332746Z","time spent":"338.743539ms","remote":"127.0.0.1:45802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":983,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-19T16:08:00.67212Z","caller":"traceutil/trace.go:171","msg":"trace[123780567] transaction","detail":"{read_only:false; response_revision:33; number_of_response:1; }","duration":"339.030375ms","start":"2026-01-19T16:08:00.333076Z","end":"2026-01-19T16:08:00.672106Z","steps":["trace[123780567] 'process raft request' (duration: 334.881079ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:00.672307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:00.333051Z","time spent":"339.151988ms","remote":"127.0.0.1:45702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":713,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-19T16:08:00.672663Z","caller":"traceutil/trace.go:171","msg":"trace[1466858372] transaction","detail":"{read_only:false; response_revision:34; number_of_response:1; }","duration":"339.174969ms","start":"2026-01-19T16:08:00.33345Z","end":"2026-01-19T16:08:00.672625Z","steps":["trace[1466858372] 'process raft request' (duration: 334.543562ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:00.672807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:00.333436Z","time spent":"339.263681ms","remote":"127.0.0.1:45802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":959,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-19T16:08:00.729077Z","caller":"traceutil/trace.go:171","msg":"trace[1277490123] transaction","detail":"{read_only:false; response_revision:36; number_of_response:1; }","duration":"148.790386ms","start":"2026-01-19T16:08:00.580257Z","end":"2026-01-19T16:08:00.729048Z","steps":["trace[1277490123] 'process raft request' (duration: 138.748302ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:00.872043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.3725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-19T16:08:00.874552Z","caller":"traceutil/trace.go:171","msg":"trace[872096903] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:48; }","duration":"109.90383ms","start":"2026-01-19T16:08:00.764635Z","end":"2026-01-19T16:08:00.874539Z","steps":["trace[872096903] 'agreement among raft nodes before linearized reading' (duration: 107.366271ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:01.126123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.995722ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-01-19T16:08:01.126445Z","caller":"traceutil/trace.go:171","msg":"trace[1861714995] transaction","detail":"{read_only:false; response_revision:52; number_of_response:1; }","duration":"198.254315ms","start":"2026-01-19T16:08:00.928165Z","end":"2026-01-19T16:08:01.12642Z","steps":["trace[1861714995] 'process raft request' (duration: 84.671ms)","trace[1861714995] 'compare' (duration: 112.855348ms)"],"step_count":2} {"level":"info","ts":"2026-01-19T16:08:01.126625Z","caller":"traceutil/trace.go:171","msg":"trace[745969544] transaction","detail":"{read_only:false; response_revision:53; number_of_response:1; }","duration":"196.708399ms","start":"2026-01-19T16:08:00.929893Z","end":"2026-01-19T16:08:01.126601Z","steps":["trace[745969544] 'process raft request' (duration: 196.474774ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:08:01.126966Z","caller":"traceutil/trace.go:171","msg":"trace[899244541] linearizableReadLoop","detail":"{readStateIndex:56; appliedIndex:55; }","duration":"197.320364ms","start":"2026-01-19T16:08:00.929638Z","end":"2026-01-19T16:08:01.126959Z","steps":["trace[899244541] 'read index received' (duration: 83.309899ms)","trace[899244541] 'applied index is now lower than readState.Index' (duration: 114.009895ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:08:01.127746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.375875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-19T16:08:01.127793Z","caller":"traceutil/trace.go:171","msg":"trace[26272972] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-edit; range_end:; response_count:0; response_revision:53; }","duration":"198.154833ms","start":"2026-01-19T16:08:00.929613Z","end":"2026-01-19T16:08:01.127768Z","steps":["trace[26272972] 'agreement among raft nodes before linearized reading' (duration: 197.383756ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:01.441485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.321779ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-01-19T16:08:01.44222Z","caller":"traceutil/trace.go:171","msg":"trace[1240074570] transaction","detail":"{read_only:false; response_revision:55; number_of_response:1; }","duration":"310.592217ms","start":"2026-01-19T16:08:01.131604Z","end":"2026-01-19T16:08:01.442196Z","steps":["trace[1240074570] 'process raft request' (duration: 141.287765ms)","trace[1240074570] 'compare' (duration: 168.122015ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:08:01.442358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:01.131596Z","time spent":"310.73069ms","remote":"127.0.0.1:45694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1216,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T16:08:01.442824Z","caller":"traceutil/trace.go:171","msg":"trace[267775401] linearizableReadLoop","detail":"{readStateIndex:59; appliedIndex:58; }","duration":"308.008018ms","start":"2026-01-19T16:08:01.134807Z","end":"2026-01-19T16:08:01.442815Z","steps":["trace[267775401] 'read index received' (duration: 138.016279ms)","trace[267775401] 'applied index is now lower than readState.Index' (duration: 169.990709ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:08:01.443023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.211242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-view\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-19T16:08:01.443081Z","caller":"traceutil/trace.go:171","msg":"trace[1689500313] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-view; range_end:; response_count:0; response_revision:56; }","duration":"308.330556ms","start":"2026-01-19T16:08:01.134741Z","end":"2026-01-19T16:08:01.443071Z","steps":["trace[1689500313] 'agreement among raft nodes before linearized reading' (duration: 308.183812ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:01.443146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:01.134734Z","time spent":"308.404637ms","remote":"127.0.0.1:45596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":0,"response size":28,"request content":"key:\"/registry/clusterroles/system:aggregate-to-view\" "} {"level":"info","ts":"2026-01-19T16:08:01.443652Z","caller":"traceutil/trace.go:171","msg":"trace[1737460844] transaction","detail":"{read_only:false; response_revision:56; number_of_response:1; }","duration":"308.327076ms","start":"2026-01-19T16:08:01.135313Z","end":"2026-01-19T16:08:01.44364Z","steps":["trace[1737460844] 'process raft request' (duration: 307.401714ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:01.443788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:01.135305Z","time spent":"308.458429ms","remote":"127.0.0.1:45694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":826,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-19T16:08:01.444031Z","caller":"traceutil/trace.go:171","msg":"trace[1432277484] transaction","detail":"{read_only:false; response_revision:57; number_of_response:1; }","duration":"239.947455ms","start":"2026-01-19T16:08:01.204075Z","end":"2026-01-19T16:08:01.444023Z","steps":["trace[1432277484] 'process raft request' (duration: 238.682925ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:01.444324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.560841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-19T16:08:01.444416Z","caller":"traceutil/trace.go:171","msg":"trace[1718901551] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:57; }","duration":"244.680564ms","start":"2026-01-19T16:08:01.199726Z","end":"2026-01-19T16:08:01.444407Z","steps":["trace[1718901551] 'agreement among raft nodes before linearized reading' (duration: 244.543441ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:01.444691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.284412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-19T16:08:01.444782Z","caller":"traceutil/trace.go:171","msg":"trace[878580515] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:57; }","duration":"272.414305ms","start":"2026-01-19T16:08:01.172356Z","end":"2026-01-19T16:08:01.44477Z","steps":["trace[878580515] 'agreement among raft nodes before linearized reading' (duration: 271.164866ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:01.815213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.647652ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-01-19T16:08:01.81562Z","caller":"traceutil/trace.go:171","msg":"trace[929507928] transaction","detail":"{read_only:false; response_revision:60; number_of_response:1; }","duration":"364.954344ms","start":"2026-01-19T16:08:01.450647Z","end":"2026-01-19T16:08:01.815602Z","steps":["trace[929507928] 'process raft request' (duration: 364.919993ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:01.815798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:01.45064Z","time spent":"365.094727ms","remote":"127.0.0.1:45694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-19T16:08:01.81635Z","caller":"traceutil/trace.go:171","msg":"trace[822720353] transaction","detail":"{read_only:false; response_revision:59; number_of_response:1; }","duration":"365.807284ms","start":"2026-01-19T16:08:01.450525Z","end":"2026-01-19T16:08:01.816332Z","steps":["trace[822720353] 'process raft request' (duration: 364.77304ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:08:01.817489Z","caller":"traceutil/trace.go:171","msg":"trace[857669046] transaction","detail":"{read_only:false; response_revision:58; number_of_response:1; }","duration":"366.93321ms","start":"2026-01-19T16:08:01.45051Z","end":"2026-01-19T16:08:01.817443Z","steps":["trace[857669046] 'process raft request' (duration: 242.994655ms)","trace[857669046] 'compare' (duration: 121.511629ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:08:01.818829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:01.450495Z","time spent":"368.259651ms","remote":"127.0.0.1:45462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3431,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-01-19T16:08:01.817761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:01.450514Z","time spent":"365.877715ms","remote":"127.0.0.1:45694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1199,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T16:08:01.817812Z","caller":"traceutil/trace.go:171","msg":"trace[1977407533] linearizableReadLoop","detail":"{readStateIndex:63; appliedIndex:61; }","duration":"367.216057ms","start":"2026-01-19T16:08:01.450584Z","end":"2026-01-19T16:08:01.8178Z","steps":["trace[1977407533] 'read index received' (duration: 242.938354ms)","trace[1977407533] 'applied index is now lower than readState.Index' (duration: 124.276513ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:08:01.817948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.3549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-6zk77\" ","response":"range_response_count:1 size:861"} {"level":"info","ts":"2026-01-19T16:08:01.820573Z","caller":"traceutil/trace.go:171","msg":"trace[1993462541] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-6zk77; range_end:; response_count:1; response_revision:60; }","duration":"369.96319ms","start":"2026-01-19T16:08:01.450569Z","end":"2026-01-19T16:08:01.820533Z","steps":["trace[1993462541] 'agreement among raft nodes before linearized reading' (duration: 367.262777ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:01.82067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:01.450565Z","time spent":"370.084553ms","remote":"127.0.0.1:45526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":885,"request content":"key:\"/registry/certificatesigningrequests/csr-6zk77\" "} {"level":"warn","ts":"2026-01-19T16:08:01.820958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.968977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:discovery\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-19T16:08:01.821043Z","caller":"traceutil/trace.go:171","msg":"trace[194865137] range","detail":"{range_begin:/registry/clusterrolebindings/system:discovery; range_end:; response_count:0; response_revision:60; }","duration":"369.042049ms","start":"2026-01-19T16:08:01.451958Z","end":"2026-01-19T16:08:01.821Z","steps":["trace[194865137] 'agreement among raft nodes before linearized reading' (duration: 368.961907ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:01.82124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:01.451946Z","time spent":"369.280834ms","remote":"127.0.0.1:45598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/clusterrolebindings/system:discovery\" "} {"level":"info","ts":"2026-01-19T16:08:02.139627Z","caller":"traceutil/trace.go:171","msg":"trace[161506423] linearizableReadLoop","detail":"{readStateIndex:65; appliedIndex:64; }","duration":"311.826012ms","start":"2026-01-19T16:08:01.827774Z","end":"2026-01-19T16:08:02.1396Z","steps":["trace[161506423] 'read index received' (duration: 235.84495ms)","trace[161506423] 'applied index is now lower than readState.Index' (duration: 75.980102ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:08:02.140061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.266082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/cluster-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-19T16:08:02.140142Z","caller":"traceutil/trace.go:171","msg":"trace[42872057] range","detail":"{range_begin:/registry/clusterroles/cluster-admin; range_end:; response_count:0; response_revision:61; }","duration":"312.373374ms","start":"2026-01-19T16:08:01.827753Z","end":"2026-01-19T16:08:02.140126Z","steps":["trace[42872057] 'agreement among raft nodes before linearized reading' (duration: 312.051437ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:02.140303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:01.827746Z","time spent":"312.547518ms","remote":"127.0.0.1:45596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":28,"request content":"key:\"/registry/clusterroles/cluster-admin\" "} {"level":"warn","ts":"2026-01-19T16:08:02.140485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.613739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-19T16:08:02.140648Z","caller":"traceutil/trace.go:171","msg":"trace[2031618675] transaction","detail":"{read_only:false; response_revision:61; number_of_response:1; }","duration":"315.429485ms","start":"2026-01-19T16:08:01.825205Z","end":"2026-01-19T16:08:02.140635Z","steps":["trace[2031618675] 'process raft request' (duration: 238.405629ms)","trace[2031618675] 'compare' (duration: 75.871199ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:08:02.140349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.320773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/system-leader-election\" ","response":"range_response_count:1 size:1228"} {"level":"info","ts":"2026-01-19T16:08:02.140949Z","caller":"traceutil/trace.go:171","msg":"trace[147726117] range","detail":"{range_begin:/registry/flowschemas/system-leader-election; range_end:; response_count:1; response_revision:61; }","duration":"312.930887ms","start":"2026-01-19T16:08:01.828003Z","end":"2026-01-19T16:08:02.140934Z","steps":["trace[147726117] 'agreement among raft nodes before linearized reading' (duration: 312.249941ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:02.141519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:01.827988Z","time spent":"313.003779ms","remote":"127.0.0.1:45694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":1252,"request content":"key:\"/registry/flowschemas/system-leader-election\" "} {"level":"info","ts":"2026-01-19T16:08:02.140648Z","caller":"traceutil/trace.go:171","msg":"trace[1568705892] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:61; }","duration":"167.68618ms","start":"2026-01-19T16:08:01.972829Z","end":"2026-01-19T16:08:02.140515Z","steps":["trace[1568705892] 'agreement among raft nodes before linearized reading' (duration: 167.605828ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:02.141535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:01.825183Z","time spent":"315.61819ms","remote":"127.0.0.1:45694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":671,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-19T16:08:03.065513Z","caller":"traceutil/trace.go:171","msg":"trace[1388694332] linearizableReadLoop","detail":"{readStateIndex:160; appliedIndex:159; }","duration":"259.159742ms","start":"2026-01-19T16:08:02.806331Z","end":"2026-01-19T16:08:03.065491Z","steps":["trace[1388694332] 'read index received' (duration: 257.064385ms)","trace[1388694332] 'applied index is now lower than readState.Index' (duration: 2.094217ms)"],"step_count":2} {"level":"info","ts":"2026-01-19T16:08:03.06582Z","caller":"traceutil/trace.go:171","msg":"trace[2075404772] transaction","detail":"{read_only:false; response_revision:156; number_of_response:1; }","duration":"423.588243ms","start":"2026-01-19T16:08:02.642208Z","end":"2026-01-19T16:08:03.065796Z","steps":["trace[2075404772] 'process raft request' (duration: 421.25532ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:03.066057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:02.642201Z","time spent":"423.676365ms","remote":"127.0.0.1:45598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":744,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-01-19T16:08:03.066588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.247747ms","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-01-19T16:08:03.066636Z","caller":"traceutil/trace.go:171","msg":"trace[23873346] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:156; }","duration":"260.325259ms","start":"2026-01-19T16:08:02.806299Z","end":"2026-01-19T16:08:03.066624Z","steps":["trace[23873346] 'agreement among raft nodes before linearized reading' (duration: 260.221197ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:03.06732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.418181ms","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-01-19T16:08:03.067366Z","caller":"traceutil/trace.go:171","msg":"trace[891519941] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:156; }","duration":"260.944913ms","start":"2026-01-19T16:08:02.806409Z","end":"2026-01-19T16:08:03.067353Z","steps":["trace[891519941] 'agreement among raft nodes before linearized reading' (duration: 260.438991ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:08:08.739254Z","caller":"traceutil/trace.go:171","msg":"trace[827297822] linearizableReadLoop","detail":"{readStateIndex:329; appliedIndex:327; }","duration":"486.66696ms","start":"2026-01-19T16:08:08.252572Z","end":"2026-01-19T16:08:08.739239Z","steps":["trace[827297822] 'read index received' (duration: 478.540293ms)","trace[827297822] 'applied index is now lower than readState.Index' (duration: 8.124867ms)"],"step_count":2} {"level":"info","ts":"2026-01-19T16:08:08.739255Z","caller":"traceutil/trace.go:171","msg":"trace[1862539173] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"547.968824ms","start":"2026-01-19T16:08:08.191268Z","end":"2026-01-19T16:08:08.739237Z","steps":["trace[1862539173] 'process raft request' (duration: 539.907228ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:08.739354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:08.191252Z","time spent":"548.059787ms","remote":"127.0.0.1:45488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":186,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-19T16:08:08.739374Z","caller":"traceutil/trace.go:171","msg":"trace[1726243978] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"536.276298ms","start":"2026-01-19T16:08:08.203091Z","end":"2026-01-19T16:08:08.739368Z","steps":["trace[1726243978] 'process raft request' (duration: 536.083304ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:08.739448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:08.203076Z","time spent":"536.320919ms","remote":"127.0.0.1:45534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-19T16:08:08.739491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.915116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-01-19T16:08:08.739512Z","caller":"traceutil/trace.go:171","msg":"trace[924807070] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:315; }","duration":"486.971867ms","start":"2026-01-19T16:08:08.252533Z","end":"2026-01-19T16:08:08.739505Z","steps":["trace[924807070] 'agreement among raft nodes before linearized reading' (duration: 486.920856ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:08.739526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:08.252516Z","time spent":"487.006989ms","remote":"127.0.0.1:45534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-19T16:08:09.260604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.377045ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-19T16:08:09.260713Z","caller":"traceutil/trace.go:171","msg":"trace[1708224452] linearizableReadLoop","detail":"{readStateIndex:353; appliedIndex:352; }","duration":"321.013868ms","start":"2026-01-19T16:08:08.939681Z","end":"2026-01-19T16:08:09.260695Z","steps":["trace[1708224452] 'read index received' (duration: 10.390548ms)","trace[1708224452] 'applied index is now lower than readState.Index' (duration: 310.62159ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:08:09.261015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.604192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-01-19T16:08:09.261166Z","caller":"traceutil/trace.go:171","msg":"trace[504819292] transaction","detail":"{read_only:false; response_revision:339; number_of_response:1; }","duration":"325.865804ms","start":"2026-01-19T16:08:08.934924Z","end":"2026-01-19T16:08:09.26079Z","steps":["trace[504819292] 'process raft request' (duration: 15.195343ms)","trace[504819292] 'compare' (duration: 310.203121ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:08:09.261257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:08.934912Z","time spent":"326.287653ms","remote":"127.0.0.1:45462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4406,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T16:08:09.261267Z","caller":"traceutil/trace.go:171","msg":"trace[1615112046] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:339; }","duration":"270.811917ms","start":"2026-01-19T16:08:08.990355Z","end":"2026-01-19T16:08:09.261167Z","steps":["trace[1615112046] 'agreement among raft nodes before linearized reading' (duration: 270.595663ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:09.262378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.689094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-01-19T16:08:09.264396Z","caller":"traceutil/trace.go:171","msg":"trace[1600159185] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:339; }","duration":"324.722148ms","start":"2026-01-19T16:08:08.939661Z","end":"2026-01-19T16:08:09.264383Z","steps":["trace[1600159185] 'agreement among raft nodes before linearized reading' (duration: 322.677904ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:09.264592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:08.939655Z","time spent":"324.851021ms","remote":"127.0.0.1:45488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":238,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-01-19T16:08:09.264912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.661444ms","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-01-19T16:08:09.265054Z","caller":"traceutil/trace.go:171","msg":"trace[285650728] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:339; }","duration":"153.840458ms","start":"2026-01-19T16:08:09.111206Z","end":"2026-01-19T16:08:09.265046Z","steps":["trace[285650728] 'agreement among raft nodes before linearized reading' (duration: 153.674204ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:09.26529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.546939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-01-19T16:08:09.265433Z","caller":"traceutil/trace.go:171","msg":"trace[1931025000] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:339; }","duration":"174.719202ms","start":"2026-01-19T16:08:09.090682Z","end":"2026-01-19T16:08:09.265401Z","steps":["trace[1931025000] 'agreement among raft nodes before linearized reading' (duration: 174.574329ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:09.265694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.253894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-01-19T16:08:09.265807Z","caller":"traceutil/trace.go:171","msg":"trace[360262572] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:339; }","duration":"226.392907ms","start":"2026-01-19T16:08:09.039406Z","end":"2026-01-19T16:08:09.265799Z","steps":["trace[360262572] 'agreement among raft nodes before linearized reading' (duration: 226.255164ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:09.265841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.965281ms","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-01-19T16:08:09.266215Z","caller":"traceutil/trace.go:171","msg":"trace[19862803] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:339; }","duration":"154.325918ms","start":"2026-01-19T16:08:09.111857Z","end":"2026-01-19T16:08:09.266183Z","steps":["trace[19862803] 'agreement among raft nodes before linearized reading' (duration: 150.570997ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:09.266744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.123556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-01-19T16:08:09.266799Z","caller":"traceutil/trace.go:171","msg":"trace[345697321] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:339; }","duration":"127.186167ms","start":"2026-01-19T16:08:09.139595Z","end":"2026-01-19T16:08:09.266781Z","steps":["trace[345697321] 'agreement among raft nodes before linearized reading' (duration: 127.047614ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:09.267075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.360501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-01-19T16:08:09.267125Z","caller":"traceutil/trace.go:171","msg":"trace[1774144044] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:339; }","duration":"138.424342ms","start":"2026-01-19T16:08:09.12869Z","end":"2026-01-19T16:08:09.267114Z","steps":["trace[1774144044] 'agreement among raft nodes before linearized reading' (duration: 138.34218ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:09.791214Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8934750058525616660,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-19T16:08:12.555896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.714076791s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-19T16:08:12.555991Z","caller":"traceutil/trace.go:171","msg":"trace[606293143] transaction","detail":"{read_only:false; response_revision:340; number_of_response:1; }","duration":"3.280755896s","start":"2026-01-19T16:08:09.275217Z","end":"2026-01-19T16:08:12.555973Z","steps":["trace[606293143] 'process raft request' (duration: 566.531502ms)","trace[606293143] 'compare' (duration: 2.713919328s)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:08:12.556048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:09.275205Z","time spent":"3.280816857s","remote":"127.0.0.1:45388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2163,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-19T16:08:12.613505Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.77176361s","expected-duration":"1s"} {"level":"info","ts":"2026-01-19T16:08:12.614238Z","caller":"traceutil/trace.go:171","msg":"trace[167747931] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"3.337150977s","start":"2026-01-19T16:08:09.277057Z","end":"2026-01-19T16:08:12.614208Z","steps":["trace[167747931] 'process raft request' (duration: 3.337073516s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:12.614381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:09.27705Z","time spent":"3.33726633s","remote":"127.0.0.1:45380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":754,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-19T16:08:12.614682Z","caller":"traceutil/trace.go:171","msg":"trace[1120968918] transaction","detail":"{read_only:false; response_revision:341; number_of_response:1; }","duration":"3.337648877s","start":"2026-01-19T16:08:09.276992Z","end":"2026-01-19T16:08:12.61464Z","steps":["trace[1120968918] 'process raft request' (duration: 3.336976353s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:12.614768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:09.276959Z","time spent":"3.33776355s","remote":"127.0.0.1:45534","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-01-19T16:08:12.615076Z","caller":"traceutil/trace.go:171","msg":"trace[973802488] linearizableReadLoop","detail":"{readStateIndex:357; appliedIndex:353; }","duration":"3.324548822s","start":"2026-01-19T16:08:09.290516Z","end":"2026-01-19T16:08:12.615064Z","steps":["trace[973802488] 'read index received' (duration: 551.26728ms)","trace[973802488] 'applied index is now lower than readState.Index' (duration: 2.773280442s)"],"step_count":2} {"level":"info","ts":"2026-01-19T16:08:12.615261Z","caller":"traceutil/trace.go:171","msg":"trace[1502181236] transaction","detail":"{read_only:false; response_revision:343; number_of_response:1; }","duration":"3.338059056s","start":"2026-01-19T16:08:09.27719Z","end":"2026-01-19T16:08:12.615249Z","steps":["trace[1502181236] 'process raft request' (duration: 3.336983874s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:12.615323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:09.277181Z","time spent":"3.338108857s","remote":"127.0.0.1:45534","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-01-19T16:08:12.615471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.324951651s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-01-19T16:08:12.615522Z","caller":"traceutil/trace.go:171","msg":"trace[1607200543] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:343; }","duration":"3.325000052s","start":"2026-01-19T16:08:09.290508Z","end":"2026-01-19T16:08:12.615508Z","steps":["trace[1607200543] 'agreement among raft nodes before linearized reading' (duration: 3.3249203s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:12.615561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:09.290384Z","time spent":"3.325166676s","remote":"127.0.0.1:45488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":1,"response size":242,"request content":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" "} {"level":"warn","ts":"2026-01-19T16:08:13.046233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.736892ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-19T16:08:13.046797Z","caller":"traceutil/trace.go:171","msg":"trace[530160727] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"424.09973ms","start":"2026-01-19T16:08:12.622659Z","end":"2026-01-19T16:08:13.046759Z","steps":["trace[530160727] 'process raft request' (duration: 423.701751ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:13.046905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:12.622651Z","time spent":"424.194671ms","remote":"127.0.0.1:45488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":156,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-19T16:08:13.048542Z","caller":"traceutil/trace.go:171","msg":"trace[1009833546] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"1.987270798s","start":"2026-01-19T16:08:11.061231Z","end":"2026-01-19T16:08:13.048501Z","steps":["trace[1009833546] 'process raft request' (duration: 1.599158036s)","trace[1009833546] 'compare' (duration: 385.295973ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:08:13.048912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:11.061212Z","time spent":"1.98738442s","remote":"127.0.0.1:45534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T16:08:13.049821Z","caller":"traceutil/trace.go:171","msg":"trace[360512781] linearizableReadLoop","detail":"{readStateIndex:358; appliedIndex:357; }","duration":"434.618333ms","start":"2026-01-19T16:08:12.615128Z","end":"2026-01-19T16:08:13.049746Z","steps":["trace[360512781] 'read index received' (duration: 45.269354ms)","trace[360512781] 'applied index is now lower than readState.Index' (duration: 389.157045ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:08:13.051188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.30244457s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-01-19T16:08:13.051649Z","caller":"traceutil/trace.go:171","msg":"trace[616788292] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:345; }","duration":"2.302537282s","start":"2026-01-19T16:08:10.748702Z","end":"2026-01-19T16:08:13.051239Z","steps":["trace[616788292] 'agreement among raft nodes before linearized reading' (duration: 2.302278267s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:13.051806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:10.748691Z","time spent":"2.303016082s","remote":"127.0.0.1:45534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-19T16:08:13.052641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.30663591s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-01-19T16:08:13.052861Z","caller":"traceutil/trace.go:171","msg":"trace[1730092134] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:345; }","duration":"2.306715972s","start":"2026-01-19T16:08:10.745954Z","end":"2026-01-19T16:08:13.05267Z","steps":["trace[1730092134] 'agreement among raft nodes before linearized reading' (duration: 2.306487397s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:13.052907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:10.745931Z","time spent":"2.306964927s","remote":"127.0.0.1:45534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-19T16:08:13.053356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.37177361s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T16:08:13.053506Z","caller":"traceutil/trace.go:171","msg":"trace[1888346711] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:345; }","duration":"3.371844901s","start":"2026-01-19T16:08:09.681569Z","end":"2026-01-19T16:08:13.053414Z","steps":["trace[1888346711] 'agreement among raft nodes before linearized reading' (duration: 3.371651857s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:13.055087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.605713072s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-01-19T16:08:13.055137Z","caller":"traceutil/trace.go:171","msg":"trace[169521448] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:1; response_revision:345; }","duration":"3.605771982s","start":"2026-01-19T16:08:09.449345Z","end":"2026-01-19T16:08:13.055117Z","steps":["trace[169521448] 'agreement among raft nodes before linearized reading' (duration: 3.605693421s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:13.055172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:09.449334Z","time spent":"3.605829354s","remote":"127.0.0.1:45534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":559,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"warn","ts":"2026-01-19T16:08:13.053693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.892563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-proxy-fn4kr.188c2d991c206ab7\" ","response":"range_response_count:1 size:769"} {"level":"info","ts":"2026-01-19T16:08:13.058344Z","caller":"traceutil/trace.go:171","msg":"trace[1407475272] range","detail":"{range_begin:/registry/events/kube-system/kube-proxy-fn4kr.188c2d991c206ab7; range_end:; response_count:1; response_revision:345; }","duration":"435.740035ms","start":"2026-01-19T16:08:12.622591Z","end":"2026-01-19T16:08:13.058331Z","steps":["trace[1407475272] 'agreement among raft nodes before linearized reading' (duration: 430.690479ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:13.058899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:12.622576Z","time spent":"436.308578ms","remote":"127.0.0.1:45380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":793,"request content":"key:\"/registry/events/kube-system/kube-proxy-fn4kr.188c2d991c206ab7\" "} {"level":"warn","ts":"2026-01-19T16:08:13.055422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.513716199s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-01-19T16:08:13.060754Z","caller":"traceutil/trace.go:171","msg":"trace[1646899385] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:345; }","duration":"3.519103222s","start":"2026-01-19T16:08:09.541638Z","end":"2026-01-19T16:08:13.060741Z","steps":["trace[1646899385] 'agreement among raft nodes before linearized reading' (duration: 3.513728129s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:13.060795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:09.541608Z","time spent":"3.519171634s","remote":"127.0.0.1:45488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":233,"request content":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" "} {"level":"warn","ts":"2026-01-19T16:08:13.055585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.564403632s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-01-19T16:08:13.06098Z","caller":"traceutil/trace.go:171","msg":"trace[936165116] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:345; }","duration":"3.569844857s","start":"2026-01-19T16:08:09.491126Z","end":"2026-01-19T16:08:13.060971Z","steps":["trace[936165116] 'agreement among raft nodes before linearized reading' (duration: 3.564345591s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:13.061015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:09.491098Z","time spent":"3.569908469s","remote":"127.0.0.1:45488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"warn","ts":"2026-01-19T16:08:13.055885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.662558438s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-01-19T16:08:13.061253Z","caller":"traceutil/trace.go:171","msg":"trace[933715216] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:345; }","duration":"3.667856451s","start":"2026-01-19T16:08:09.393319Z","end":"2026-01-19T16:08:13.061175Z","steps":["trace[933715216] 'agreement among raft nodes before linearized reading' (duration: 3.662536568s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:13.061286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:09.393154Z","time spent":"3.668122797s","remote":"127.0.0.1:45488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":217,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"warn","ts":"2026-01-19T16:08:13.05594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.613257315s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-01-19T16:08:13.061511Z","caller":"traceutil/trace.go:171","msg":"trace[1852816513] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:345; }","duration":"3.618874714s","start":"2026-01-19T16:08:09.442616Z","end":"2026-01-19T16:08:13.061491Z","steps":["trace[1852816513] 'agreement among raft nodes before linearized reading' (duration: 3.613288916s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:13.061541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:09.442584Z","time spent":"3.618948845s","remote":"127.0.0.1:45488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":233,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"warn","ts":"2026-01-19T16:08:13.056064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.714724634s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-01-19T16:08:13.061777Z","caller":"traceutil/trace.go:171","msg":"trace[290191323] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:345; }","duration":"3.720533147s","start":"2026-01-19T16:08:09.341235Z","end":"2026-01-19T16:08:13.061768Z","steps":["trace[290191323] 'agreement among raft nodes before linearized reading' (duration: 3.714721834s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:13.061811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:09.341203Z","time spent":"3.720595049s","remote":"127.0.0.1:45488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":238,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"info","ts":"2026-01-19T16:08:13.244757Z","caller":"traceutil/trace.go:171","msg":"trace[1698253837] linearizableReadLoop","detail":"{readStateIndex:360; appliedIndex:359; }","duration":"176.510664ms","start":"2026-01-19T16:08:13.068223Z","end":"2026-01-19T16:08:13.244734Z","steps":["trace[1698253837] 'read index received' (duration: 176.440763ms)","trace[1698253837] 'applied index is now lower than readState.Index' (duration: 68.871µs)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:08:13.244922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.676688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3944"} {"level":"info","ts":"2026-01-19T16:08:13.244963Z","caller":"traceutil/trace.go:171","msg":"trace[2138440058] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:346; }","duration":"176.75717ms","start":"2026-01-19T16:08:13.068192Z","end":"2026-01-19T16:08:13.24495Z","steps":["trace[2138440058] 'agreement among raft nodes before linearized reading' (duration: 176.626127ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:08:13.245225Z","caller":"traceutil/trace.go:171","msg":"trace[445550834] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"180.676252ms","start":"2026-01-19T16:08:13.064536Z","end":"2026-01-19T16:08:13.245212Z","steps":["trace[445550834] 'process raft request' (duration: 180.012568ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:08:13.356316Z","caller":"traceutil/trace.go:171","msg":"trace[268465171] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"288.010644ms","start":"2026-01-19T16:08:13.068288Z","end":"2026-01-19T16:08:13.356299Z","steps":["trace[268465171] 'process raft request' (duration: 287.429192ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:08:13.356868Z","caller":"traceutil/trace.go:171","msg":"trace[1913031120] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"288.510105ms","start":"2026-01-19T16:08:13.068349Z","end":"2026-01-19T16:08:13.356859Z","steps":["trace[1913031120] 'process raft request' (duration: 287.628986ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:08:13.358716Z","caller":"traceutil/trace.go:171","msg":"trace[602753844] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"276.323017ms","start":"2026-01-19T16:08:13.082384Z","end":"2026-01-19T16:08:13.358707Z","steps":["trace[602753844] 'process raft request' (duration: 273.887345ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:08:13.359153Z","caller":"traceutil/trace.go:171","msg":"trace[2062730461] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"276.688274ms","start":"2026-01-19T16:08:13.082457Z","end":"2026-01-19T16:08:13.359145Z","steps":["trace[2062730461] 'process raft request' (duration: 275.169282ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:08:13.359697Z","caller":"traceutil/trace.go:171","msg":"trace[1802483973] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"277.230086ms","start":"2026-01-19T16:08:13.082457Z","end":"2026-01-19T16:08:13.359687Z","steps":["trace[1802483973] 'process raft request' (duration: 276.629993ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:08:13.361129Z","caller":"traceutil/trace.go:171","msg":"trace[250427417] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"278.626795ms","start":"2026-01-19T16:08:13.082495Z","end":"2026-01-19T16:08:13.361122Z","steps":["trace[250427417] 'process raft request' (duration: 276.631633ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:08:13.363639Z","caller":"traceutil/trace.go:171","msg":"trace[1499136703] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"281.099048ms","start":"2026-01-19T16:08:13.082532Z","end":"2026-01-19T16:08:13.363631Z","steps":["trace[1499136703] 'process raft request' (duration: 278.35635ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:08:13.364018Z","caller":"traceutil/trace.go:171","msg":"trace[1568467133] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"281.480276ms","start":"2026-01-19T16:08:13.08253Z","end":"2026-01-19T16:08:13.36401Z","steps":["trace[1568467133] 'process raft request' (duration: 278.509973ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:08:13.364539Z","caller":"traceutil/trace.go:171","msg":"trace[1155961411] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"279.724439ms","start":"2026-01-19T16:08:13.0848Z","end":"2026-01-19T16:08:13.364524Z","steps":["trace[1155961411] 'process raft request' (duration: 279.158957ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:08:14.735159Z","caller":"traceutil/trace.go:171","msg":"trace[2047572865] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"101.915652ms","start":"2026-01-19T16:08:14.633214Z","end":"2026-01-19T16:08:14.735129Z","steps":["trace[2047572865] 'process raft request' (duration: 101.84825ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:08:14.735293Z","caller":"traceutil/trace.go:171","msg":"trace[1676801609] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"102.073285ms","start":"2026-01-19T16:08:14.6332Z","end":"2026-01-19T16:08:14.735273Z","steps":["trace[1676801609] 'process raft request' (duration: 101.699567ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:08:15.334193Z","caller":"traceutil/trace.go:171","msg":"trace[632598460] linearizableReadLoop","detail":"{readStateIndex:397; appliedIndex:396; }","duration":"163.87762ms","start":"2026-01-19T16:08:15.170296Z","end":"2026-01-19T16:08:15.334174Z","steps":["trace[632598460] 'read index received' (duration: 136.352376ms)","trace[632598460] 'applied index is now lower than readState.Index' (duration: 27.524624ms)"],"step_count":2} {"level":"info","ts":"2026-01-19T16:08:15.334372Z","caller":"traceutil/trace.go:171","msg":"trace[327341250] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"180.575688ms","start":"2026-01-19T16:08:15.153788Z","end":"2026-01-19T16:08:15.334363Z","steps":["trace[327341250] 'process raft request' (duration: 152.917741ms)","trace[327341250] 'compare' (duration: 27.234578ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:08:15.334542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.241617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4729"} {"level":"info","ts":"2026-01-19T16:08:15.334573Z","caller":"traceutil/trace.go:171","msg":"trace[118108930] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:381; }","duration":"164.342069ms","start":"2026-01-19T16:08:15.170224Z","end":"2026-01-19T16:08:15.334566Z","steps":["trace[118108930] 'agreement among raft nodes before linearized reading' (duration: 164.277108ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:15.841084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.556236ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-19T16:08:15.841451Z","caller":"traceutil/trace.go:171","msg":"trace[1319161748] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"427.94721ms","start":"2026-01-19T16:08:15.413481Z","end":"2026-01-19T16:08:15.841428Z","steps":["trace[1319161748] 'process raft request' (duration: 260.956935ms)","trace[1319161748] 'compare' (duration: 166.044825ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:08:15.841679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:15.41347Z","time spent":"428.025831ms","remote":"127.0.0.1:45534","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-01-19T16:08:15.843371Z","caller":"traceutil/trace.go:171","msg":"trace[559624574] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"424.338835ms","start":"2026-01-19T16:08:15.418999Z","end":"2026-01-19T16:08:15.843337Z","steps":["trace[559624574] 'process raft request' (duration: 423.801214ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:08:15.843634Z","caller":"traceutil/trace.go:171","msg":"trace[1445548836] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"256.420611ms","start":"2026-01-19T16:08:15.587198Z","end":"2026-01-19T16:08:15.843618Z","steps":["trace[1445548836] 'process raft request' (duration: 255.849309ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:15.843847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:15.418988Z","time spent":"424.59118ms","remote":"127.0.0.1:45462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T16:08:16.04306Z","caller":"traceutil/trace.go:171","msg":"trace[1624951066] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"172.026614ms","start":"2026-01-19T16:08:15.870995Z","end":"2026-01-19T16:08:16.043021Z","steps":["trace[1624951066] 'process raft request' (duration: 80.924479ms)","trace[1624951066] 'compare' (duration: 90.921871ms)"],"step_count":2} {"level":"info","ts":"2026-01-19T16:08:16.894564Z","caller":"traceutil/trace.go:171","msg":"trace[741190035] linearizableReadLoop","detail":"{readStateIndex:405; appliedIndex:404; }","duration":"150.484839ms","start":"2026-01-19T16:08:16.744062Z","end":"2026-01-19T16:08:16.894547Z","steps":["trace[741190035] 'read index received' (duration: 150.311785ms)","trace[741190035] 'applied index is now lower than readState.Index' (duration: 172.394µs)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:08:16.894685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.608321ms","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-01-19T16:08:16.894715Z","caller":"traceutil/trace.go:171","msg":"trace[23019153] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:389; }","duration":"150.682353ms","start":"2026-01-19T16:08:16.744026Z","end":"2026-01-19T16:08:16.894708Z","steps":["trace[23019153] 'agreement among raft nodes before linearized reading' (duration: 150.589641ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:08:16.894941Z","caller":"traceutil/trace.go:171","msg":"trace[1486763857] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"152.517451ms","start":"2026-01-19T16:08:16.742414Z","end":"2026-01-19T16:08:16.894932Z","steps":["trace[1486763857] 'process raft request' (duration: 152.00959ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:08:17.443653Z","caller":"traceutil/trace.go:171","msg":"trace[1195449765] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"545.181278ms","start":"2026-01-19T16:08:16.89844Z","end":"2026-01-19T16:08:17.443621Z","steps":["trace[1195449765] 'process raft request' (duration: 459.603147ms)","trace[1195449765] 'compare' (duration: 85.04629ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:08:17.444096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:16.898423Z","time spent":"545.30257ms","remote":"127.0.0.1:45534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-19T16:08:17.792484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.204223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T16:08:17.792557Z","caller":"traceutil/trace.go:171","msg":"trace[588682142] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:391; }","duration":"169.349115ms","start":"2026-01-19T16:08:17.623192Z","end":"2026-01-19T16:08:17.792541Z","steps":["trace[588682142] 'range keys from in-memory index tree' (duration: 169.029769ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:19.590168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"795.866244ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-01-19T16:08:19.590314Z","caller":"traceutil/trace.go:171","msg":"trace[1609598588] linearizableReadLoop","detail":"{readStateIndex:410; appliedIndex:409; }","duration":"691.162934ms","start":"2026-01-19T16:08:18.899097Z","end":"2026-01-19T16:08:19.59026Z","steps":["trace[1609598588] 'read index received' (duration: 29.221µs)","trace[1609598588] 'applied index is now lower than readState.Index' (duration: 691.131983ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:08:19.590983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.880118ms","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-01-19T16:08:19.591031Z","caller":"traceutil/trace.go:171","msg":"trace[313360976] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:393; }","duration":"691.96984ms","start":"2026-01-19T16:08:18.89905Z","end":"2026-01-19T16:08:19.59102Z","steps":["trace[313360976] 'agreement among raft nodes before linearized reading' (duration: 691.872238ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:19.591073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:18.899032Z","time spent":"692.032521ms","remote":"127.0.0.1:45534","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-01-19T16:08:19.597597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.322132ms","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-01-19T16:08:19.597636Z","caller":"traceutil/trace.go:171","msg":"trace[879531500] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:393; }","duration":"146.292571ms","start":"2026-01-19T16:08:19.451334Z","end":"2026-01-19T16:08:19.597627Z","steps":["trace[879531500] 'agreement among raft nodes before linearized reading' (duration: 143.324391ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:08:23.534875Z","caller":"traceutil/trace.go:171","msg":"trace[335637451] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"151.514057ms","start":"2026-01-19T16:08:23.383345Z","end":"2026-01-19T16:08:23.534859Z","steps":["trace[335637451] 'process raft request' (duration: 142.07445ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:08:24.963298Z","caller":"traceutil/trace.go:171","msg":"trace[29856347] linearizableReadLoop","detail":"{readStateIndex:433; appliedIndex:432; }","duration":"337.932575ms","start":"2026-01-19T16:08:24.625341Z","end":"2026-01-19T16:08:24.963273Z","steps":["trace[29856347] 'read index received' (duration: 337.736231ms)","trace[29856347] 'applied index is now lower than readState.Index' (duration: 195.184µs)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:08:24.963427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.065008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T16:08:24.963458Z","caller":"traceutil/trace.go:171","msg":"trace[70575010] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:414; }","duration":"338.13873ms","start":"2026-01-19T16:08:24.62531Z","end":"2026-01-19T16:08:24.963448Z","steps":["trace[70575010] 'agreement among raft nodes before linearized reading' (duration: 338.051548ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:24.963491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:24.625292Z","time spent":"338.19043ms","remote":"127.0.0.1:45278","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-01-19T16:08:24.964026Z","caller":"traceutil/trace.go:171","msg":"trace[1179093875] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"508.282606ms","start":"2026-01-19T16:08:24.455729Z","end":"2026-01-19T16:08:24.964012Z","steps":["trace[1179093875] 'process raft request' (duration: 507.412088ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:24.964157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:24.45571Z","time spent":"508.379227ms","remote":"127.0.0.1:45706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4158,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T16:08:25.123828Z","caller":"traceutil/trace.go:171","msg":"trace[1611026025] linearizableReadLoop","detail":"{readStateIndex:434; appliedIndex:433; }","duration":"145.519148ms","start":"2026-01-19T16:08:24.978291Z","end":"2026-01-19T16:08:25.12381Z","steps":["trace[1611026025] 'read index received' (duration: 120.576851ms)","trace[1611026025] 'applied index is now lower than readState.Index' (duration: 24.941507ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:08:25.123939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.62241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T16:08:25.123968Z","caller":"traceutil/trace.go:171","msg":"trace[1723910229] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:415; }","duration":"145.689321ms","start":"2026-01-19T16:08:24.97827Z","end":"2026-01-19T16:08:25.12396Z","steps":["trace[1723910229] 'agreement among raft nodes before linearized reading' (duration: 145.607519ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:08:25.125156Z","caller":"traceutil/trace.go:171","msg":"trace[254460141] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"149.923354ms","start":"2026-01-19T16:08:24.975219Z","end":"2026-01-19T16:08:25.125143Z","steps":["trace[254460141] 'process raft request' (duration: 123.731602ms)","trace[254460141] 'compare' (duration: 24.773374ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:08:29.980331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.957671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-01-19T16:08:29.980481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.56059ms","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-01-19T16:08:29.980493Z","caller":"traceutil/trace.go:171","msg":"trace[1490733554] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:453; }","duration":"170.151264ms","start":"2026-01-19T16:08:29.810318Z","end":"2026-01-19T16:08:29.980469Z","steps":["trace[1490733554] 'range keys from in-memory index tree' (duration: 169.87057ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:08:29.980532Z","caller":"traceutil/trace.go:171","msg":"trace[1827224999] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:453; }","duration":"173.653681ms","start":"2026-01-19T16:08:29.806863Z","end":"2026-01-19T16:08:29.980517Z","steps":["trace[1827224999] 'range keys from in-memory index tree' (duration: 173.488079ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:08:35.984336Z","caller":"traceutil/trace.go:171","msg":"trace[557940934] linearizableReadLoop","detail":"{readStateIndex:489; appliedIndex:488; }","duration":"362.311201ms","start":"2026-01-19T16:08:35.622007Z","end":"2026-01-19T16:08:35.984318Z","steps":["trace[557940934] 'read index received' (duration: 362.146838ms)","trace[557940934] 'applied index is now lower than readState.Index' (duration: 161.953µs)"],"step_count":2} {"level":"info","ts":"2026-01-19T16:08:35.984589Z","caller":"traceutil/trace.go:171","msg":"trace[498797736] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"453.419598ms","start":"2026-01-19T16:08:35.53116Z","end":"2026-01-19T16:08:35.98458Z","steps":["trace[498797736] 'process raft request' (duration: 453.060591ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:35.984735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:35.531142Z","time spent":"453.513009ms","remote":"127.0.0.1:45534","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-01-19T16:08:35.984873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.861351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T16:08:35.984898Z","caller":"traceutil/trace.go:171","msg":"trace[490597613] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:467; }","duration":"362.910051ms","start":"2026-01-19T16:08:35.62198Z","end":"2026-01-19T16:08:35.98489Z","steps":["trace[490597613] 'agreement among raft nodes before linearized reading' (duration: 362.870741ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:35.984917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:35.621967Z","time spent":"362.945362ms","remote":"127.0.0.1:45278","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-01-19T16:08:35.985024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.209493ms","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-01-19T16:08:35.985043Z","caller":"traceutil/trace.go:171","msg":"trace[541101583] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:467; }","duration":"127.253563ms","start":"2026-01-19T16:08:35.857784Z","end":"2026-01-19T16:08:35.985038Z","steps":["trace[541101583] 'agreement among raft nodes before linearized reading' (duration: 127.217902ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:35.985198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.502962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12669"} {"level":"info","ts":"2026-01-19T16:08:35.985218Z","caller":"traceutil/trace.go:171","msg":"trace[1078588298] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:467; }","duration":"124.540213ms","start":"2026-01-19T16:08:35.860672Z","end":"2026-01-19T16:08:35.985212Z","steps":["trace[1078588298] 'agreement among raft nodes before linearized reading' (duration: 124.492642ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:08:37.17821Z","caller":"traceutil/trace.go:171","msg":"trace[1492031368] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"169.902344ms","start":"2026-01-19T16:08:37.008283Z","end":"2026-01-19T16:08:37.178185Z","steps":["trace[1492031368] 'process raft request' (duration: 93.224918ms)","trace[1492031368] 'compare' (duration: 76.544043ms)"],"step_count":2} {"level":"info","ts":"2026-01-19T16:08:40.457411Z","caller":"traceutil/trace.go:171","msg":"trace[362525179] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"131.844251ms","start":"2026-01-19T16:08:40.325539Z","end":"2026-01-19T16:08:40.457383Z","steps":["trace[362525179] 'process raft request' (duration: 131.647607ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:43.883188Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8934750058525617098,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-19T16:08:43.931268Z","caller":"traceutil/trace.go:171","msg":"trace[148335533] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:601; }","duration":"550.481612ms","start":"2026-01-19T16:08:43.380742Z","end":"2026-01-19T16:08:43.931223Z","steps":["trace[148335533] 'read index received' (duration: 548.631739ms)","trace[148335533] 'applied index is now lower than readState.Index' (duration: 1.848763ms)"],"step_count":2} {"level":"info","ts":"2026-01-19T16:08:43.931507Z","caller":"traceutil/trace.go:171","msg":"trace[1473220395] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"640.053481ms","start":"2026-01-19T16:08:43.291441Z","end":"2026-01-19T16:08:43.931494Z","steps":["trace[1473220395] 'process raft request' (duration: 637.504086ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:43.93156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:43.291411Z","time spent":"640.111932ms","remote":"127.0.0.1:45350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-19T16:08:43.932224Z","caller":"traceutil/trace.go:171","msg":"trace[1572649058] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"332.640163ms","start":"2026-01-19T16:08:43.599576Z","end":"2026-01-19T16:08:43.932216Z","steps":["trace[1572649058] 'process raft request' (duration: 330.792141ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:43.932268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:43.599559Z","time spent":"332.679784ms","remote":"127.0.0.1:45534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-19T16:08:43.932655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.911638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.206\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-01-19T16:08:43.932679Z","caller":"traceutil/trace.go:171","msg":"trace[1989479424] range","detail":"{range_begin:/registry/masterleases/199.19.213.206; range_end:; response_count:1; response_revision:579; }","duration":"551.932768ms","start":"2026-01-19T16:08:43.380736Z","end":"2026-01-19T16:08:43.932669Z","steps":["trace[1989479424] 'agreement among raft nodes before linearized reading' (duration: 551.767925ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:43.932695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:43.380672Z","time spent":"552.0191ms","remote":"127.0.0.1:45334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.206\" "} {"level":"warn","ts":"2026-01-19T16:08:43.939309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.383124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T16:08:43.939388Z","caller":"traceutil/trace.go:171","msg":"trace[858587816] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:579; }","duration":"456.496965ms","start":"2026-01-19T16:08:43.482873Z","end":"2026-01-19T16:08:43.93937Z","steps":["trace[858587816] 'agreement among raft nodes before linearized reading' (duration: 451.854994ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:43.946909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:43.482859Z","time spent":"456.582837ms","remote":"127.0.0.1:45350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-01-19T16:08:43.957087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.415307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T16:08:43.957226Z","caller":"traceutil/trace.go:171","msg":"trace[92512865] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:579; }","duration":"274.6036ms","start":"2026-01-19T16:08:43.682606Z","end":"2026-01-19T16:08:43.95721Z","steps":["trace[92512865] 'agreement among raft nodes before linearized reading' (duration: 274.414496ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:43.976088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.720952ms","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-01-19T16:08:43.976233Z","caller":"traceutil/trace.go:171","msg":"trace[1822465032] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:579; }","duration":"160.904326ms","start":"2026-01-19T16:08:43.81531Z","end":"2026-01-19T16:08:43.976214Z","steps":["trace[1822465032] 'agreement among raft nodes before linearized reading' (duration: 141.649416ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:45.072984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.124827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-01-19T16:08:45.073039Z","caller":"traceutil/trace.go:171","msg":"trace[326613070] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:586; }","duration":"591.186288ms","start":"2026-01-19T16:08:44.481838Z","end":"2026-01-19T16:08:45.073024Z","steps":["trace[326613070] 'range keys from in-memory index tree' (duration: 591.019545ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:45.073066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:44.481801Z","time spent":"591.2581ms","remote":"127.0.0.1:45350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4107,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"warn","ts":"2026-01-19T16:08:45.073214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.429498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-01-19T16:08:45.073232Z","caller":"traceutil/trace.go:171","msg":"trace[407075441] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:586; }","duration":"589.458818ms","start":"2026-01-19T16:08:44.483767Z","end":"2026-01-19T16:08:45.073226Z","steps":["trace[407075441] 'range keys from in-memory index tree' (duration: 589.376967ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:45.073249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:44.483762Z","time spent":"589.482068ms","remote":"127.0.0.1:45534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-19T16:08:45.073331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.372665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T16:08:45.073347Z","caller":"traceutil/trace.go:171","msg":"trace[396600590] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:586; }","duration":"452.410615ms","start":"2026-01-19T16:08:44.620931Z","end":"2026-01-19T16:08:45.073342Z","steps":["trace[396600590] 'range keys from in-memory index tree' (duration: 452.329014ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:45.073362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:44.620918Z","time spent":"452.440125ms","remote":"127.0.0.1:45278","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-01-19T16:08:45.073436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.004211ms","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-01-19T16:08:45.073475Z","caller":"traceutil/trace.go:171","msg":"trace[1397145445] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:586; }","duration":"410.044912ms","start":"2026-01-19T16:08:44.663424Z","end":"2026-01-19T16:08:45.073469Z","steps":["trace[1397145445] 'range keys from in-memory index tree' (duration: 409.999161ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:45.073614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.513648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-01-19T16:08:45.073633Z","caller":"traceutil/trace.go:171","msg":"trace[1621861220] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:586; }","duration":"391.564038ms","start":"2026-01-19T16:08:44.682061Z","end":"2026-01-19T16:08:45.073625Z","steps":["trace[1621861220] 'range keys from in-memory index tree' (duration: 391.451716ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:45.073649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:44.682049Z","time spent":"391.596269ms","remote":"127.0.0.1:45350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":1,"response size":13253,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"warn","ts":"2026-01-19T16:08:45.073795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.519681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-01-19T16:08:45.073814Z","caller":"traceutil/trace.go:171","msg":"trace[208976276] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:586; }","duration":"191.561722ms","start":"2026-01-19T16:08:44.882246Z","end":"2026-01-19T16:08:45.073807Z","steps":["trace[208976276] 'range keys from in-memory index tree' (duration: 191.46013ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:08:46.019012Z","caller":"traceutil/trace.go:171","msg":"trace[2032526747] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"212.282212ms","start":"2026-01-19T16:08:45.806704Z","end":"2026-01-19T16:08:46.018986Z","steps":["trace[2032526747] 'process raft request' (duration: 211.981617ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:08:46.038792Z","caller":"traceutil/trace.go:171","msg":"trace[1999994906] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:614; }","duration":"157.28388ms","start":"2026-01-19T16:08:45.881489Z","end":"2026-01-19T16:08:46.038773Z","steps":["trace[1999994906] 'read index received' (duration: 137.721901ms)","trace[1999994906] 'applied index is now lower than readState.Index' (duration: 19.561169ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:08:46.038947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.437393ms","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-01-19T16:08:46.038975Z","caller":"traceutil/trace.go:171","msg":"trace[750335812] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:590; }","duration":"157.499673ms","start":"2026-01-19T16:08:45.881464Z","end":"2026-01-19T16:08:46.038963Z","steps":["trace[750335812] 'agreement among raft nodes before linearized reading' (duration: 157.371171ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:08:46.039371Z","caller":"traceutil/trace.go:171","msg":"trace[1999606975] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"219.988624ms","start":"2026-01-19T16:08:45.819372Z","end":"2026-01-19T16:08:46.039361Z","steps":["trace[1999606975] 'process raft request' (duration: 219.1639ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:46.520818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.138409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188243"} {"level":"info","ts":"2026-01-19T16:08:46.520893Z","caller":"traceutil/trace.go:171","msg":"trace[2033898794] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:592; }","duration":"145.314622ms","start":"2026-01-19T16:08:46.375563Z","end":"2026-01-19T16:08:46.520877Z","steps":["trace[2033898794] 'range keys from in-memory index tree' (duration: 144.917985ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:08:46.571534Z","caller":"traceutil/trace.go:171","msg":"trace[823017456] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"144.977736ms","start":"2026-01-19T16:08:46.426538Z","end":"2026-01-19T16:08:46.571516Z","steps":["trace[823017456] 'process raft request' (duration: 144.836203ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:08:46.571893Z","caller":"traceutil/trace.go:171","msg":"trace[1605570360] linearizableReadLoop","detail":"{readStateIndex:618; appliedIndex:618; }","duration":"119.905991ms","start":"2026-01-19T16:08:46.451981Z","end":"2026-01-19T16:08:46.571887Z","steps":["trace[1605570360] 'read index received' (duration: 119.903651ms)","trace[1605570360] 'applied index is now lower than readState.Index' (duration: 1.87µs)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:08:46.571992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.989173ms","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-01-19T16:08:46.572008Z","caller":"traceutil/trace.go:171","msg":"trace[194659458] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:593; }","duration":"120.026963ms","start":"2026-01-19T16:08:46.451976Z","end":"2026-01-19T16:08:46.572003Z","steps":["trace[194659458] 'agreement among raft nodes before linearized reading' (duration: 119.947512ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:08:46.855859Z","caller":"traceutil/trace.go:171","msg":"trace[1334336983] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"371.883205ms","start":"2026-01-19T16:08:46.483957Z","end":"2026-01-19T16:08:46.855841Z","steps":["trace[1334336983] 'process raft request' (duration: 371.806583ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:46.855946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:46.483947Z","time spent":"371.953626ms","remote":"127.0.0.1:45350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188237,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T16:08:46.856101Z","caller":"traceutil/trace.go:171","msg":"trace[173471121] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"377.367589ms","start":"2026-01-19T16:08:46.478728Z","end":"2026-01-19T16:08:46.856096Z","steps":["trace[173471121] 'process raft request' (duration: 376.937512ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:46.85613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:46.478712Z","time spent":"377.40169ms","remote":"127.0.0.1:45350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188471,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T16:08:46.856327Z","caller":"traceutil/trace.go:171","msg":"trace[1317815172] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"404.265286ms","start":"2026-01-19T16:08:46.452047Z","end":"2026-01-19T16:08:46.856313Z","steps":["trace[1317815172] 'process raft request' (duration: 374.705703ms)","trace[1317815172] 'compare' (duration: 28.698528ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:08:46.856376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:46.452039Z","time spent":"404.309897ms","remote":"127.0.0.1:45534","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-01-19T16:08:46.877621Z","caller":"traceutil/trace.go:171","msg":"trace[2076660140] linearizableReadLoop","detail":"{readStateIndex:622; appliedIndex:618; }","duration":"253.673172ms","start":"2026-01-19T16:08:46.623932Z","end":"2026-01-19T16:08:46.877606Z","steps":["trace[2076660140] 'read index received' (duration: 202.82724ms)","trace[2076660140] 'applied index is now lower than readState.Index' (duration: 50.845422ms)"],"step_count":2} {"level":"info","ts":"2026-01-19T16:08:46.877947Z","caller":"traceutil/trace.go:171","msg":"trace[1850098282] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"302.756785ms","start":"2026-01-19T16:08:46.575184Z","end":"2026-01-19T16:08:46.877941Z","steps":["trace[1850098282] 'process raft request' (duration: 302.352138ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:46.878009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:46.575163Z","time spent":"302.799665ms","remote":"127.0.0.1:45534","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-01-19T16:08:46.878016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.859449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188486"} {"level":"info","ts":"2026-01-19T16:08:46.878042Z","caller":"traceutil/trace.go:171","msg":"trace[456496069] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:597; }","duration":"248.92688ms","start":"2026-01-19T16:08:46.629108Z","end":"2026-01-19T16:08:46.878035Z","steps":["trace[456496069] 'agreement among raft nodes before linearized reading' (duration: 248.843828ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:46.878172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.246533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T16:08:46.878187Z","caller":"traceutil/trace.go:171","msg":"trace[14987762] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:597; }","duration":"254.284713ms","start":"2026-01-19T16:08:46.623898Z","end":"2026-01-19T16:08:46.878183Z","steps":["trace[14987762] 'agreement among raft nodes before linearized reading' (duration: 254.260063ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:47.308297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.243722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-01-19T16:08:47.308395Z","caller":"traceutil/trace.go:171","msg":"trace[1761810601] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:598; }","duration":"140.376324ms","start":"2026-01-19T16:08:47.168007Z","end":"2026-01-19T16:08:47.308384Z","steps":["trace[1761810601] 'range keys from in-memory index tree' (duration: 140.14317ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:08:48.605349Z","caller":"traceutil/trace.go:171","msg":"trace[478198095] linearizableReadLoop","detail":"{readStateIndex:626; appliedIndex:625; }","duration":"252.918905ms","start":"2026-01-19T16:08:48.352412Z","end":"2026-01-19T16:08:48.605331Z","steps":["trace[478198095] 'read index received' (duration: 252.779133ms)","trace[478198095] 'applied index is now lower than readState.Index' (duration: 139.072µs)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:08:48.605474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.044887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-01-19T16:08:48.605491Z","caller":"traceutil/trace.go:171","msg":"trace[1821094957] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:601; }","duration":"253.112598ms","start":"2026-01-19T16:08:48.352374Z","end":"2026-01-19T16:08:48.605486Z","steps":["trace[1821094957] 'agreement among raft nodes before linearized reading' (duration: 253.022986ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:08:48.605566Z","caller":"traceutil/trace.go:171","msg":"trace[74686033] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"313.406304ms","start":"2026-01-19T16:08:48.292154Z","end":"2026-01-19T16:08:48.605561Z","steps":["trace[74686033] 'process raft request' (duration: 313.035178ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:48.605617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:48.292137Z","time spent":"313.438574ms","remote":"127.0.0.1:45534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-19T16:08:48.805446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.199425ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-01-19T16:08:51.093068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.875026ms","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-01-19T16:08:51.093118Z","caller":"traceutil/trace.go:171","msg":"trace[913815038] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:607; }","duration":"188.953487ms","start":"2026-01-19T16:08:50.904153Z","end":"2026-01-19T16:08:51.093107Z","steps":["trace[913815038] 'range keys from in-memory index tree' (duration: 188.791245ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:08:53.852808Z","caller":"traceutil/trace.go:171","msg":"trace[18759503] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"703.353637ms","start":"2026-01-19T16:08:53.149435Z","end":"2026-01-19T16:08:53.852789Z","steps":["trace[18759503] 'process raft request' (duration: 703.229445ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:53.852923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:53.149424Z","time spent":"703.439049ms","remote":"127.0.0.1:45534","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-01-19T16:08:54.272737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.172342ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-19T16:08:54.272838Z","caller":"traceutil/trace.go:171","msg":"trace[1167741925] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"316.826143ms","start":"2026-01-19T16:08:53.956002Z","end":"2026-01-19T16:08:54.272828Z","steps":["trace[1167741925] 'process raft request' (duration: 316.794732ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:54.27287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:53.955981Z","time spent":"316.874454ms","remote":"127.0.0.1:45534","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-01-19T16:08:54.273037Z","caller":"traceutil/trace.go:171","msg":"trace[2087632626] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:639; }","duration":"894.051884ms","start":"2026-01-19T16:08:53.37893Z","end":"2026-01-19T16:08:54.272982Z","steps":["trace[2087632626] 'read index received' (duration: 474.350546ms)","trace[2087632626] 'applied index is now lower than readState.Index' (duration: 419.698618ms)"],"step_count":2} {"level":"info","ts":"2026-01-19T16:08:54.273092Z","caller":"traceutil/trace.go:171","msg":"trace[421255950] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"1.123511443s","start":"2026-01-19T16:08:53.149574Z","end":"2026-01-19T16:08:54.273085Z","steps":["trace[421255950] 'process raft request' (duration: 772.943284ms)","trace[421255950] 'compare' (duration: 349.992709ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:08:54.27312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:53.149568Z","time spent":"1.123539423s","remote":"127.0.0.1:45534","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-01-19T16:08:54.273274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"891.791707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.206\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-01-19T16:08:54.273289Z","caller":"traceutil/trace.go:171","msg":"trace[1168011646] range","detail":"{range_begin:/registry/masterleases/199.19.213.206; range_end:; response_count:1; response_revision:615; }","duration":"891.835557ms","start":"2026-01-19T16:08:53.381448Z","end":"2026-01-19T16:08:54.273284Z","steps":["trace[1168011646] 'agreement among raft nodes before linearized reading' (duration: 891.769876ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:54.27329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"894.3366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-01-19T16:08:54.273322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.890533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T16:08:54.273335Z","caller":"traceutil/trace.go:171","msg":"trace[595103365] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:615; }","duration":"645.942373ms","start":"2026-01-19T16:08:53.627388Z","end":"2026-01-19T16:08:54.273331Z","steps":["trace[595103365] 'agreement among raft nodes before linearized reading' (duration: 645.924063ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:08:54.273331Z","caller":"traceutil/trace.go:171","msg":"trace[103642255] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:615; }","duration":"894.430751ms","start":"2026-01-19T16:08:53.37889Z","end":"2026-01-19T16:08:54.27332Z","steps":["trace[103642255] 'agreement among raft nodes before linearized reading' (duration: 894.243378ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:54.273346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:53.627363Z","time spent":"645.980954ms","remote":"127.0.0.1:45278","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-01-19T16:08:54.273375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:53.378869Z","time spent":"894.497602ms","remote":"127.0.0.1:45534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-19T16:08:54.273302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:53.381437Z","time spent":"891.862259ms","remote":"127.0.0.1:45334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.206\" "} {"level":"warn","ts":"2026-01-19T16:08:55.187763Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8934750058525617234,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-19T16:08:55.701795Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8934750058525617234,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-19T16:08:56.20219Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8934750058525617234,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-19T16:08:56.488109Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.789957001s","expected-duration":"1s"} {"level":"info","ts":"2026-01-19T16:08:56.488444Z","caller":"traceutil/trace.go:171","msg":"trace[1526108034] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:647; }","duration":"1.801117997s","start":"2026-01-19T16:08:54.687302Z","end":"2026-01-19T16:08:56.48842Z","steps":["trace[1526108034] 'read index received' (duration: 1.800938254s)","trace[1526108034] 'applied index is now lower than readState.Index' (duration: 178.853µs)"],"step_count":2} {"level":"info","ts":"2026-01-19T16:08:56.488679Z","caller":"traceutil/trace.go:171","msg":"trace[2136685196] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"1.8593917s","start":"2026-01-19T16:08:54.629276Z","end":"2026-01-19T16:08:56.488668Z","steps":["trace[2136685196] 'process raft request' (duration: 1.859016914s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:56.488804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:54.629263Z","time spent":"1.859453561s","remote":"127.0.0.1:45534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-19T16:08:56.489105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.801792588s","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-01-19T16:08:56.489161Z","caller":"traceutil/trace.go:171","msg":"trace[240944105] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:621; }","duration":"1.801879689s","start":"2026-01-19T16:08:54.687266Z","end":"2026-01-19T16:08:56.489145Z","steps":["trace[240944105] 'agreement among raft nodes before linearized reading' (duration: 1.801721167s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:56.489199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:54.687249Z","time spent":"1.801942551s","remote":"127.0.0.1:45534","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-01-19T16:08:56.48936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.128332663s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-rlthg\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-01-19T16:08:56.489396Z","caller":"traceutil/trace.go:171","msg":"trace[1533901270] range","detail":"{range_begin:/registry/pods/kube-system/cilium-rlthg; range_end:; response_count:1; response_revision:621; }","duration":"1.128406444s","start":"2026-01-19T16:08:55.36098Z","end":"2026-01-19T16:08:56.489386Z","steps":["trace[1533901270] 'agreement among raft nodes before linearized reading' (duration: 1.128346483s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:56.489427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:55.360966Z","time spent":"1.128454965s","remote":"127.0.0.1:45462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16680,"request content":"key:\"/registry/pods/kube-system/cilium-rlthg\" "} {"level":"warn","ts":"2026-01-19T16:08:56.489714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.280614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-01-19T16:08:56.489754Z","caller":"traceutil/trace.go:171","msg":"trace[74537579] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:621; }","duration":"200.346894ms","start":"2026-01-19T16:08:56.289396Z","end":"2026-01-19T16:08:56.489743Z","steps":["trace[74537579] 'agreement among raft nodes before linearized reading' (duration: 200.257063ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:56.489921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.135679ms","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-01-19T16:08:56.489955Z","caller":"traceutil/trace.go:171","msg":"trace[1579435580] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:621; }","duration":"212.19598ms","start":"2026-01-19T16:08:56.277749Z","end":"2026-01-19T16:08:56.489945Z","steps":["trace[1579435580] 'agreement among raft nodes before linearized reading' (duration: 212.108059ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:56.491743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.520624ms","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-01-19T16:08:56.491927Z","caller":"traceutil/trace.go:171","msg":"trace[437663903] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:621; }","duration":"635.611236ms","start":"2026-01-19T16:08:55.856168Z","end":"2026-01-19T16:08:56.491779Z","steps":["trace[437663903] 'agreement among raft nodes before linearized reading' (duration: 633.882737ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:56.492024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:55.856148Z","time spent":"635.86437ms","remote":"127.0.0.1:45534","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-01-19T16:08:56.492573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"870.793273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T16:08:56.492615Z","caller":"traceutil/trace.go:171","msg":"trace[398272413] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:621; }","duration":"870.871603ms","start":"2026-01-19T16:08:55.621733Z","end":"2026-01-19T16:08:56.492605Z","steps":["trace[398272413] 'agreement among raft nodes before linearized reading' (duration: 870.765902ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:56.492881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:55.621672Z","time spent":"871.109958ms","remote":"127.0.0.1:45278","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-01-19T16:08:59.006299Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8934750058525617238,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-19T16:08:59.280354Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.77069672s","expected-duration":"1s"} {"level":"warn","ts":"2026-01-19T16:08:59.522778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.015298812s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T16:08:59.522859Z","caller":"traceutil/trace.go:171","msg":"trace[1155323783] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:621; }","duration":"3.015411634s","start":"2026-01-19T16:08:56.507425Z","end":"2026-01-19T16:08:59.522837Z","steps":["trace[1155323783] 'range keys from in-memory index tree' (duration: 3.01524292s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:59.522911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:56.50741Z","time spent":"3.015487695s","remote":"127.0.0.1:45290","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-01-19T16:08:59.525666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.961717ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-19T16:08:59.525765Z","caller":"traceutil/trace.go:171","msg":"trace[544544694] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"3.016121365s","start":"2026-01-19T16:08:56.509606Z","end":"2026-01-19T16:08:59.525728Z","steps":["trace[544544694] 'process raft request' (duration: 2.771038706s)","trace[544544694] 'compare' (duration: 244.748594ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:08:59.525842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:56.509593Z","time spent":"3.016219536s","remote":"127.0.0.1:45534","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-01-19T16:08:59.529799Z","caller":"traceutil/trace.go:171","msg":"trace[1752022380] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"3.017688619s","start":"2026-01-19T16:08:56.512094Z","end":"2026-01-19T16:08:59.529783Z","steps":["trace[1752022380] 'process raft request' (duration: 3.014234133s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:59.529873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:56.512087Z","time spent":"3.017749481s","remote":"127.0.0.1:45534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T16:08:59.529897Z","caller":"traceutil/trace.go:171","msg":"trace[63378723] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"3.009925781s","start":"2026-01-19T16:08:56.519947Z","end":"2026-01-19T16:08:59.529873Z","steps":["trace[63378723] 'process raft request' (duration: 3.006578716s)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:08:59.52997Z","caller":"traceutil/trace.go:171","msg":"trace[489306981] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"2.597546159s","start":"2026-01-19T16:08:56.932417Z","end":"2026-01-19T16:08:59.529963Z","steps":["trace[489306981] 'process raft request' (duration: 2.594140763s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:59.530011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:56.9324Z","time spent":"2.59758594s","remote":"127.0.0.1:45534","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-01-19T16:08:59.530037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:56.51994Z","time spent":"3.010019902s","remote":"127.0.0.1:45534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T16:08:59.53016Z","caller":"traceutil/trace.go:171","msg":"trace[1587405087] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"1.02470984s","start":"2026-01-19T16:08:58.505439Z","end":"2026-01-19T16:08:59.530149Z","steps":["trace[1587405087] 'process raft request' (duration: 1.021147322s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:59.530202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:58.505419Z","time spent":"1.024764021s","remote":"127.0.0.1:45380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-19T16:08:59.53037Z","caller":"traceutil/trace.go:171","msg":"trace[1066759775] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"3.011711061s","start":"2026-01-19T16:08:56.518651Z","end":"2026-01-19T16:08:59.530362Z","steps":["trace[1066759775] 'process raft request' (duration: 3.007844697s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:59.53041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:56.518644Z","time spent":"3.011744881s","remote":"127.0.0.1:45534","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-01-19T16:08:59.530519Z","caller":"traceutil/trace.go:171","msg":"trace[1407580753] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"3.014154591s","start":"2026-01-19T16:08:56.516356Z","end":"2026-01-19T16:08:59.530511Z","steps":["trace[1407580753] 'process raft request' (duration: 3.010082365s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:59.53056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:56.516347Z","time spent":"3.014191151s","remote":"127.0.0.1:45462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T16:08:59.539881Z","caller":"traceutil/trace.go:171","msg":"trace[454453969] linearizableReadLoop","detail":"{readStateIndex:655; appliedIndex:648; }","duration":"1.033836869s","start":"2026-01-19T16:08:58.506028Z","end":"2026-01-19T16:08:59.539865Z","steps":["trace[454453969] 'read index received' (duration: 774.624439ms)","trace[454453969] 'applied index is now lower than readState.Index' (duration: 259.21147ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:08:59.540208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.034168154s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T16:08:59.540278Z","caller":"traceutil/trace.go:171","msg":"trace[893386082] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:628; }","duration":"1.034257356s","start":"2026-01-19T16:08:58.506011Z","end":"2026-01-19T16:08:59.540268Z","steps":["trace[893386082] 'agreement among raft nodes before linearized reading' (duration: 1.034164265s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:59.540308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:58.506006Z","time spent":"1.034295887s","remote":"127.0.0.1:45278","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-01-19T16:08:59.540561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.022264559s","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-01-19T16:08:59.540685Z","caller":"traceutil/trace.go:171","msg":"trace[6472835] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:628; }","duration":"1.022403721s","start":"2026-01-19T16:08:58.518273Z","end":"2026-01-19T16:08:59.540676Z","steps":["trace[6472835] 'agreement among raft nodes before linearized reading' (duration: 1.022205888s)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:08:59.540721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:08:58.518258Z","time spent":"1.022451253s","remote":"127.0.0.1:45534","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-01-19T16:09:00.511324Z","caller":"traceutil/trace.go:171","msg":"trace[809453865] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"120.382746ms","start":"2026-01-19T16:09:00.390928Z","end":"2026-01-19T16:09:00.51131Z","steps":["trace[809453865] 'process raft request' (duration: 119.341589ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:09:02.693784Z","caller":"traceutil/trace.go:171","msg":"trace[880856803] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"123.975528ms","start":"2026-01-19T16:09:02.569781Z","end":"2026-01-19T16:09:02.693757Z","steps":["trace[880856803] 'process raft request' (duration: 123.744014ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:09:03.714408Z","caller":"traceutil/trace.go:171","msg":"trace[481504027] linearizableReadLoop","detail":"{readStateIndex:678; appliedIndex:677; }","duration":"166.661834ms","start":"2026-01-19T16:09:03.547728Z","end":"2026-01-19T16:09:03.71439Z","steps":["trace[481504027] 'read index received' (duration: 103.761375ms)","trace[481504027] 'applied index is now lower than readState.Index' (duration: 62.899899ms)"],"step_count":2} {"level":"info","ts":"2026-01-19T16:09:03.714568Z","caller":"traceutil/trace.go:171","msg":"trace[142232328] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"289.79839ms","start":"2026-01-19T16:09:03.42476Z","end":"2026-01-19T16:09:03.714559Z","steps":["trace[142232328] 'process raft request' (duration: 226.725768ms)","trace[142232328] 'compare' (duration: 62.826208ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:09:03.714732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.999279ms","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-01-19T16:09:03.714751Z","caller":"traceutil/trace.go:171","msg":"trace[1255715904] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:649; }","duration":"167.184663ms","start":"2026-01-19T16:09:03.54756Z","end":"2026-01-19T16:09:03.714745Z","steps":["trace[1255715904] 'agreement among raft nodes before linearized reading' (duration: 167.097671ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:09:03.714893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.221071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-01-19T16:09:03.714907Z","caller":"traceutil/trace.go:171","msg":"trace[770450165] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:649; }","duration":"165.246071ms","start":"2026-01-19T16:09:03.549656Z","end":"2026-01-19T16:09:03.714903Z","steps":["trace[770450165] 'agreement among raft nodes before linearized reading' (duration: 165.204671ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:09:03.715131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.089446ms","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-01-19T16:09:03.715146Z","caller":"traceutil/trace.go:171","msg":"trace[1882157670] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:649; }","duration":"100.119776ms","start":"2026-01-19T16:09:03.615022Z","end":"2026-01-19T16:09:03.715142Z","steps":["trace[1882157670] 'agreement among raft nodes before linearized reading' (duration: 100.076946ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:09:03.715224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.247367ms","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-01-19T16:09:03.715241Z","caller":"traceutil/trace.go:171","msg":"trace[55150152] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:649; }","duration":"113.284168ms","start":"2026-01-19T16:09:03.601949Z","end":"2026-01-19T16:09:03.715233Z","steps":["trace[55150152] 'agreement among raft nodes before linearized reading' (duration: 113.247027ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:09:03.715316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.739933ms","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-01-19T16:09:03.715332Z","caller":"traceutil/trace.go:171","msg":"trace[1440041226] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:649; }","duration":"164.765093ms","start":"2026-01-19T16:09:03.550563Z","end":"2026-01-19T16:09:03.715328Z","steps":["trace[1440041226] 'agreement among raft nodes before linearized reading' (duration: 164.726393ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:09:04.145254Z","caller":"traceutil/trace.go:171","msg":"trace[1439964471] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"156.279559ms","start":"2026-01-19T16:09:03.988959Z","end":"2026-01-19T16:09:04.145238Z","steps":["trace[1439964471] 'process raft request' (duration: 156.165557ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:09:04.657365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.978592ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-01-19T16:09:04.657499Z","caller":"traceutil/trace.go:171","msg":"trace[940392938] linearizableReadLoop","detail":"{readStateIndex:687; appliedIndex:685; }","duration":"249.853263ms","start":"2026-01-19T16:09:04.407591Z","end":"2026-01-19T16:09:04.657444Z","steps":["trace[940392938] 'read index received' (duration: 30.185073ms)","trace[940392938] 'applied index is now lower than readState.Index' (duration: 219.66706ms)"],"step_count":2} {"level":"info","ts":"2026-01-19T16:09:04.65759Z","caller":"traceutil/trace.go:171","msg":"trace[1826744384] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"267.157889ms","start":"2026-01-19T16:09:04.39042Z","end":"2026-01-19T16:09:04.657578Z","steps":["trace[1826744384] 'process raft request' (duration: 110.901722ms)","trace[1826744384] 'compare' (duration: 155.771739ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:09:04.657896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.29535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-rlthg\" ","response":"range_response_count:1 size:17670"} {"level":"info","ts":"2026-01-19T16:09:04.657936Z","caller":"traceutil/trace.go:171","msg":"trace[185803271] range","detail":"{range_begin:/registry/pods/kube-system/cilium-rlthg; range_end:; response_count:1; response_revision:657; }","duration":"250.36377ms","start":"2026-01-19T16:09:04.407562Z","end":"2026-01-19T16:09:04.657926Z","steps":["trace[185803271] 'agreement among raft nodes before linearized reading' (duration: 250.196108ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:09:04.992793Z","caller":"traceutil/trace.go:171","msg":"trace[214307630] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"320.370109ms","start":"2026-01-19T16:09:04.672402Z","end":"2026-01-19T16:09:04.992772Z","steps":["trace[214307630] 'process raft request' (duration: 320.203487ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:09:04.992972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:09:04.672386Z","time spent":"320.490951ms","remote":"127.0.0.1:45462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17817,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-19T16:09:05.63478Z","caller":"traceutil/trace.go:171","msg":"trace[1297935274] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"200.041543ms","start":"2026-01-19T16:09:05.434714Z","end":"2026-01-19T16:09:05.634756Z","steps":["trace[1297935274] 'process raft request' (duration: 199.8272ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:09:05.674617Z","caller":"traceutil/trace.go:171","msg":"trace[832775235] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"237.056412ms","start":"2026-01-19T16:09:05.437539Z","end":"2026-01-19T16:09:05.674595Z","steps":["trace[832775235] 'process raft request' (duration: 236.683226ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:09:07.536751Z","caller":"traceutil/trace.go:171","msg":"trace[984933181] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"127.134777ms","start":"2026-01-19T16:09:07.4096Z","end":"2026-01-19T16:09:07.536735Z","steps":["trace[984933181] 'process raft request' (duration: 118.276757ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:09:09.881101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.845474ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-01-19T16:09:09.881682Z","caller":"traceutil/trace.go:171","msg":"trace[863002435] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"189.138242ms","start":"2026-01-19T16:09:09.69252Z","end":"2026-01-19T16:09:09.881658Z","steps":["trace[863002435] 'process raft request' (duration: 188.947889ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:09:09.882478Z","caller":"traceutil/trace.go:171","msg":"trace[1305245318] linearizableReadLoop","detail":"{readStateIndex:718; appliedIndex:718; }","duration":"154.739163ms","start":"2026-01-19T16:09:09.727726Z","end":"2026-01-19T16:09:09.882465Z","steps":["trace[1305245318] 'read index received' (duration: 154.734633ms)","trace[1305245318] 'applied index is now lower than readState.Index' (duration: 3.7µs)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:09:09.882552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.819134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T16:09:09.882569Z","caller":"traceutil/trace.go:171","msg":"trace[1908896930] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:686; }","duration":"154.845894ms","start":"2026-01-19T16:09:09.727718Z","end":"2026-01-19T16:09:09.882564Z","steps":["trace[1908896930] 'agreement among raft nodes before linearized reading' (duration: 154.799594ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:09:09.910305Z","caller":"traceutil/trace.go:171","msg":"trace[908506963] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"116.007136ms","start":"2026-01-19T16:09:09.794195Z","end":"2026-01-19T16:09:09.910202Z","steps":["trace[908506963] 'process raft request' (duration: 115.59088ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:09:22.174127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.982709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-01-19T16:09:22.174213Z","caller":"traceutil/trace.go:171","msg":"trace[2115418686] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:787; }","duration":"184.132332ms","start":"2026-01-19T16:09:21.990055Z","end":"2026-01-19T16:09:22.174187Z","steps":["trace[2115418686] 'range keys from in-memory index tree' (duration: 183.829228ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:09:22.174275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.951663ms","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-01-19T16:09:22.174327Z","caller":"traceutil/trace.go:171","msg":"trace[233640970] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:787; }","duration":"114.034954ms","start":"2026-01-19T16:09:22.060278Z","end":"2026-01-19T16:09:22.174313Z","steps":["trace[233640970] 'range keys from in-memory index tree' (duration: 113.849281ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:09:22.174474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.649659ms","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-01-19T16:09:22.174497Z","caller":"traceutil/trace.go:171","msg":"trace[141541412] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:787; }","duration":"204.69504ms","start":"2026-01-19T16:09:21.969792Z","end":"2026-01-19T16:09:22.174487Z","steps":["trace[141541412] 'range keys from in-memory index tree' (duration: 204.590169ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:09:22.358114Z","caller":"traceutil/trace.go:171","msg":"trace[578710102] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"175.705945ms","start":"2026-01-19T16:09:22.182353Z","end":"2026-01-19T16:09:22.358059Z","steps":["trace[578710102] 'process raft request' (duration: 171.442901ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:09:22.358191Z","caller":"traceutil/trace.go:171","msg":"trace[1719713595] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"175.648954ms","start":"2026-01-19T16:09:22.182538Z","end":"2026-01-19T16:09:22.358187Z","steps":["trace[1719713595] 'process raft request' (duration: 175.465781ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:09:22.358135Z","caller":"traceutil/trace.go:171","msg":"trace[851748025] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"175.640394ms","start":"2026-01-19T16:09:22.182479Z","end":"2026-01-19T16:09:22.358119Z","steps":["trace[851748025] 'process raft request' (duration: 175.505442ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:09:22.358521Z","caller":"traceutil/trace.go:171","msg":"trace[2070098432] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"173.240129ms","start":"2026-01-19T16:09:22.1848Z","end":"2026-01-19T16:09:22.35804Z","steps":["trace[2070098432] 'process raft request' (duration: 173.217369ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:09:42.697353Z","caller":"traceutil/trace.go:171","msg":"trace[1871720809] transaction","detail":"{read_only:false; response_revision:854; number_of_response:1; }","duration":"188.513415ms","start":"2026-01-19T16:09:42.508821Z","end":"2026-01-19T16:09:42.697334Z","steps":["trace[1871720809] 'process raft request' (duration: 175.002785ms)","trace[1871720809] 'compare' (duration: 13.183685ms)"],"step_count":2} {"level":"info","ts":"2026-01-19T16:10:02.883034Z","caller":"traceutil/trace.go:171","msg":"trace[677893145] linearizableReadLoop","detail":"{readStateIndex:949; appliedIndex:948; }","duration":"175.694895ms","start":"2026-01-19T16:10:02.707317Z","end":"2026-01-19T16:10:02.883012Z","steps":["trace[677893145] 'read index received' (duration: 175.098197ms)","trace[677893145] 'applied index is now lower than readState.Index' (duration: 595.808µs)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:10:02.88409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.776482ms","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-01-19T16:10:02.884144Z","caller":"traceutil/trace.go:171","msg":"trace[326146701] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:907; }","duration":"174.854934ms","start":"2026-01-19T16:10:02.709277Z","end":"2026-01-19T16:10:02.884132Z","steps":["trace[326146701] 'agreement among raft nodes before linearized reading' (duration: 174.702312ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:10:02.884585Z","caller":"traceutil/trace.go:171","msg":"trace[131148867] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"195.623113ms","start":"2026-01-19T16:10:02.68895Z","end":"2026-01-19T16:10:02.884573Z","steps":["trace[131148867] 'process raft request' (duration: 193.531145ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:10:02.885154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.826784ms","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-01-19T16:10:02.885201Z","caller":"traceutil/trace.go:171","msg":"trace[1636331421] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:907; }","duration":"177.872115ms","start":"2026-01-19T16:10:02.707314Z","end":"2026-01-19T16:10:02.885186Z","steps":["trace[1636331421] 'agreement among raft nodes before linearized reading' (duration: 177.748963ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:10:11.282323Z","caller":"traceutil/trace.go:171","msg":"trace[40876722] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"165.271038ms","start":"2026-01-19T16:10:11.117027Z","end":"2026-01-19T16:10:11.282298Z","steps":["trace[40876722] 'process raft request' (duration: 97.46112ms)","trace[40876722] 'compare' (duration: 67.703096ms)"],"step_count":2} {"level":"info","ts":"2026-01-19T16:10:14.851043Z","caller":"traceutil/trace.go:171","msg":"trace[498851731] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"128.899448ms","start":"2026-01-19T16:10:14.722119Z","end":"2026-01-19T16:10:14.851019Z","steps":["trace[498851731] 'process raft request' (duration: 128.32849ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:10:17.842341Z","caller":"traceutil/trace.go:171","msg":"trace[2002775471] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"122.09194ms","start":"2026-01-19T16:10:17.720236Z","end":"2026-01-19T16:10:17.842328Z","steps":["trace[2002775471] 'process raft request' (duration: 121.792996ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:10:23.345374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.333952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-01-19T16:10:23.345815Z","caller":"traceutil/trace.go:171","msg":"trace[1777063537] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1000; }","duration":"304.815928ms","start":"2026-01-19T16:10:23.040983Z","end":"2026-01-19T16:10:23.345799Z","steps":["trace[1777063537] 'range keys from in-memory index tree' (duration: 304.19778ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:10:23.345897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-19T16:10:23.040962Z","time spent":"304.92406ms","remote":"127.0.0.1:45534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-19T16:10:23.346154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.068326ms","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-01-19T16:10:23.346216Z","caller":"traceutil/trace.go:171","msg":"trace[281705487] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1000; }","duration":"273.169527ms","start":"2026-01-19T16:10:23.073039Z","end":"2026-01-19T16:10:23.346208Z","steps":["trace[281705487] 'range keys from in-memory index tree' (duration: 272.964545ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:10:23.347357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.619686ms","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-01-19T16:10:23.35044Z","caller":"traceutil/trace.go:171","msg":"trace[1716119355] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1000; }","duration":"190.749727ms","start":"2026-01-19T16:10:23.159681Z","end":"2026-01-19T16:10:23.350431Z","steps":["trace[1716119355] 'range keys from in-memory index tree' (duration: 187.485795ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:10:23.347594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.106378ms","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-01-19T16:10:23.350525Z","caller":"traceutil/trace.go:171","msg":"trace[1956044188] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1000; }","duration":"186.070986ms","start":"2026-01-19T16:10:23.164448Z","end":"2026-01-19T16:10:23.350519Z","steps":["trace[1956044188] 'range keys from in-memory index tree' (duration: 182.899395ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:10:25.546296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.926114ms","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":"warn","ts":"2026-01-19T16:10:25.546828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.187225ms","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-01-19T16:10:25.546911Z","caller":"traceutil/trace.go:171","msg":"trace[130344427] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1011; }","duration":"126.273665ms","start":"2026-01-19T16:10:25.42062Z","end":"2026-01-19T16:10:25.546893Z","steps":["trace[130344427] 'range keys from in-memory index tree' (duration: 125.219612ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:10:25.54683Z","caller":"traceutil/trace.go:171","msg":"trace[20018374] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1011; }","duration":"127.516761ms","start":"2026-01-19T16:10:25.419291Z","end":"2026-01-19T16:10:25.546808Z","steps":["trace[20018374] 'range keys from in-memory index tree' (duration: 126.753762ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:10:25.546684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.253785ms","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-01-19T16:10:25.54863Z","caller":"traceutil/trace.go:171","msg":"trace[808132122] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1011; }","duration":"128.310412ms","start":"2026-01-19T16:10:25.420311Z","end":"2026-01-19T16:10:25.548621Z","steps":["trace[808132122] 'range keys from in-memory index tree' (duration: 126.051772ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:10:25.675288Z","caller":"traceutil/trace.go:171","msg":"trace[1494478077] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"123.101234ms","start":"2026-01-19T16:10:25.55217Z","end":"2026-01-19T16:10:25.675271Z","steps":["trace[1494478077] 'process raft request' (duration: 122.894211ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:10:25.6872Z","caller":"traceutil/trace.go:171","msg":"trace[2088105874] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"133.62737ms","start":"2026-01-19T16:10:25.553558Z","end":"2026-01-19T16:10:25.687185Z","steps":["trace[2088105874] 'process raft request' (duration: 133.178504ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:10:25.687719Z","caller":"traceutil/trace.go:171","msg":"trace[644351428] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"132.8997ms","start":"2026-01-19T16:10:25.554807Z","end":"2026-01-19T16:10:25.687707Z","steps":["trace[644351428] 'process raft request' (duration: 132.315903ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:10:26.407987Z","caller":"traceutil/trace.go:171","msg":"trace[2054454232] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"185.66787ms","start":"2026-01-19T16:10:26.222296Z","end":"2026-01-19T16:10:26.407964Z","steps":["trace[2054454232] 'process raft request' (duration: 185.540599ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:10:27.505728Z","caller":"traceutil/trace.go:171","msg":"trace[1377930158] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"105.498393ms","start":"2026-01-19T16:10:27.400205Z","end":"2026-01-19T16:10:27.505703Z","steps":["trace[1377930158] 'process raft request' (duration: 105.333381ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:10:39.782756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.13813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:public-info-viewer\" ","response":"range_response_count:1 size:613"} {"level":"info","ts":"2026-01-19T16:10:39.782831Z","caller":"traceutil/trace.go:171","msg":"trace[1972957988] range","detail":"{range_begin:/registry/clusterroles/system:public-info-viewer; range_end:; response_count:1; response_revision:1051; }","duration":"165.251462ms","start":"2026-01-19T16:10:39.617561Z","end":"2026-01-19T16:10:39.782813Z","steps":["trace[1972957988] 'range keys from in-memory index tree' (duration: 165.064079ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:10:39.783296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.89482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:service-account-issuer-discovery\" ","response":"range_response_count:1 size:699"} {"level":"info","ts":"2026-01-19T16:10:39.783393Z","caller":"traceutil/trace.go:171","msg":"trace[1795904247] range","detail":"{range_begin:/registry/clusterroles/system:service-account-issuer-discovery; range_end:; response_count:1; response_revision:1051; }","duration":"126.039463ms","start":"2026-01-19T16:10:39.65733Z","end":"2026-01-19T16:10:39.78337Z","steps":["trace[1795904247] 'range keys from in-memory index tree' (duration: 125.760069ms)"],"step_count":1} {"level":"warn","ts":"2026-01-19T16:10:39.783436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.247893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-19T16:10:39.783473Z","caller":"traceutil/trace.go:171","msg":"trace[2121871113] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1051; }","duration":"162.295324ms","start":"2026-01-19T16:10:39.621168Z","end":"2026-01-19T16:10:39.783463Z","steps":["trace[2121871113] 'range keys from in-memory index tree' (duration: 162.206033ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:10:56.10238Z","caller":"traceutil/trace.go:171","msg":"trace[1787165388] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"151.996221ms","start":"2026-01-19T16:10:55.950356Z","end":"2026-01-19T16:10:56.102352Z","steps":["trace[1787165388] 'process raft request' (duration: 151.853096ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:10:56.109259Z","caller":"traceutil/trace.go:171","msg":"trace[802814994] linearizableReadLoop","detail":"{readStateIndex:1153; appliedIndex:1152; }","duration":"133.808631ms","start":"2026-01-19T16:10:55.975433Z","end":"2026-01-19T16:10:56.109242Z","steps":["trace[802814994] 'read index received' (duration: 127.221657ms)","trace[802814994] 'applied index is now lower than readState.Index' (duration: 6.585944ms)"],"step_count":2} {"level":"warn","ts":"2026-01-19T16:10:56.109635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.154864ms","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-01-19T16:10:56.109679Z","caller":"traceutil/trace.go:171","msg":"trace[341406957] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1098; }","duration":"134.265587ms","start":"2026-01-19T16:10:55.975403Z","end":"2026-01-19T16:10:56.109668Z","steps":["trace[341406957] 'agreement among raft nodes before linearized reading' (duration: 133.925044ms)"],"step_count":1} {"level":"info","ts":"2026-01-19T16:10:56.110304Z","caller":"traceutil/trace.go:171","msg":"trace[701133142] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"158.415756ms","start":"2026-01-19T16:10:55.951135Z","end":"2026-01-19T16:10:56.109551Z","steps":["trace[701133142] 'process raft request' (duration: 158.029641ms)"],"step_count":1}