{"level":"warn","ts":"2026-02-17T16:36:27.314643Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-17T16:36:27.314745Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.95:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.95:2380","--initial-cluster=instance=https://199.204.45.95:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.95:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.95:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-17T16:36:27.314959Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-17T16:36:27.314973Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.95:2380"]} {"level":"info","ts":"2026-02-17T16:36:27.315048Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-17T16:36:27.315876Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"]} {"level":"info","ts":"2026-02-17T16:36:27.316047Z","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.204.45.95:2380"],"listen-peer-urls":["https://199.204.45.95:2380"],"advertise-client-urls":["https://199.204.45.95:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.95:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-17T16:36:27.36059Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"44.233529ms"} {"level":"info","ts":"2026-02-17T16:36:27.686966Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"be773dfc027af3eb","cluster-id":"9ba5fbd7288fd5af"} {"level":"info","ts":"2026-02-17T16:36:27.687065Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=()"} {"level":"info","ts":"2026-02-17T16:36:27.687086Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became follower at term 0"} {"level":"info","ts":"2026-02-17T16:36:27.687097Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft be773dfc027af3eb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-17T16:36:27.687104Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became follower at term 1"} {"level":"info","ts":"2026-02-17T16:36:27.687163Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=(13724506542017541099)"} {"level":"warn","ts":"2026-02-17T16:36:27.784722Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-17T16:36:27.837957Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-17T16:36:27.876347Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-02-17T16:36:27.919206Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"be773dfc027af3eb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-17T16:36:27.91937Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"be773dfc027af3eb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-17T16:36:27.91956Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-17T16:36:27.919659Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-17T16:36:27.919718Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-17T16:36:27.930066Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=(13724506542017541099)"} {"level":"info","ts":"2026-02-17T16:36:27.930258Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9ba5fbd7288fd5af","local-member-id":"be773dfc027af3eb","added-peer-id":"be773dfc027af3eb","added-peer-peer-urls":["https://199.204.45.95:2380"]} {"level":"info","ts":"2026-02-17T16:36:27.931476Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-17T16:36:27.9316Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.95:2380"} {"level":"info","ts":"2026-02-17T16:36:27.93169Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.95:2380"} {"level":"info","ts":"2026-02-17T16:36:27.931715Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"be773dfc027af3eb","initial-advertise-peer-urls":["https://199.204.45.95:2380"],"listen-peer-urls":["https://199.204.45.95:2380"],"advertise-client-urls":["https://199.204.45.95:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-17T16:36:27.931773Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-17T16:36:28.087864Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb is starting a new election at term 1"} {"level":"info","ts":"2026-02-17T16:36:28.087991Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became pre-candidate at term 1"} {"level":"info","ts":"2026-02-17T16:36:28.088035Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb received MsgPreVoteResp from be773dfc027af3eb at term 1"} {"level":"info","ts":"2026-02-17T16:36:28.088101Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became candidate at term 2"} {"level":"info","ts":"2026-02-17T16:36:28.088138Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb received MsgVoteResp from be773dfc027af3eb at term 2"} {"level":"info","ts":"2026-02-17T16:36:28.0882Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became leader at term 2"} {"level":"info","ts":"2026-02-17T16:36:28.088238Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: be773dfc027af3eb elected leader be773dfc027af3eb at term 2"} {"level":"info","ts":"2026-02-17T16:36:28.102509Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T16:36:28.105501Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-17T16:36:28.105457Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"be773dfc027af3eb","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.95:2379]}","request-path":"/0/members/be773dfc027af3eb/attributes","cluster-id":"9ba5fbd7288fd5af","publish-timeout":"7s"} {"level":"info","ts":"2026-02-17T16:36:28.107306Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-17T16:36:28.110337Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.95:2379"} {"level":"info","ts":"2026-02-17T16:36:28.110512Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-17T16:36:28.110788Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-17T16:36:28.113162Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-17T16:36:28.155552Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9ba5fbd7288fd5af","local-member-id":"be773dfc027af3eb","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T16:36:28.155666Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T16:36:28.155693Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T16:36:29.664517Z","caller":"traceutil/trace.go:171","msg":"trace[174111463] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"148.964382ms","start":"2026-02-17T16:36:29.515535Z","end":"2026-02-17T16:36:29.664499Z","steps":["trace[174111463] 'read index received' (duration: 46.80928ms)","trace[174111463] 'applied index is now lower than readState.Index' (duration: 102.154322ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:36:29.664624Z","caller":"traceutil/trace.go:171","msg":"trace[389934317] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"134.196685ms","start":"2026-02-17T16:36:29.530421Z","end":"2026-02-17T16:36:29.664618Z","steps":["trace[389934317] 'process raft request' (duration: 133.963341ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:36:29.66482Z","caller":"traceutil/trace.go:171","msg":"trace[849695136] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"161.130158ms","start":"2026-02-17T16:36:29.503667Z","end":"2026-02-17T16:36:29.664797Z","steps":["trace[849695136] 'process raft request' (duration: 160.626907ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:36:29.664954Z","caller":"traceutil/trace.go:171","msg":"trace[1125003383] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"132.181317ms","start":"2026-02-17T16:36:29.532768Z","end":"2026-02-17T16:36:29.664949Z","steps":["trace[1125003383] 'process raft request' (duration: 131.693576ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:36:29.665039Z","caller":"traceutil/trace.go:171","msg":"trace[1657147789] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"132.197057ms","start":"2026-02-17T16:36:29.532836Z","end":"2026-02-17T16:36:29.665033Z","steps":["trace[1657147789] 'process raft request' (duration: 131.644025ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:36:29.665196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.651097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-17T16:36:29.665258Z","caller":"traceutil/trace.go:171","msg":"trace[600285775] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:9; }","duration":"149.754939ms","start":"2026-02-17T16:36:29.515496Z","end":"2026-02-17T16:36:29.665251Z","steps":["trace[600285775] 'agreement among raft nodes before linearized reading' (duration: 149.622956ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:36:29.664899Z","caller":"traceutil/trace.go:171","msg":"trace[154019111] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"132.569464ms","start":"2026-02-17T16:36:29.532323Z","end":"2026-02-17T16:36:29.664892Z","steps":["trace[154019111] 'process raft request' (duration: 132.087243ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:36:29.665707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.026942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-17T16:36:29.665734Z","caller":"traceutil/trace.go:171","msg":"trace[2127818375] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:9; }","duration":"114.081052ms","start":"2026-02-17T16:36:29.551647Z","end":"2026-02-17T16:36:29.665728Z","steps":["trace[2127818375] 'agreement among raft nodes before linearized reading' (duration: 114.039322ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:36:29.665924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.266967ms","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-02-17T16:36:29.665949Z","caller":"traceutil/trace.go:171","msg":"trace[1829061587] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:9; }","duration":"135.312229ms","start":"2026-02-17T16:36:29.53063Z","end":"2026-02-17T16:36:29.665942Z","steps":["trace[1829061587] 'agreement among raft nodes before linearized reading' (duration: 135.270988ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:36:29.666088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.542553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-17T16:36:29.666112Z","caller":"traceutil/trace.go:171","msg":"trace[1037586419] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:9; }","duration":"138.589104ms","start":"2026-02-17T16:36:29.527516Z","end":"2026-02-17T16:36:29.666105Z","steps":["trace[1037586419] 'agreement among raft nodes before linearized reading' (duration: 138.554543ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:36:29.664922Z","caller":"traceutil/trace.go:171","msg":"trace[1677469131] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"132.223067ms","start":"2026-02-17T16:36:29.532692Z","end":"2026-02-17T16:36:29.664916Z","steps":["trace[1677469131] 'process raft request' (duration: 131.733697ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:36:29.664938Z","caller":"traceutil/trace.go:171","msg":"trace[1564157944] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"132.202207ms","start":"2026-02-17T16:36:29.532732Z","end":"2026-02-17T16:36:29.664934Z","steps":["trace[1564157944] 'process raft request' (duration: 131.715366ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:36:29.665469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.414391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.95\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-17T16:36:29.668364Z","caller":"traceutil/trace.go:171","msg":"trace[814999582] range","detail":"{range_begin:/registry/masterleases/199.204.45.95; range_end:; response_count:0; response_revision:9; }","duration":"142.046083ms","start":"2026-02-17T16:36:29.525017Z","end":"2026-02-17T16:36:29.667063Z","steps":["trace[814999582] 'agreement among raft nodes before linearized reading' (duration: 140.41261ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:36:29.915178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.941198ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-17T16:36:29.915681Z","caller":"traceutil/trace.go:171","msg":"trace[1351767537] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"128.713586ms","start":"2026-02-17T16:36:29.786946Z","end":"2026-02-17T16:36:29.91566Z","steps":["trace[1351767537] 'process raft request' (duration: 128.668196ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:36:29.915987Z","caller":"traceutil/trace.go:171","msg":"trace[1043549877] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"239.367118ms","start":"2026-02-17T16:36:29.676592Z","end":"2026-02-17T16:36:29.915959Z","steps":["trace[1043549877] 'process raft request' (duration: 123.4591ms)","trace[1043549877] 'compare' (duration: 107.827976ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:36:29.91626Z","caller":"traceutil/trace.go:171","msg":"trace[1620210799] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"239.516001ms","start":"2026-02-17T16:36:29.67673Z","end":"2026-02-17T16:36:29.916246Z","steps":["trace[1620210799] 'process raft request' (duration: 238.688235ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:36:29.916463Z","caller":"traceutil/trace.go:171","msg":"trace[1763903608] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"239.638194ms","start":"2026-02-17T16:36:29.676812Z","end":"2026-02-17T16:36:29.91645Z","steps":["trace[1763903608] 'process raft request' (duration: 238.658465ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:36:29.916672Z","caller":"traceutil/trace.go:171","msg":"trace[1609565743] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"239.739796ms","start":"2026-02-17T16:36:29.676917Z","end":"2026-02-17T16:36:29.916657Z","steps":["trace[1609565743] 'process raft request' (duration: 238.586373ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:36:29.916877Z","caller":"traceutil/trace.go:171","msg":"trace[21791267] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"239.761457ms","start":"2026-02-17T16:36:29.677106Z","end":"2026-02-17T16:36:29.916868Z","steps":["trace[21791267] 'process raft request' (duration: 238.41828ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:36:29.917081Z","caller":"traceutil/trace.go:171","msg":"trace[1209308000] transaction","detail":"{read_only:false; number_of_response:0; response_revision:15; }","duration":"239.804728ms","start":"2026-02-17T16:36:29.677265Z","end":"2026-02-17T16:36:29.91707Z","steps":["trace[1209308000] 'process raft request' (duration: 238.281877ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:36:29.918186Z","caller":"traceutil/trace.go:171","msg":"trace[1639191336] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"240.451851ms","start":"2026-02-17T16:36:29.677719Z","end":"2026-02-17T16:36:29.918171Z","steps":["trace[1639191336] 'process raft request' (duration: 237.847689ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:36:29.918486Z","caller":"traceutil/trace.go:171","msg":"trace[1625544523] linearizableReadLoop","detail":"{readStateIndex:16; appliedIndex:12; }","duration":"241.608753ms","start":"2026-02-17T16:36:29.676848Z","end":"2026-02-17T16:36:29.918457Z","steps":["trace[1625544523] 'read index received' (duration: 56.905143ms)","trace[1625544523] 'applied index is now lower than readState.Index' (duration: 184.70263ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:36:29.919306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.600233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-17T16:36:29.919419Z","caller":"traceutil/trace.go:171","msg":"trace[202705990] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17; }","duration":"242.751296ms","start":"2026-02-17T16:36:29.676657Z","end":"2026-02-17T16:36:29.919408Z","steps":["trace[202705990] 'agreement among raft nodes before linearized reading' (duration: 242.318258ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:36:29.919931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.448561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:2858"} {"level":"info","ts":"2026-02-17T16:36:29.920038Z","caller":"traceutil/trace.go:171","msg":"trace[343413781] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:17; }","duration":"243.559823ms","start":"2026-02-17T16:36:29.676464Z","end":"2026-02-17T16:36:29.920024Z","steps":["trace[343413781] 'agreement among raft nodes before linearized reading' (duration: 243.429841ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:36:29.966649Z","caller":"traceutil/trace.go:171","msg":"trace[849899814] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"102.011549ms","start":"2026-02-17T16:36:29.864615Z","end":"2026-02-17T16:36:29.966626Z","steps":["trace[849899814] 'process raft request' (duration: 101.203973ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:36:29.969824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.641742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-17T16:36:29.969972Z","caller":"traceutil/trace.go:171","msg":"trace[631702876] range","detail":"{range_begin:/registry/resourcequotas/kube-public/; range_end:/registry/resourcequotas/kube-public0; response_count:0; response_revision:18; }","duration":"231.843487ms","start":"2026-02-17T16:36:29.738074Z","end":"2026-02-17T16:36:29.969917Z","steps":["trace[631702876] 'agreement among raft nodes before linearized reading' (duration: 231.618062ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:36:29.970436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.423881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-02-17T16:36:29.970498Z","caller":"traceutil/trace.go:171","msg":"trace[768995696] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:18; }","duration":"241.520503ms","start":"2026-02-17T16:36:29.728967Z","end":"2026-02-17T16:36:29.970487Z","steps":["trace[768995696] 'agreement among raft nodes before linearized reading' (duration: 241.420291ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:36:29.970997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.287262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:2858"} {"level":"info","ts":"2026-02-17T16:36:29.971058Z","caller":"traceutil/trace.go:171","msg":"trace[713263968] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:18; }","duration":"292.361753ms","start":"2026-02-17T16:36:29.678685Z","end":"2026-02-17T16:36:29.971047Z","steps":["trace[713263968] 'agreement among raft nodes before linearized reading' (duration: 292.270691ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:36:30.097868Z","caller":"traceutil/trace.go:171","msg":"trace[1188412940] transaction","detail":"{read_only:false; response_revision:28; number_of_response:1; }","duration":"118.752865ms","start":"2026-02-17T16:36:29.979042Z","end":"2026-02-17T16:36:30.097795Z","steps":["trace[1188412940] 'process raft request' (duration: 118.607452ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:36:30.098326Z","caller":"traceutil/trace.go:171","msg":"trace[1218622253] transaction","detail":"{read_only:false; response_revision:25; number_of_response:1; }","duration":"166.815391ms","start":"2026-02-17T16:36:29.931497Z","end":"2026-02-17T16:36:30.098313Z","steps":["trace[1218622253] 'process raft request' (duration: 166.054226ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:36:30.098835Z","caller":"traceutil/trace.go:171","msg":"trace[621535274] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"168.011154ms","start":"2026-02-17T16:36:29.930796Z","end":"2026-02-17T16:36:30.098807Z","steps":["trace[621535274] 'process raft request' (duration: 80.738841ms)","trace[621535274] 'compare' (duration: 85.738833ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:36:30.101161Z","caller":"traceutil/trace.go:171","msg":"trace[1991404424] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"169.854752ms","start":"2026-02-17T16:36:29.931288Z","end":"2026-02-17T16:36:30.101143Z","steps":["trace[1991404424] 'process raft request' (duration: 166.115537ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:36:30.098889Z","caller":"traceutil/trace.go:171","msg":"trace[774299419] linearizableReadLoop","detail":"{readStateIndex:29; appliedIndex:22; }","duration":"132.811249ms","start":"2026-02-17T16:36:29.966062Z","end":"2026-02-17T16:36:30.098873Z","steps":["trace[774299419] 'read index received' (duration: 45.373031ms)","trace[774299419] 'applied index is now lower than readState.Index' (duration: 87.436367ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:36:30.099238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.928904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-02-17T16:36:30.099814Z","caller":"traceutil/trace.go:171","msg":"trace[1428786167] transaction","detail":"{read_only:false; response_revision:26; number_of_response:1; }","duration":"121.773817ms","start":"2026-02-17T16:36:29.978025Z","end":"2026-02-17T16:36:30.099798Z","steps":["trace[1428786167] 'process raft request' (duration: 119.565522ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:36:30.099941Z","caller":"traceutil/trace.go:171","msg":"trace[1639492228] transaction","detail":"{read_only:false; response_revision:27; number_of_response:1; }","duration":"120.92012ms","start":"2026-02-17T16:36:29.979011Z","end":"2026-02-17T16:36:30.099931Z","steps":["trace[1639492228] 'process raft request' (duration: 118.613603ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:36:30.100065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.476985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:2858"} {"level":"info","ts":"2026-02-17T16:36:30.102023Z","caller":"traceutil/trace.go:171","msg":"trace[1549153371] transaction","detail":"{read_only:false; response_revision:22; number_of_response:1; }","duration":"170.656439ms","start":"2026-02-17T16:36:29.931353Z","end":"2026-02-17T16:36:30.102009Z","steps":["trace[1549153371] 'process raft request' (duration: 166.113317ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:36:30.102058Z","caller":"traceutil/trace.go:171","msg":"trace[1679088940] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:28; }","duration":"170.483645ms","start":"2026-02-17T16:36:29.931555Z","end":"2026-02-17T16:36:30.102039Z","steps":["trace[1679088940] 'agreement among raft nodes before linearized reading' (duration: 168.465554ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:36:30.102334Z","caller":"traceutil/trace.go:171","msg":"trace[1678659780] transaction","detail":"{read_only:false; response_revision:23; number_of_response:1; }","duration":"170.921424ms","start":"2026-02-17T16:36:29.9314Z","end":"2026-02-17T16:36:30.102321Z","steps":["trace[1678659780] 'process raft request' (duration: 166.096106ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:36:30.102558Z","caller":"traceutil/trace.go:171","msg":"trace[1095431062] transaction","detail":"{read_only:false; response_revision:24; number_of_response:1; }","duration":"171.119227ms","start":"2026-02-17T16:36:29.931428Z","end":"2026-02-17T16:36:30.102547Z","steps":["trace[1095431062] 'process raft request' (duration: 166.102706ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:36:30.103063Z","caller":"traceutil/trace.go:171","msg":"trace[1613673303] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:28; }","duration":"176.783771ms","start":"2026-02-17T16:36:29.92627Z","end":"2026-02-17T16:36:30.103054Z","steps":["trace[1613673303] 'agreement among raft nodes before linearized reading' (duration: 172.696939ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:36:30.101909Z","caller":"traceutil/trace.go:171","msg":"trace[153924040] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"170.493405ms","start":"2026-02-17T16:36:29.931333Z","end":"2026-02-17T16:36:30.101826Z","steps":["trace[153924040] 'process raft request' (duration: 166.108496ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:36:30.10847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.778938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-75ss8\" ","response":"range_response_count:1 size:861"} {"level":"info","ts":"2026-02-17T16:36:30.108501Z","caller":"traceutil/trace.go:171","msg":"trace[402648581] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-75ss8; range_end:; response_count:1; response_revision:28; }","duration":"133.86147ms","start":"2026-02-17T16:36:29.974632Z","end":"2026-02-17T16:36:30.108494Z","steps":["trace[402648581] 'agreement among raft nodes before linearized reading' (duration: 133.806699ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:36:30.668284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.261169ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-17T16:36:30.66893Z","caller":"traceutil/trace.go:171","msg":"trace[1202537044] transaction","detail":"{read_only:false; response_revision:29; number_of_response:1; }","duration":"559.850847ms","start":"2026-02-17T16:36:30.109045Z","end":"2026-02-17T16:36:30.668896Z","steps":["trace[1202537044] 'process raft request' (duration: 295.917955ms)","trace[1202537044] 'compare' (duration: 263.146607ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:36:30.67111Z","caller":"traceutil/trace.go:171","msg":"trace[713842516] linearizableReadLoop","detail":"{readStateIndex:38; appliedIndex:32; }","duration":"557.124432ms","start":"2026-02-17T16:36:30.113956Z","end":"2026-02-17T16:36:30.671081Z","steps":["trace[713842516] 'read index received' (duration: 291.096788ms)","trace[713842516] 'applied index is now lower than readState.Index' (duration: 266.026974ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:36:30.66901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:36:30.10902Z","time spent":"559.95794ms","remote":"127.0.0.1:42982","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-02-17T16:36:30.67216Z","caller":"traceutil/trace.go:171","msg":"trace[515178349] transaction","detail":"{read_only:false; response_revision:30; number_of_response:1; }","duration":"562.877168ms","start":"2026-02-17T16:36:30.109271Z","end":"2026-02-17T16:36:30.672148Z","steps":["trace[515178349] 'process raft request' (duration: 559.261296ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:36:30.673059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:36:30.109256Z","time spent":"562.931899ms","remote":"127.0.0.1:42906","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-02-17T16:36:30.673873Z","caller":"traceutil/trace.go:171","msg":"trace[1870137886] transaction","detail":"{read_only:false; response_revision:31; number_of_response:1; }","duration":"562.879348ms","start":"2026-02-17T16:36:30.110984Z","end":"2026-02-17T16:36:30.673864Z","steps":["trace[1870137886] 'process raft request' (duration: 557.604982ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:36:30.673922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:36:30.110973Z","time spent":"562.927939ms","remote":"127.0.0.1:42982","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-02-17T16:36:30.674894Z","caller":"traceutil/trace.go:171","msg":"trace[644584985] transaction","detail":"{read_only:false; response_revision:32; number_of_response:1; }","duration":"563.865078ms","start":"2026-02-17T16:36:30.111018Z","end":"2026-02-17T16:36:30.674883Z","steps":["trace[644584985] 'process raft request' (duration: 557.594063ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:36:30.677782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:36:30.110804Z","time spent":"564.116083ms","remote":"127.0.0.1:42982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":959,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-17T16:36:30.678249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.262945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-17T16:36:30.678286Z","caller":"traceutil/trace.go:171","msg":"trace[1452286099] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:0; response_revision:35; }","duration":"564.334357ms","start":"2026-02-17T16:36:30.113942Z","end":"2026-02-17T16:36:30.678276Z","steps":["trace[1452286099] 'agreement among raft nodes before linearized reading' (duration: 564.008671ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:36:30.678346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.88891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-17T16:36:30.678363Z","caller":"traceutil/trace.go:171","msg":"trace[906754097] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:35; }","duration":"250.90536ms","start":"2026-02-17T16:36:30.427451Z","end":"2026-02-17T16:36:30.678357Z","steps":["trace[906754097] 'agreement among raft nodes before linearized reading' (duration: 250.876299ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:36:30.678408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:36:30.113909Z","time spent":"564.394088ms","remote":"127.0.0.1:42570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":0,"response size":28,"request content":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" "} {"level":"info","ts":"2026-02-17T16:36:30.678591Z","caller":"traceutil/trace.go:171","msg":"trace[1694284908] transaction","detail":"{read_only:false; response_revision:33; number_of_response:1; }","duration":"565.645723ms","start":"2026-02-17T16:36:30.112937Z","end":"2026-02-17T16:36:30.678582Z","steps":["trace[1694284908] 'process raft request' (duration: 556.135153ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:36:30.678727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:36:30.11293Z","time spent":"565.676704ms","remote":"127.0.0.1:42982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":983,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-17T16:36:30.678823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.154087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-17T16:36:30.678844Z","caller":"traceutil/trace.go:171","msg":"trace[65373096] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:35; }","duration":"152.203198ms","start":"2026-02-17T16:36:30.526633Z","end":"2026-02-17T16:36:30.678837Z","steps":["trace[65373096] 'agreement among raft nodes before linearized reading' (duration: 152.063885ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:36:30.678912Z","caller":"traceutil/trace.go:171","msg":"trace[581859210] transaction","detail":"{read_only:false; response_revision:34; number_of_response:1; }","duration":"565.106514ms","start":"2026-02-17T16:36:30.113796Z","end":"2026-02-17T16:36:30.678903Z","steps":["trace[581859210] 'process raft request' (duration: 557.206505ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:36:30.678951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:36:30.11379Z","time spent":"565.140794ms","remote":"127.0.0.1:42982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":959,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-17T16:36:30.679147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.561345ms","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-02-17T16:36:30.679161Z","caller":"traceutil/trace.go:171","msg":"trace[1047100517] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:35; }","duration":"251.592395ms","start":"2026-02-17T16:36:30.427564Z","end":"2026-02-17T16:36:30.679156Z","steps":["trace[1047100517] 'agreement among raft nodes before linearized reading' (duration: 251.568734ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:36:30.679242Z","caller":"traceutil/trace.go:171","msg":"trace[409767545] transaction","detail":"{read_only:false; response_revision:35; number_of_response:1; }","duration":"565.253316ms","start":"2026-02-17T16:36:30.113983Z","end":"2026-02-17T16:36:30.679237Z","steps":["trace[409767545] 'process raft request' (duration: 557.060202ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:36:30.679265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:36:30.113978Z","time spent":"565.274627ms","remote":"127.0.0.1:42574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":350,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-17T16:36:30.874162Z","caller":"traceutil/trace.go:171","msg":"trace[204639665] linearizableReadLoop","detail":"{readStateIndex:44; appliedIndex:43; }","duration":"120.656614ms","start":"2026-02-17T16:36:30.753465Z","end":"2026-02-17T16:36:30.874121Z","steps":["trace[204639665] 'read index received' (duration: 80.959567ms)","trace[204639665] 'applied index is now lower than readState.Index' (duration: 39.696247ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:36:30.874337Z","caller":"traceutil/trace.go:171","msg":"trace[720823178] transaction","detail":"{read_only:false; response_revision:40; number_of_response:1; }","duration":"122.203444ms","start":"2026-02-17T16:36:30.752123Z","end":"2026-02-17T16:36:30.874327Z","steps":["trace[720823178] 'process raft request' (duration: 82.363594ms)","trace[720823178] 'compare' (duration: 39.542944ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:36:30.874535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.053122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-cluster-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-17T16:36:30.874557Z","caller":"traceutil/trace.go:171","msg":"trace[1450731491] range","detail":"{range_begin:/registry/priorityclasses/system-cluster-critical; range_end:; response_count:0; response_revision:40; }","duration":"121.089442ms","start":"2026-02-17T16:36:30.75346Z","end":"2026-02-17T16:36:30.87455Z","steps":["trace[1450731491] 'agreement among raft nodes before linearized reading' (duration: 120.99063ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:36:30.878478Z","caller":"traceutil/trace.go:171","msg":"trace[2005864108] transaction","detail":"{read_only:false; response_revision:41; number_of_response:1; }","duration":"124.864859ms","start":"2026-02-17T16:36:30.753604Z","end":"2026-02-17T16:36:30.878469Z","steps":["trace[2005864108] 'process raft request' (duration: 124.803818ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:36:30.878731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.218796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-17T16:36:30.878752Z","caller":"traceutil/trace.go:171","msg":"trace[1961917831] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:41; }","duration":"125.251106ms","start":"2026-02-17T16:36:30.753494Z","end":"2026-02-17T16:36:30.878745Z","steps":["trace[1961917831] 'agreement among raft nodes before linearized reading' (duration: 125.203315ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:36:31.491581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.411647ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-17T16:36:31.49178Z","caller":"traceutil/trace.go:171","msg":"trace[62835924] transaction","detail":"{read_only:false; response_revision:54; number_of_response:1; }","duration":"400.909394ms","start":"2026-02-17T16:36:31.090852Z","end":"2026-02-17T16:36:31.491761Z","steps":["trace[62835924] 'process raft request' (duration: 400.865873ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:36:31.492025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:36:31.090827Z","time spent":"400.993117ms","remote":"127.0.0.1:42896","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-02-17T16:36:31.492322Z","caller":"traceutil/trace.go:171","msg":"trace[236659795] transaction","detail":"{read_only:false; response_revision:52; number_of_response:1; }","duration":"408.619489ms","start":"2026-02-17T16:36:31.083689Z","end":"2026-02-17T16:36:31.492309Z","steps":["trace[236659795] 'process raft request' (duration: 89.433247ms)","trace[236659795] 'compare' (duration: 318.277944ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:36:31.492374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:36:31.083683Z","time spent":"408.669331ms","remote":"127.0.0.1:42802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-17T16:36:31.49271Z","caller":"traceutil/trace.go:171","msg":"trace[1841737076] transaction","detail":"{read_only:false; response_revision:53; number_of_response:1; }","duration":"408.725052ms","start":"2026-02-17T16:36:31.083975Z","end":"2026-02-17T16:36:31.4927Z","steps":["trace[1841737076] 'process raft request' (duration: 407.694201ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:36:31.492756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:36:31.08397Z","time spent":"408.764563ms","remote":"127.0.0.1:42896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":826,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-17T16:36:32.126929Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576313960390830018,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T16:36:32.627416Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576313960390830018,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T16:36:32.879771Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.064304623s","expected-duration":"1s"} {"level":"info","ts":"2026-02-17T16:36:32.912398Z","caller":"traceutil/trace.go:171","msg":"trace[652131567] transaction","detail":"{read_only:false; response_revision:57; number_of_response:1; }","duration":"1.413155783s","start":"2026-02-17T16:36:31.499219Z","end":"2026-02-17T16:36:32.912374Z","steps":["trace[652131567] 'process raft request' (duration: 1.413110382s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:36:32.912545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:36:31.499211Z","time spent":"1.413261955s","remote":"127.0.0.1:42802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":634,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-17T16:36:32.912789Z","caller":"traceutil/trace.go:171","msg":"trace[2121456288] transaction","detail":"{read_only:false; response_revision:56; number_of_response:1; }","duration":"1.415088031s","start":"2026-02-17T16:36:31.497687Z","end":"2026-02-17T16:36:32.912775Z","steps":["trace[2121456288] 'process raft request' (duration: 1.38270707s)","trace[2121456288] 'compare' (duration: 31.83506ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:36:32.912857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:36:31.497678Z","time spent":"1.415140982s","remote":"127.0.0.1:42896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1244,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T16:36:32.942791Z","caller":"traceutil/trace.go:171","msg":"trace[394995940] linearizableReadLoop","detail":"{readStateIndex:62; appliedIndex:59; }","duration":"1.316884618s","start":"2026-02-17T16:36:31.625882Z","end":"2026-02-17T16:36:32.942767Z","steps":["trace[394995940] 'read index received' (duration: 1.254523274s)","trace[394995940] 'applied index is now lower than readState.Index' (duration: 62.359953ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:36:32.943147Z","caller":"traceutil/trace.go:171","msg":"trace[27614072] transaction","detail":"{read_only:false; response_revision:58; number_of_response:1; }","duration":"1.387729532s","start":"2026-02-17T16:36:31.555403Z","end":"2026-02-17T16:36:32.943132Z","steps":["trace[27614072] 'process raft request' (duration: 1.387246032s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:36:32.943279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:36:31.555384Z","time spent":"1.387814503s","remote":"127.0.0.1:42896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":671,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-17T16:36:32.943884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.317998259s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-17T16:36:32.943934Z","caller":"traceutil/trace.go:171","msg":"trace[1825051089] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:58; }","duration":"1.318080561s","start":"2026-02-17T16:36:31.62584Z","end":"2026-02-17T16:36:32.943921Z","steps":["trace[1825051089] 'agreement among raft nodes before linearized reading' (duration: 1.317932778s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:36:32.944308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:36:31.625826Z","time spent":"1.318467049s","remote":"127.0.0.1:42446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-17T16:36:32.954425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.483009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-17T16:36:32.954457Z","caller":"traceutil/trace.go:171","msg":"trace[1939775363] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:59; }","duration":"312.515369ms","start":"2026-02-17T16:36:32.641934Z","end":"2026-02-17T16:36:32.954449Z","steps":["trace[1939775363] 'agreement among raft nodes before linearized reading' (duration: 312.475598ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:36:32.954475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:36:32.641882Z","time spent":"312.589451ms","remote":"127.0.0.1:42446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-17T16:36:33.112233Z","caller":"traceutil/trace.go:171","msg":"trace[830814467] transaction","detail":"{read_only:false; response_revision:63; number_of_response:1; }","duration":"104.083581ms","start":"2026-02-17T16:36:33.008126Z","end":"2026-02-17T16:36:33.11221Z","steps":["trace[830814467] 'compare' (duration: 97.372627ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:36:33.326492Z","caller":"traceutil/trace.go:171","msg":"trace[825810353] transaction","detail":"{read_only:false; response_revision:68; number_of_response:1; }","duration":"106.250224ms","start":"2026-02-17T16:36:33.22005Z","end":"2026-02-17T16:36:33.3263Z","steps":["trace[825810353] 'process raft request' (duration: 105.926987ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:36:33.329427Z","caller":"traceutil/trace.go:171","msg":"trace[1468309426] transaction","detail":"{read_only:false; response_revision:69; number_of_response:1; }","duration":"109.206534ms","start":"2026-02-17T16:36:33.220204Z","end":"2026-02-17T16:36:33.32941Z","steps":["trace[1468309426] 'process raft request' (duration: 108.764055ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:36:33.329439Z","caller":"traceutil/trace.go:171","msg":"trace[2145045843] transaction","detail":"{read_only:false; response_revision:70; number_of_response:1; }","duration":"108.813477ms","start":"2026-02-17T16:36:33.220599Z","end":"2026-02-17T16:36:33.329413Z","steps":["trace[2145045843] 'process raft request' (duration: 108.46665ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:36:33.500435Z","caller":"traceutil/trace.go:171","msg":"trace[462322958] linearizableReadLoop","detail":"{readStateIndex:78; appliedIndex:76; }","duration":"128.258467ms","start":"2026-02-17T16:36:33.372157Z","end":"2026-02-17T16:36:33.500416Z","steps":["trace[462322958] 'read index received' (duration: 116.863398ms)","trace[462322958] 'applied index is now lower than readState.Index' (duration: 11.394409ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:36:33.500645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.466211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-17T16:36:33.500715Z","caller":"traceutil/trace.go:171","msg":"trace[1305296028] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:0; response_revision:74; }","duration":"128.570393ms","start":"2026-02-17T16:36:33.372136Z","end":"2026-02-17T16:36:33.500706Z","steps":["trace[1305296028] 'agreement among raft nodes before linearized reading' (duration: 128.43127ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:36:33.50068Z","caller":"traceutil/trace.go:171","msg":"trace[864974138] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"129.159205ms","start":"2026-02-17T16:36:33.371487Z","end":"2026-02-17T16:36:33.500646Z","steps":["trace[864974138] 'process raft request' (duration: 117.600853ms)","trace[864974138] 'compare' (duration: 11.134144ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:36:33.501061Z","caller":"traceutil/trace.go:171","msg":"trace[1462070118] transaction","detail":"{read_only:false; response_revision:74; number_of_response:1; }","duration":"129.043053ms","start":"2026-02-17T16:36:33.372008Z","end":"2026-02-17T16:36:33.501051Z","steps":["trace[1462070118] 'process raft request' (duration: 128.36243ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:37:06.40901Z","caller":"traceutil/trace.go:171","msg":"trace[1028788223] linearizableReadLoop","detail":"{readStateIndex:545; appliedIndex:544; }","duration":"117.810366ms","start":"2026-02-17T16:37:06.291177Z","end":"2026-02-17T16:37:06.408988Z","steps":["trace[1028788223] 'read index received' (duration: 117.637223ms)","trace[1028788223] 'applied index is now lower than readState.Index' (duration: 172.263µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:37:06.409295Z","caller":"traceutil/trace.go:171","msg":"trace[315394826] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"283.500625ms","start":"2026-02-17T16:37:06.125781Z","end":"2026-02-17T16:37:06.409282Z","steps":["trace[315394826] 'process raft request' (duration: 283.092677ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:37:06.409479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.284267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:37:06.40952Z","caller":"traceutil/trace.go:171","msg":"trace[1985105754] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:527; }","duration":"118.360298ms","start":"2026-02-17T16:37:06.291146Z","end":"2026-02-17T16:37:06.409506Z","steps":["trace[1985105754] 'agreement among raft nodes before linearized reading' (duration: 118.281066ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:37:08.222583Z","caller":"traceutil/trace.go:171","msg":"trace[1928744773] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"359.442221ms","start":"2026-02-17T16:37:07.863116Z","end":"2026-02-17T16:37:08.222559Z","steps":["trace[1928744773] 'process raft request' (duration: 359.301399ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:37:08.222742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:37:07.863104Z","time spent":"359.566745ms","remote":"127.0.0.1:42720","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-02-17T16:37:08.228799Z","caller":"traceutil/trace.go:171","msg":"trace[1308973450] linearizableReadLoop","detail":"{readStateIndex:552; appliedIndex:551; }","duration":"357.304868ms","start":"2026-02-17T16:37:07.871478Z","end":"2026-02-17T16:37:08.228783Z","steps":["trace[1308973450] 'read index received' (duration: 352.455771ms)","trace[1308973450] 'applied index is now lower than readState.Index' (duration: 4.848477ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:37:08.228968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.477492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-2k9gp\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-02-17T16:37:08.229005Z","caller":"traceutil/trace.go:171","msg":"trace[1988833052] range","detail":"{range_begin:/registry/pods/kube-system/cilium-2k9gp; range_end:; response_count:1; response_revision:534; }","duration":"357.538694ms","start":"2026-02-17T16:37:07.871456Z","end":"2026-02-17T16:37:08.228994Z","steps":["trace[1988833052] 'agreement among raft nodes before linearized reading' (duration: 357.390271ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:37:08.229061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:37:07.871449Z","time spent":"357.604735ms","remote":"127.0.0.1:42636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16676,"request content":"key:\"/registry/pods/kube-system/cilium-2k9gp\" "} {"level":"info","ts":"2026-02-17T16:37:08.229426Z","caller":"traceutil/trace.go:171","msg":"trace[701842230] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"364.726088ms","start":"2026-02-17T16:37:07.864687Z","end":"2026-02-17T16:37:08.229413Z","steps":["trace[701842230] 'process raft request' (duration: 364.012774ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:37:08.229497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:37:07.864668Z","time spent":"364.792079ms","remote":"127.0.0.1:42720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T16:37:19.197972Z","caller":"traceutil/trace.go:171","msg":"trace[1342291029] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"372.395311ms","start":"2026-02-17T16:37:18.825336Z","end":"2026-02-17T16:37:19.197732Z","steps":["trace[1342291029] 'process raft request' (duration: 372.207838ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:37:19.198342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:37:18.825292Z","time spent":"372.970083ms","remote":"127.0.0.1:51656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2262,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-17T16:37:26.620121Z","caller":"traceutil/trace.go:171","msg":"trace[1356187286] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"227.17907ms","start":"2026-02-17T16:37:26.392923Z","end":"2026-02-17T16:37:26.620102Z","steps":["trace[1356187286] 'process raft request' (duration: 227.087358ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:37:27.039668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.220846ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T16:37:27.039806Z","caller":"traceutil/trace.go:171","msg":"trace[2145394020] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"229.071678ms","start":"2026-02-17T16:37:26.81072Z","end":"2026-02-17T16:37:27.039791Z","steps":["trace[2145394020] 'process raft request' (duration: 229.026887ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:37:27.040093Z","caller":"traceutil/trace.go:171","msg":"trace[983410738] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"645.78332ms","start":"2026-02-17T16:37:26.394297Z","end":"2026-02-17T16:37:27.040081Z","steps":["trace[983410738] 'process raft request' (duration: 387.067754ms)","trace[983410738] 'compare' (duration: 258.074653ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:37:27.040149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:37:26.394289Z","time spent":"645.828411ms","remote":"127.0.0.1:42720","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-02-17T16:37:27.040279Z","caller":"traceutil/trace.go:171","msg":"trace[1270872781] linearizableReadLoop","detail":"{readStateIndex:743; appliedIndex:742; }","duration":"628.678595ms","start":"2026-02-17T16:37:26.411591Z","end":"2026-02-17T16:37:27.04027Z","steps":["trace[1270872781] 'read index received' (duration: 208.870941ms)","trace[1270872781] 'applied index is now lower than readState.Index' (duration: 419.806644ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:37:27.040484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.890219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-17T16:37:27.04052Z","caller":"traceutil/trace.go:171","msg":"trace[1475300331] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:720; }","duration":"628.93872ms","start":"2026-02-17T16:37:26.411567Z","end":"2026-02-17T16:37:27.040506Z","steps":["trace[1475300331] 'agreement among raft nodes before linearized reading' (duration: 628.832698ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:37:27.040582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:37:26.411558Z","time spent":"629.008852ms","remote":"127.0.0.1:42720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-17T16:37:27.212582Z","caller":"traceutil/trace.go:171","msg":"trace[2102514082] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"167.865644ms","start":"2026-02-17T16:37:27.04469Z","end":"2026-02-17T16:37:27.212556Z","steps":["trace[2102514082] 'process raft request' (duration: 167.810003ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:37:27.213002Z","caller":"traceutil/trace.go:171","msg":"trace[513795661] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"357.507947ms","start":"2026-02-17T16:37:26.855481Z","end":"2026-02-17T16:37:27.212989Z","steps":["trace[513795661] 'process raft request' (duration: 302.901756ms)","trace[513795661] 'compare' (duration: 53.994159ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:37:27.213148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:37:26.855469Z","time spent":"357.603039ms","remote":"127.0.0.1:42720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T16:37:27.213277Z","caller":"traceutil/trace.go:171","msg":"trace[1425608819] linearizableReadLoop","detail":"{readStateIndex:745; appliedIndex:744; }","duration":"172.966267ms","start":"2026-02-17T16:37:27.040299Z","end":"2026-02-17T16:37:27.213265Z","steps":["trace[1425608819] 'read index received' (duration: 118.093701ms)","trace[1425608819] 'applied index is now lower than readState.Index' (duration: 54.871666ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:37:27.213384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.725136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-17T16:37:27.213412Z","caller":"traceutil/trace.go:171","msg":"trace[1550267550] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:722; }","duration":"629.788477ms","start":"2026-02-17T16:37:26.583614Z","end":"2026-02-17T16:37:27.213402Z","steps":["trace[1550267550] 'agreement among raft nodes before linearized reading' (duration: 629.685625ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:37:27.213439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:37:26.583596Z","time spent":"629.835418ms","remote":"127.0.0.1:42720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-17T16:37:27.213679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.836917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-17T16:37:27.213707Z","caller":"traceutil/trace.go:171","msg":"trace[1003264741] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:722; }","duration":"617.876557ms","start":"2026-02-17T16:37:26.595822Z","end":"2026-02-17T16:37:27.213698Z","steps":["trace[1003264741] 'agreement among raft nodes before linearized reading' (duration: 617.799545ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:37:27.213732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:37:26.595814Z","time spent":"617.911898ms","remote":"127.0.0.1:42720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-17T16:37:28.741854Z","caller":"traceutil/trace.go:171","msg":"trace[544439952] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"113.852665ms","start":"2026-02-17T16:37:28.627978Z","end":"2026-02-17T16:37:28.741831Z","steps":["trace[544439952] 'process raft request' (duration: 113.736943ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:37:35.353991Z","caller":"traceutil/trace.go:171","msg":"trace[1941471977] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"102.66897ms","start":"2026-02-17T16:37:35.251298Z","end":"2026-02-17T16:37:35.353967Z","steps":["trace[1941471977] 'process raft request' (duration: 102.526356ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:37:35.472133Z","caller":"traceutil/trace.go:171","msg":"trace[604423859] linearizableReadLoop","detail":"{readStateIndex:777; appliedIndex:776; }","duration":"114.097871ms","start":"2026-02-17T16:37:35.358015Z","end":"2026-02-17T16:37:35.472113Z","steps":["trace[604423859] 'read index received' (duration: 66.119864ms)","trace[604423859] 'applied index is now lower than readState.Index' (duration: 47.977437ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:37:35.472231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.197183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:37:35.472252Z","caller":"traceutil/trace.go:171","msg":"trace[377782777] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:751; }","duration":"114.253384ms","start":"2026-02-17T16:37:35.357992Z","end":"2026-02-17T16:37:35.472246Z","steps":["trace[377782777] 'agreement among raft nodes before linearized reading' (duration: 114.186312ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:37:35.472356Z","caller":"traceutil/trace.go:171","msg":"trace[1276172461] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"114.60841ms","start":"2026-02-17T16:37:35.357735Z","end":"2026-02-17T16:37:35.472343Z","steps":["trace[1276172461] 'process raft request' (duration: 66.397438ms)","trace[1276172461] 'compare' (duration: 47.905356ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:37:37.136631Z","caller":"traceutil/trace.go:171","msg":"trace[1612935407] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"120.158712ms","start":"2026-02-17T16:37:37.016454Z","end":"2026-02-17T16:37:37.136613Z","steps":["trace[1612935407] 'process raft request' (duration: 119.751734ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:37:49.469594Z","caller":"traceutil/trace.go:171","msg":"trace[566600954] linearizableReadLoop","detail":"{readStateIndex:816; appliedIndex:815; }","duration":"177.245493ms","start":"2026-02-17T16:37:49.292332Z","end":"2026-02-17T16:37:49.469578Z","steps":["trace[566600954] 'read index received' (duration: 177.166951ms)","trace[566600954] 'applied index is now lower than readState.Index' (duration: 77.812µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:37:49.469796Z","caller":"traceutil/trace.go:171","msg":"trace[1037841844] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"286.784511ms","start":"2026-02-17T16:37:49.183001Z","end":"2026-02-17T16:37:49.469786Z","steps":["trace[1037841844] 'process raft request' (duration: 286.422654ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:37:49.470044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.689863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:37:49.470218Z","caller":"traceutil/trace.go:171","msg":"trace[615674702] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:788; }","duration":"177.936707ms","start":"2026-02-17T16:37:49.29227Z","end":"2026-02-17T16:37:49.470207Z","steps":["trace[615674702] 'agreement among raft nodes before linearized reading' (duration: 177.729373ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:37:49.49348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.733529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-17T16:37:49.493689Z","caller":"traceutil/trace.go:171","msg":"trace[1977216544] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:788; }","duration":"147.979033ms","start":"2026-02-17T16:37:49.345694Z","end":"2026-02-17T16:37:49.493673Z","steps":["trace[1977216544] 'agreement among raft nodes before linearized reading' (duration: 147.726088ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:37:49.494065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.732984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-17T16:37:49.494172Z","caller":"traceutil/trace.go:171","msg":"trace[870805018] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:788; }","duration":"182.847916ms","start":"2026-02-17T16:37:49.31128Z","end":"2026-02-17T16:37:49.494128Z","steps":["trace[870805018] 'agreement among raft nodes before linearized reading' (duration: 182.677182ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:37:49.64493Z","caller":"traceutil/trace.go:171","msg":"trace[1046020081] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"143.237678ms","start":"2026-02-17T16:37:49.501674Z","end":"2026-02-17T16:37:49.644912Z","steps":["trace[1046020081] 'process raft request' (duration: 143.134716ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:37:49.645413Z","caller":"traceutil/trace.go:171","msg":"trace[593317530] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"143.404021ms","start":"2026-02-17T16:37:49.502003Z","end":"2026-02-17T16:37:49.645407Z","steps":["trace[593317530] 'process raft request' (duration: 143.37493ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:37:49.645727Z","caller":"traceutil/trace.go:171","msg":"trace[932054181] linearizableReadLoop","detail":"{readStateIndex:819; appliedIndex:819; }","duration":"106.428066ms","start":"2026-02-17T16:37:49.539291Z","end":"2026-02-17T16:37:49.645719Z","steps":["trace[932054181] 'read index received' (duration: 106.425806ms)","trace[932054181] 'applied index is now lower than readState.Index' (duration: 1.77µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:37:49.646083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.780313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-17T16:37:49.646107Z","caller":"traceutil/trace.go:171","msg":"trace[185881818] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:790; }","duration":"106.851053ms","start":"2026-02-17T16:37:49.539251Z","end":"2026-02-17T16:37:49.646102Z","steps":["trace[185881818] 'agreement among raft nodes before linearized reading' (duration: 106.497376ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:37:50.021104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.533709ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:37:50.021225Z","caller":"traceutil/trace.go:171","msg":"trace[222363179] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:790; }","duration":"301.684082ms","start":"2026-02-17T16:37:49.719524Z","end":"2026-02-17T16:37:50.021208Z","steps":["trace[222363179] 'range keys from in-memory index tree' (duration: 301.515629ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:37:50.021443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.928608ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T16:37:50.021527Z","caller":"traceutil/trace.go:171","msg":"trace[1703048202] linearizableReadLoop","detail":"{readStateIndex:820; appliedIndex:819; }","duration":"141.694976ms","start":"2026-02-17T16:37:49.879816Z","end":"2026-02-17T16:37:50.02151Z","steps":["trace[1703048202] 'read index received' (duration: 36.5µs)","trace[1703048202] 'applied index is now lower than readState.Index' (duration: 141.656856ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:37:50.021919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.090696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-17T16:37:50.021964Z","caller":"traceutil/trace.go:171","msg":"trace[1697659041] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:791; }","duration":"142.175457ms","start":"2026-02-17T16:37:49.879777Z","end":"2026-02-17T16:37:50.021952Z","steps":["trace[1697659041] 'agreement among raft nodes before linearized reading' (duration: 142.034793ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:37:50.02278Z","caller":"traceutil/trace.go:171","msg":"trace[617832364] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"373.0082ms","start":"2026-02-17T16:37:49.649753Z","end":"2026-02-17T16:37:50.022761Z","steps":["trace[617832364] 'process raft request' (duration: 133.612893ms)","trace[617832364] 'compare' (duration: 237.619131ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:37:50.023091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:37:49.64974Z","time spent":"373.079062ms","remote":"127.0.0.1:42720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T16:38:01.28932Z","caller":"traceutil/trace.go:171","msg":"trace[679247889] transaction","detail":"{read_only:false; response_revision:871; number_of_response:1; }","duration":"288.888664ms","start":"2026-02-17T16:38:01.000415Z","end":"2026-02-17T16:38:01.289303Z","steps":["trace[679247889] 'process raft request' (duration: 288.784732ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:38:03.013302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.002561712s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T16:38:03.013412Z","caller":"traceutil/trace.go:171","msg":"trace[1567433738] linearizableReadLoop","detail":"{readStateIndex:908; appliedIndex:906; }","duration":"880.124763ms","start":"2026-02-17T16:38:02.133276Z","end":"2026-02-17T16:38:03.013401Z","steps":["trace[1567433738] 'read index received' (duration: 126.951529ms)","trace[1567433738] 'applied index is now lower than readState.Index' (duration: 753.172134ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:38:03.01356Z","caller":"traceutil/trace.go:171","msg":"trace[1130908420] transaction","detail":"{read_only:false; response_revision:874; number_of_response:1; }","duration":"1.301547719s","start":"2026-02-17T16:38:01.712003Z","end":"2026-02-17T16:38:03.013551Z","steps":["trace[1130908420] 'process raft request' (duration: 298.678411ms)","trace[1130908420] 'compare' (duration: 1.002351388s)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:38:03.013608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:38:01.711997Z","time spent":"1.30159143s","remote":"127.0.0.1:42720","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-02-17T16:38:03.013712Z","caller":"traceutil/trace.go:171","msg":"trace[1607377046] transaction","detail":"{read_only:false; response_revision:875; number_of_response:1; }","duration":"1.178610751s","start":"2026-02-17T16:38:01.835096Z","end":"2026-02-17T16:38:03.013707Z","steps":["trace[1607377046] 'process raft request' (duration: 1.178271794s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:38:03.01374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:38:01.83508Z","time spent":"1.178644311s","remote":"127.0.0.1:42720","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-02-17T16:38:03.013877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"880.599502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-17T16:38:03.013893Z","caller":"traceutil/trace.go:171","msg":"trace[184278912] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:875; }","duration":"880.637874ms","start":"2026-02-17T16:38:02.13325Z","end":"2026-02-17T16:38:03.013888Z","steps":["trace[184278912] 'agreement among raft nodes before linearized reading' (duration: 880.554362ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:38:03.013908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:38:02.133238Z","time spent":"880.666614ms","remote":"127.0.0.1:42720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-17T16:38:03.014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"821.859378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-17T16:38:03.014016Z","caller":"traceutil/trace.go:171","msg":"trace[1420070786] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:875; }","duration":"821.908329ms","start":"2026-02-17T16:38:02.192101Z","end":"2026-02-17T16:38:03.014009Z","steps":["trace[1420070786] 'agreement among raft nodes before linearized reading' (duration: 821.877368ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:38:03.014032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:38:02.192086Z","time spent":"821.941239ms","remote":"127.0.0.1:42906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":31,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-17T16:38:03.014612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.152971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-17T16:38:03.014635Z","caller":"traceutil/trace.go:171","msg":"trace[824800156] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:875; }","duration":"548.216732ms","start":"2026-02-17T16:38:02.466412Z","end":"2026-02-17T16:38:03.014628Z","steps":["trace[824800156] 'agreement among raft nodes before linearized reading' (duration: 548.174721ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:38:03.014651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:38:02.466392Z","time spent":"548.254583ms","remote":"127.0.0.1:42574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":4,"response size":31,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-02-17T16:38:03.014744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"726.13314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:38:03.014759Z","caller":"traceutil/trace.go:171","msg":"trace[161706842] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:875; }","duration":"726.17464ms","start":"2026-02-17T16:38:02.288579Z","end":"2026-02-17T16:38:03.014754Z","steps":["trace[161706842] 'agreement among raft nodes before linearized reading' (duration: 726.151409ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:38:03.014774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:38:02.288564Z","time spent":"726.205541ms","remote":"127.0.0.1:42446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-17T16:38:09.453677Z","caller":"traceutil/trace.go:171","msg":"trace[412381961] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"114.524419ms","start":"2026-02-17T16:38:09.339138Z","end":"2026-02-17T16:38:09.453662Z","steps":["trace[412381961] 'process raft request' (duration: 114.353865ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:38:11.161037Z","caller":"traceutil/trace.go:171","msg":"trace[922966231] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"115.744894ms","start":"2026-02-17T16:38:11.045276Z","end":"2026-02-17T16:38:11.161021Z","steps":["trace[922966231] 'process raft request' (duration: 115.471798ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:38:11.339623Z","caller":"traceutil/trace.go:171","msg":"trace[1831362887] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"174.099179ms","start":"2026-02-17T16:38:11.165507Z","end":"2026-02-17T16:38:11.339606Z","steps":["trace[1831362887] 'process raft request' (duration: 170.111009ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:38:14.486446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.834499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:38:14.486496Z","caller":"traceutil/trace.go:171","msg":"trace[625274363] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:908; }","duration":"198.91413ms","start":"2026-02-17T16:38:14.28757Z","end":"2026-02-17T16:38:14.486484Z","steps":["trace[625274363] 'range keys from in-memory index tree' (duration: 198.749937ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:38:33.421639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.878891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-17T16:38:33.421691Z","caller":"traceutil/trace.go:171","msg":"trace[1751725736] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1001; }","duration":"143.962845ms","start":"2026-02-17T16:38:33.277716Z","end":"2026-02-17T16:38:33.421679Z","steps":["trace[1751725736] 'range keys from in-memory index tree' (duration: 143.768029ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:38:33.421857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.483913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:38:33.421876Z","caller":"traceutil/trace.go:171","msg":"trace[1258228361] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1001; }","duration":"131.523423ms","start":"2026-02-17T16:38:33.290348Z","end":"2026-02-17T16:38:33.421871Z","steps":["trace[1258228361] 'range keys from in-memory index tree' (duration: 131.444142ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:38:33.76352Z","caller":"traceutil/trace.go:171","msg":"trace[773114250] linearizableReadLoop","detail":"{readStateIndex:1041; appliedIndex:1040; }","duration":"291.153322ms","start":"2026-02-17T16:38:33.472345Z","end":"2026-02-17T16:38:33.763498Z","steps":["trace[773114250] 'read index received' (duration: 291.03931ms)","trace[773114250] 'applied index is now lower than readState.Index' (duration: 112.722µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:38:33.763652Z","caller":"traceutil/trace.go:171","msg":"trace[356002463] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"338.124987ms","start":"2026-02-17T16:38:33.425513Z","end":"2026-02-17T16:38:33.763638Z","steps":["trace[356002463] 'process raft request' (duration: 337.859822ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:38:33.763769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:38:33.425502Z","time spent":"338.179278ms","remote":"127.0.0.1:42720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T16:38:33.76389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.506006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-17T16:38:33.763972Z","caller":"traceutil/trace.go:171","msg":"trace[988152679] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1002; }","duration":"260.621839ms","start":"2026-02-17T16:38:33.503327Z","end":"2026-02-17T16:38:33.763948Z","steps":["trace[988152679] 'agreement among raft nodes before linearized reading' (duration: 260.485136ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:38:33.764312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.637494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-17T16:38:33.764344Z","caller":"traceutil/trace.go:171","msg":"trace[1787128275] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1002; }","duration":"206.705345ms","start":"2026-02-17T16:38:33.557628Z","end":"2026-02-17T16:38:33.764334Z","steps":["trace[1787128275] 'agreement among raft nodes before linearized reading' (duration: 206.575113ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:38:33.76443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.086901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-17T16:38:33.764473Z","caller":"traceutil/trace.go:171","msg":"trace[1700169453] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1002; }","duration":"292.151782ms","start":"2026-02-17T16:38:33.472307Z","end":"2026-02-17T16:38:33.764459Z","steps":["trace[1700169453] 'agreement among raft nodes before linearized reading' (duration: 292.06031ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:38:34.026345Z","caller":"traceutil/trace.go:171","msg":"trace[901437034] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"254.447705ms","start":"2026-02-17T16:38:33.771491Z","end":"2026-02-17T16:38:34.025938Z","steps":["trace[901437034] 'process raft request' (duration: 155.866262ms)","trace[901437034] 'compare' (duration: 98.406219ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:38:34.070164Z","caller":"traceutil/trace.go:171","msg":"trace[882571636] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"297.279466ms","start":"2026-02-17T16:38:33.772699Z","end":"2026-02-17T16:38:34.069978Z","steps":["trace[882571636] 'process raft request' (duration: 296.114443ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:38:34.070394Z","caller":"traceutil/trace.go:171","msg":"trace[92779101] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"296.825417ms","start":"2026-02-17T16:38:33.773557Z","end":"2026-02-17T16:38:34.070382Z","steps":["trace[92779101] 'process raft request' (duration: 295.377228ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:38:34.07067Z","caller":"traceutil/trace.go:171","msg":"trace[1080457830] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"202.413269ms","start":"2026-02-17T16:38:33.868244Z","end":"2026-02-17T16:38:34.070657Z","steps":["trace[1080457830] 'process raft request' (duration: 200.721175ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:38:34.39432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.67354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.95\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-17T16:38:34.394412Z","caller":"traceutil/trace.go:171","msg":"trace[2081399589] range","detail":"{range_begin:/registry/masterleases/199.204.45.95; range_end:; response_count:1; response_revision:1007; }","duration":"259.780791ms","start":"2026-02-17T16:38:34.134592Z","end":"2026-02-17T16:38:34.394373Z","steps":["trace[2081399589] 'range keys from in-memory index tree' (duration: 259.418665ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:39:37.894317Z","caller":"traceutil/trace.go:171","msg":"trace[1381116538] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"117.829834ms","start":"2026-02-17T16:39:37.776469Z","end":"2026-02-17T16:39:37.894299Z","steps":["trace[1381116538] 'process raft request' (duration: 117.732652ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:39:50.774738Z","caller":"traceutil/trace.go:171","msg":"trace[2068737619] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"157.898097ms","start":"2026-02-17T16:39:50.616807Z","end":"2026-02-17T16:39:50.774705Z","steps":["trace[2068737619] 'process raft request' (duration: 74.471254ms)","trace[2068737619] 'compare' (duration: 83.32287ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:39:50.797238Z","caller":"traceutil/trace.go:171","msg":"trace[1724397530] transaction","detail":"{read_only:false; response_revision:1336; number_of_response:1; }","duration":"180.141394ms","start":"2026-02-17T16:39:50.617085Z","end":"2026-02-17T16:39:50.797226Z","steps":["trace[1724397530] 'process raft request' (duration: 179.96858ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:39:50.987391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.605868ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T16:39:50.987819Z","caller":"traceutil/trace.go:171","msg":"trace[1741042167] transaction","detail":"{read_only:false; response_revision:1337; number_of_response:1; }","duration":"186.293068ms","start":"2026-02-17T16:39:50.801504Z","end":"2026-02-17T16:39:50.987797Z","steps":["trace[1741042167] 'process raft request' (duration: 75.175419ms)","trace[1741042167] 'compare' (duration: 110.366283ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:39:50.988195Z","caller":"traceutil/trace.go:171","msg":"trace[141935783] linearizableReadLoop","detail":"{readStateIndex:1392; appliedIndex:1391; }","duration":"185.954161ms","start":"2026-02-17T16:39:50.802228Z","end":"2026-02-17T16:39:50.988182Z","steps":["trace[141935783] 'read index received' (duration: 74.517425ms)","trace[141935783] 'applied index is now lower than readState.Index' (duration: 111.435465ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:39:50.988474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.237116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-17T16:39:50.989031Z","caller":"traceutil/trace.go:171","msg":"trace[2114841001] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1337; }","duration":"186.810458ms","start":"2026-02-17T16:39:50.802207Z","end":"2026-02-17T16:39:50.989018Z","steps":["trace[2114841001] 'agreement among raft nodes before linearized reading' (duration: 186.165425ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:41:37.77341Z","caller":"traceutil/trace.go:171","msg":"trace[756935079] transaction","detail":"{read_only:false; response_revision:1800; number_of_response:1; }","duration":"151.60203ms","start":"2026-02-17T16:41:37.621787Z","end":"2026-02-17T16:41:37.773389Z","steps":["trace[756935079] 'process raft request' (duration: 115.113168ms)","trace[756935079] 'compare' (duration: 35.678455ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:41:57.714197Z","caller":"traceutil/trace.go:171","msg":"trace[1965295201] linearizableReadLoop","detail":"{readStateIndex:1971; appliedIndex:1970; }","duration":"108.927077ms","start":"2026-02-17T16:41:57.605244Z","end":"2026-02-17T16:41:57.714171Z","steps":["trace[1965295201] 'read index received' (duration: 108.717003ms)","trace[1965295201] 'applied index is now lower than readState.Index' (duration: 208.894µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:41:57.714318Z","caller":"traceutil/trace.go:171","msg":"trace[780783286] transaction","detail":"{read_only:false; response_revision:1890; number_of_response:1; }","duration":"124.377477ms","start":"2026-02-17T16:41:57.58993Z","end":"2026-02-17T16:41:57.714308Z","steps":["trace[780783286] 'process raft request' (duration: 124.03985ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:41:57.714711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.449527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:pod-garbage-collector\" ","response":"range_response_count:1 size:759"} {"level":"info","ts":"2026-02-17T16:41:57.714751Z","caller":"traceutil/trace.go:171","msg":"trace[937891523] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:pod-garbage-collector; range_end:; response_count:1; response_revision:1890; }","duration":"109.520678ms","start":"2026-02-17T16:41:57.605217Z","end":"2026-02-17T16:41:57.714737Z","steps":["trace[937891523] 'agreement among raft nodes before linearized reading' (duration: 109.423256ms)"],"step_count":1}