{"level":"warn","ts":"2026-01-31T18:49:29.624329Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-31T18:49:29.624547Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.70:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.70:2380","--initial-cluster=instance=https://162.253.55.70:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.70:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.70:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-01-31T18:49:29.624715Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-31T18:49:29.62483Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.70:2380"]} {"level":"info","ts":"2026-01-31T18:49:29.624856Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-01-31T18:49:29.628159Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.70:2379"]} {"level":"info","ts":"2026-01-31T18:49:29.629501Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.70:2380"],"listen-peer-urls":["https://162.253.55.70:2380"],"advertise-client-urls":["https://162.253.55.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.70:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-01-31T18:49:29.636917Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.316487ms"} {"level":"info","ts":"2026-01-31T18:49:29.687873Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"23887246632736aa","cluster-id":"62cfeca3ef46d3ef"} {"level":"info","ts":"2026-01-31T18:49:29.687945Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa switched to configuration voters=()"} {"level":"info","ts":"2026-01-31T18:49:29.687965Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became follower at term 0"} {"level":"info","ts":"2026-01-31T18:49:29.687979Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 23887246632736aa [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-01-31T18:49:29.687989Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became follower at term 1"} {"level":"info","ts":"2026-01-31T18:49:29.688086Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa switched to configuration voters=(2560422034796918442)"} {"level":"warn","ts":"2026-01-31T18:49:29.699308Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-01-31T18:49:29.701656Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-01-31T18:49:29.715547Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-01-31T18:49:29.724457Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"23887246632736aa","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-01-31T18:49:29.728146Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"23887246632736aa","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-01-31T18:49:29.728276Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-01-31T18:49:29.72832Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-01-31T18:49:29.72833Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-01-31T18:49:29.729126Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa switched to configuration voters=(2560422034796918442)"} {"level":"info","ts":"2026-01-31T18:49:29.729293Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"62cfeca3ef46d3ef","local-member-id":"23887246632736aa","added-peer-id":"23887246632736aa","added-peer-peer-urls":["https://162.253.55.70:2380"]} {"level":"info","ts":"2026-01-31T18:49:29.761723Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-01-31T18:49:29.76194Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"23887246632736aa","initial-advertise-peer-urls":["https://162.253.55.70:2380"],"listen-peer-urls":["https://162.253.55.70:2380"],"advertise-client-urls":["https://162.253.55.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-01-31T18:49:29.761965Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-01-31T18:49:29.764262Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.70:2380"} {"level":"info","ts":"2026-01-31T18:49:29.764314Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.70:2380"} {"level":"info","ts":"2026-01-31T18:49:30.589289Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa is starting a new election at term 1"} {"level":"info","ts":"2026-01-31T18:49:30.589339Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became pre-candidate at term 1"} {"level":"info","ts":"2026-01-31T18:49:30.589356Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa received MsgPreVoteResp from 23887246632736aa at term 1"} {"level":"info","ts":"2026-01-31T18:49:30.589372Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became candidate at term 2"} {"level":"info","ts":"2026-01-31T18:49:30.589378Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa received MsgVoteResp from 23887246632736aa at term 2"} {"level":"info","ts":"2026-01-31T18:49:30.589389Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became leader at term 2"} {"level":"info","ts":"2026-01-31T18:49:30.589396Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 23887246632736aa elected leader 23887246632736aa at term 2"} {"level":"info","ts":"2026-01-31T18:49:30.596961Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-01-31T18:49:30.599417Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"62cfeca3ef46d3ef","local-member-id":"23887246632736aa","cluster-version":"3.5"} {"level":"info","ts":"2026-01-31T18:49:30.59947Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-01-31T18:49:30.599485Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-01-31T18:49:30.599505Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"23887246632736aa","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.70:2379]}","request-path":"/0/members/23887246632736aa/attributes","cluster-id":"62cfeca3ef46d3ef","publish-timeout":"7s"} {"level":"info","ts":"2026-01-31T18:49:30.603538Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-31T18:49:30.603912Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-31T18:49:30.605403Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-01-31T18:49:30.606573Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.70:2379"} {"level":"info","ts":"2026-01-31T18:49:30.606654Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-01-31T18:49:30.60667Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-01-31T18:49:32.312496Z","caller":"traceutil/trace.go:171","msg":"trace[1981009498] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"203.881595ms","start":"2026-01-31T18:49:32.108573Z","end":"2026-01-31T18:49:32.312454Z","steps":["trace[1981009498] 'process raft request' (duration: 203.565018ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:49:32.321167Z","caller":"traceutil/trace.go:171","msg":"trace[847475232] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:5; }","duration":"200.805298ms","start":"2026-01-31T18:49:32.120351Z","end":"2026-01-31T18:49:32.321156Z","steps":["trace[847475232] 'read index received' (duration: 192.799734ms)","trace[847475232] 'applied index is now lower than readState.Index' (duration: 8.005094ms)"],"step_count":2} {"level":"info","ts":"2026-01-31T18:49:32.321373Z","caller":"traceutil/trace.go:171","msg":"trace[37908254] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"212.571954ms","start":"2026-01-31T18:49:32.108794Z","end":"2026-01-31T18:49:32.321365Z","steps":["trace[37908254] 'process raft request' (duration: 212.093623ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:49:32.32226Z","caller":"traceutil/trace.go:171","msg":"trace[1854874657] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"195.064133ms","start":"2026-01-31T18:49:32.127182Z","end":"2026-01-31T18:49:32.322247Z","steps":["trace[1854874657] 'process raft request' (duration: 193.773485ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:49:32.322512Z","caller":"traceutil/trace.go:171","msg":"trace[1682141717] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"179.30405ms","start":"2026-01-31T18:49:32.1432Z","end":"2026-01-31T18:49:32.322504Z","steps":["trace[1682141717] 'process raft request' (duration: 177.783106ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:49:32.322688Z","caller":"traceutil/trace.go:171","msg":"trace[856077934] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"158.704902ms","start":"2026-01-31T18:49:32.163977Z","end":"2026-01-31T18:49:32.322682Z","steps":["trace[856077934] 'process raft request' (duration: 157.050485ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:49:32.32281Z","caller":"traceutil/trace.go:171","msg":"trace[1954955587] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"135.652171ms","start":"2026-01-31T18:49:32.187151Z","end":"2026-01-31T18:49:32.322803Z","steps":["trace[1954955587] 'process raft request' (duration: 133.895423ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:49:32.322892Z","caller":"traceutil/trace.go:171","msg":"trace[2138211458] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"133.621116ms","start":"2026-01-31T18:49:32.189266Z","end":"2026-01-31T18:49:32.322887Z","steps":["trace[2138211458] 'process raft request' (duration: 131.793016ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:49:32.323247Z","caller":"traceutil/trace.go:171","msg":"trace[1621482491] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"131.884489ms","start":"2026-01-31T18:49:32.191354Z","end":"2026-01-31T18:49:32.323239Z","steps":["trace[1621482491] 'process raft request' (duration: 129.771902ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:49:32.323322Z","caller":"traceutil/trace.go:171","msg":"trace[124700167] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"125.842497ms","start":"2026-01-31T18:49:32.197474Z","end":"2026-01-31T18:49:32.323316Z","steps":["trace[124700167] 'process raft request' (duration: 123.66889ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:49:32.323474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.100358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-31T18:49:32.323505Z","caller":"traceutil/trace.go:171","msg":"trace[1304565304] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"203.150799ms","start":"2026-01-31T18:49:32.120347Z","end":"2026-01-31T18:49:32.323498Z","steps":["trace[1304565304] 'agreement among raft nodes before linearized reading' (duration: 203.025966ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:49:32.323624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.34934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.70\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-31T18:49:32.323645Z","caller":"traceutil/trace.go:171","msg":"trace[1696127755] range","detail":"{range_begin:/registry/masterleases/162.253.55.70; range_end:; response_count:0; response_revision:11; }","duration":"201.39565ms","start":"2026-01-31T18:49:32.122245Z","end":"2026-01-31T18:49:32.32364Z","steps":["trace[1696127755] 'agreement among raft nodes before linearized reading' (duration: 201.362579ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:49:32.32382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.231197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-31T18:49:32.323842Z","caller":"traceutil/trace.go:171","msg":"trace[1565759268] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:11; }","duration":"127.271907ms","start":"2026-01-31T18:49:32.196565Z","end":"2026-01-31T18:49:32.323837Z","steps":["trace[1565759268] 'agreement among raft nodes before linearized reading' (duration: 127.242187ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:49:32.323917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.499026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-31T18:49:32.323936Z","caller":"traceutil/trace.go:171","msg":"trace[1017860747] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"146.534976ms","start":"2026-01-31T18:49:32.177397Z","end":"2026-01-31T18:49:32.323932Z","steps":["trace[1017860747] 'agreement among raft nodes before linearized reading' (duration: 146.504386ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:49:32.324006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.179064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-31T18:49:32.324033Z","caller":"traceutil/trace.go:171","msg":"trace[761402267] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"160.227144ms","start":"2026-01-31T18:49:32.163794Z","end":"2026-01-31T18:49:32.324021Z","steps":["trace[761402267] 'agreement among raft nodes before linearized reading' (duration: 160.201104ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:49:32.324109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.328557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-31T18:49:32.32413Z","caller":"traceutil/trace.go:171","msg":"trace[889517613] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"160.367848ms","start":"2026-01-31T18:49:32.163757Z","end":"2026-01-31T18:49:32.324125Z","steps":["trace[889517613] 'agreement among raft nodes before linearized reading' (duration: 160.336717ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:49:32.324215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.824639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-31T18:49:32.324236Z","caller":"traceutil/trace.go:171","msg":"trace[1723755965] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"195.84754ms","start":"2026-01-31T18:49:32.128384Z","end":"2026-01-31T18:49:32.324231Z","steps":["trace[1723755965] 'agreement among raft nodes before linearized reading' (duration: 195.815179ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:49:32.324314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.27168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-31T18:49:32.324336Z","caller":"traceutil/trace.go:171","msg":"trace[387950208] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"197.314451ms","start":"2026-01-31T18:49:32.127017Z","end":"2026-01-31T18:49:32.324332Z","steps":["trace[387950208] 'agreement among raft nodes before linearized reading' (duration: 197.28551ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:49:32.322358Z","caller":"traceutil/trace.go:171","msg":"trace[1437899219] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"132.770228ms","start":"2026-01-31T18:49:32.189562Z","end":"2026-01-31T18:49:32.322332Z","steps":["trace[1437899219] 'process raft request' (duration: 131.5157ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:49:40.079817Z","caller":"traceutil/trace.go:171","msg":"trace[1874146482] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"295.269463ms","start":"2026-01-31T18:49:39.784529Z","end":"2026-01-31T18:49:40.079798Z","steps":["trace[1874146482] 'process raft request' (duration: 295.181581ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:49:40.270776Z","caller":"traceutil/trace.go:171","msg":"trace[1891424748] linearizableReadLoop","detail":"{readStateIndex:348; appliedIndex:347; }","duration":"138.124655ms","start":"2026-01-31T18:49:40.132641Z","end":"2026-01-31T18:49:40.270765Z","steps":["trace[1891424748] 'read index received' (duration: 132.575214ms)","trace[1891424748] 'applied index is now lower than readState.Index' (duration: 5.548981ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T18:49:40.270965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.310639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-01-31T18:49:40.27099Z","caller":"traceutil/trace.go:171","msg":"trace[1138165075] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:336; }","duration":"138.354499ms","start":"2026-01-31T18:49:40.13263Z","end":"2026-01-31T18:49:40.270985Z","steps":["trace[1138165075] 'agreement among raft nodes before linearized reading' (duration: 138.239307ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:49:40.271154Z","caller":"traceutil/trace.go:171","msg":"trace[2113900213] transaction","detail":"{read_only:false; response_revision:336; number_of_response:1; }","duration":"168.1958ms","start":"2026-01-31T18:49:40.102952Z","end":"2026-01-31T18:49:40.271148Z","steps":["trace[2113900213] 'process raft request' (duration: 162.363283ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:50:01.038674Z","caller":"traceutil/trace.go:171","msg":"trace[42684001] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"567.057096ms","start":"2026-01-31T18:50:00.471602Z","end":"2026-01-31T18:50:01.038659Z","steps":["trace[42684001] 'process raft request' (duration: 566.966734ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:50:01.039008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:50:00.471584Z","time spent":"567.144267ms","remote":"127.0.0.1:43722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T18:50:01.126438Z","caller":"traceutil/trace.go:171","msg":"trace[1459550599] linearizableReadLoop","detail":"{readStateIndex:528; appliedIndex:527; }","duration":"384.992511ms","start":"2026-01-31T18:50:00.741429Z","end":"2026-01-31T18:50:01.126421Z","steps":["trace[1459550599] 'read index received' (duration: 297.929671ms)","trace[1459550599] 'applied index is now lower than readState.Index' (duration: 87.06213ms)"],"step_count":2} {"level":"info","ts":"2026-01-31T18:50:01.126611Z","caller":"traceutil/trace.go:171","msg":"trace[586956769] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"653.851351ms","start":"2026-01-31T18:50:00.472752Z","end":"2026-01-31T18:50:01.126604Z","steps":["trace[586956769] 'process raft request' (duration: 651.143931ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:50:01.126708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:50:00.472745Z","time spent":"653.885041ms","remote":"127.0.0.1:43722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T18:50:01.126875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.445481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-01-31T18:50:01.126894Z","caller":"traceutil/trace.go:171","msg":"trace[32380263] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:510; }","duration":"385.498092ms","start":"2026-01-31T18:50:00.74139Z","end":"2026-01-31T18:50:01.126888Z","steps":["trace[32380263] 'agreement among raft nodes before linearized reading' (duration: 385.42441ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:50:01.126912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:50:00.741355Z","time spent":"385.552994ms","remote":"127.0.0.1:43722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-01-31T18:50:01.126999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.340335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T18:50:01.127016Z","caller":"traceutil/trace.go:171","msg":"trace[897075893] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:510; }","duration":"315.387916ms","start":"2026-01-31T18:50:00.811621Z","end":"2026-01-31T18:50:01.127009Z","steps":["trace[897075893] 'agreement among raft nodes before linearized reading' (duration: 315.363355ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:50:01.12703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:50:00.811609Z","time spent":"315.417847ms","remote":"127.0.0.1:43406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-01-31T18:50:02.354386Z","caller":"traceutil/trace.go:171","msg":"trace[953521086] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"527.337549ms","start":"2026-01-31T18:50:01.827032Z","end":"2026-01-31T18:50:02.35437Z","steps":["trace[953521086] 'process raft request' (duration: 527.250848ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:50:02.354482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:50:01.827022Z","time spent":"527.412281ms","remote":"127.0.0.1:43722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T18:50:02.628028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.214053ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T18:50:02.628123Z","caller":"traceutil/trace.go:171","msg":"trace[441826891] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:512; }","duration":"111.322205ms","start":"2026-01-31T18:50:02.516788Z","end":"2026-01-31T18:50:02.62811Z","steps":["trace[441826891] 'range keys from in-memory index tree' (duration: 111.202602ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:50:03.444017Z","caller":"traceutil/trace.go:171","msg":"trace[230750535] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"219.613283ms","start":"2026-01-31T18:50:03.224381Z","end":"2026-01-31T18:50:03.443995Z","steps":["trace[230750535] 'process raft request' (duration: 126.772716ms)","trace[230750535] 'compare' (duration: 92.750625ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T18:50:07.41291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.480576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-01-31T18:50:07.412977Z","caller":"traceutil/trace.go:171","msg":"trace[366123261] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:526; }","duration":"329.553769ms","start":"2026-01-31T18:50:07.083406Z","end":"2026-01-31T18:50:07.41296Z","steps":["trace[366123261] 'range keys from in-memory index tree' (duration: 329.324853ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:50:07.413012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:50:07.083367Z","time spent":"329.63738ms","remote":"127.0.0.1:43722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-31T18:50:07.413348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.365128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-01-31T18:50:07.413377Z","caller":"traceutil/trace.go:171","msg":"trace[1775267677] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:526; }","duration":"180.428689ms","start":"2026-01-31T18:50:07.232939Z","end":"2026-01-31T18:50:07.413368Z","steps":["trace[1775267677] 'range keys from in-memory index tree' (duration: 180.273436ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:50:07.805109Z","caller":"traceutil/trace.go:171","msg":"trace[1625512811] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"385.356459ms","start":"2026-01-31T18:50:07.41973Z","end":"2026-01-31T18:50:07.805087Z","steps":["trace[1625512811] 'process raft request' (duration: 385.197105ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:50:07.805243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:50:07.419716Z","time spent":"385.457262ms","remote":"127.0.0.1:43722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T18:50:08.366349Z","caller":"traceutil/trace.go:171","msg":"trace[1383689427] linearizableReadLoop","detail":"{readStateIndex:548; appliedIndex:546; }","duration":"908.831693ms","start":"2026-01-31T18:50:07.457486Z","end":"2026-01-31T18:50:08.366318Z","steps":["trace[1383689427] 'read index received' (duration: 349.073948ms)","trace[1383689427] 'applied index is now lower than readState.Index' (duration: 559.756184ms)"],"step_count":2} {"level":"info","ts":"2026-01-31T18:50:08.366711Z","caller":"traceutil/trace.go:171","msg":"trace[358120240] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"943.045767ms","start":"2026-01-31T18:50:07.423649Z","end":"2026-01-31T18:50:08.366695Z","steps":["trace[358120240] 'process raft request' (duration: 942.465694ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:50:08.366821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:50:07.423643Z","time spent":"943.108918ms","remote":"127.0.0.1:43722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T18:50:08.367094Z","caller":"traceutil/trace.go:171","msg":"trace[2110212211] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"937.946454ms","start":"2026-01-31T18:50:07.429136Z","end":"2026-01-31T18:50:08.367083Z","steps":["trace[2110212211] 'process raft request' (duration: 937.106776ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:50:08.367149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:50:07.429128Z","time spent":"937.991405ms","remote":"127.0.0.1:43628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T18:50:08.36733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"909.839364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-01-31T18:50:08.367362Z","caller":"traceutil/trace.go:171","msg":"trace[306055957] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:529; }","duration":"909.898176ms","start":"2026-01-31T18:50:07.457452Z","end":"2026-01-31T18:50:08.367351Z","steps":["trace[306055957] 'agreement among raft nodes before linearized reading' (duration: 909.796534ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:50:08.367391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:50:07.457438Z","time spent":"909.946327ms","remote":"127.0.0.1:43722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-01-31T18:50:08.367616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"736.118155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T18:50:08.367646Z","caller":"traceutil/trace.go:171","msg":"trace[561158919] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:529; }","duration":"736.192236ms","start":"2026-01-31T18:50:07.631445Z","end":"2026-01-31T18:50:08.367637Z","steps":["trace[561158919] 'agreement among raft nodes before linearized reading' (duration: 736.145015ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:50:08.367678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:50:07.631425Z","time spent":"736.245877ms","remote":"127.0.0.1:43406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-31T18:50:10.09251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.113871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-01-31T18:50:10.092604Z","caller":"traceutil/trace.go:171","msg":"trace[480458252] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:539; }","duration":"282.320215ms","start":"2026-01-31T18:50:09.810264Z","end":"2026-01-31T18:50:10.092585Z","steps":["trace[480458252] 'range keys from in-memory index tree' (duration: 281.870846ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:50:22.697509Z","caller":"traceutil/trace.go:171","msg":"trace[94621508] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"213.282284ms","start":"2026-01-31T18:50:22.484181Z","end":"2026-01-31T18:50:22.697463Z","steps":["trace[94621508] 'process raft request' (duration: 213.09625ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:50:22.698774Z","caller":"traceutil/trace.go:171","msg":"trace[1896968406] linearizableReadLoop","detail":"{readStateIndex:716; appliedIndex:714; }","duration":"210.519373ms","start":"2026-01-31T18:50:22.488235Z","end":"2026-01-31T18:50:22.698754Z","steps":["trace[1896968406] 'read index received' (duration: 209.194874ms)","trace[1896968406] 'applied index is now lower than readState.Index' (duration: 1.323699ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T18:50:22.699096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.157605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-01-31T18:50:22.699129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.85366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-01-31T18:50:22.699132Z","caller":"traceutil/trace.go:171","msg":"trace[303330413] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:694; }","duration":"210.214237ms","start":"2026-01-31T18:50:22.488907Z","end":"2026-01-31T18:50:22.699121Z","steps":["trace[303330413] 'agreement among raft nodes before linearized reading' (duration: 210.083634ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:50:22.699204Z","caller":"traceutil/trace.go:171","msg":"trace[203305174] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:694; }","duration":"210.954383ms","start":"2026-01-31T18:50:22.488214Z","end":"2026-01-31T18:50:22.699168Z","steps":["trace[203305174] 'agreement among raft nodes before linearized reading' (duration: 210.746108ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:50:22.699483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.46849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-5qkm2\" ","response":"range_response_count:1 size:4823"} {"level":"info","ts":"2026-01-31T18:50:22.699548Z","caller":"traceutil/trace.go:171","msg":"trace[1242720892] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-5qkm2; range_end:; response_count:1; response_revision:694; }","duration":"175.525572ms","start":"2026-01-31T18:50:22.52398Z","end":"2026-01-31T18:50:22.699506Z","steps":["trace[1242720892] 'agreement among raft nodes before linearized reading' (duration: 175.43781ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:50:22.699597Z","caller":"traceutil/trace.go:171","msg":"trace[565121513] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"215.232738ms","start":"2026-01-31T18:50:22.484349Z","end":"2026-01-31T18:50:22.699582Z","steps":["trace[565121513] 'process raft request' (duration: 214.264556ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:50:22.699739Z","caller":"traceutil/trace.go:171","msg":"trace[327631536] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"175.502621ms","start":"2026-01-31T18:50:22.524204Z","end":"2026-01-31T18:50:22.699706Z","steps":["trace[327631536] 'process raft request' (duration: 174.508879ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:50:28.866395Z","caller":"traceutil/trace.go:171","msg":"trace[1685052829] linearizableReadLoop","detail":"{readStateIndex:738; appliedIndex:737; }","duration":"106.602351ms","start":"2026-01-31T18:50:28.759771Z","end":"2026-01-31T18:50:28.866373Z","steps":["trace[1685052829] 'read index received' (duration: 106.445898ms)","trace[1685052829] 'applied index is now lower than readState.Index' (duration: 155.173µs)"],"step_count":2} {"level":"warn","ts":"2026-01-31T18:50:28.866601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.806745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-01-31T18:50:28.866632Z","caller":"traceutil/trace.go:171","msg":"trace[2010525119] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:714; }","duration":"106.886266ms","start":"2026-01-31T18:50:28.759736Z","end":"2026-01-31T18:50:28.866622Z","steps":["trace[2010525119] 'agreement among raft nodes before linearized reading' (duration: 106.726523ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:50:28.866768Z","caller":"traceutil/trace.go:171","msg":"trace[1553054022] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"111.117351ms","start":"2026-01-31T18:50:28.755608Z","end":"2026-01-31T18:50:28.866725Z","steps":["trace[1553054022] 'process raft request' (duration: 110.592839ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:50:36.457703Z","caller":"traceutil/trace.go:171","msg":"trace[63922653] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"144.18702ms","start":"2026-01-31T18:50:36.31349Z","end":"2026-01-31T18:50:36.457677Z","steps":["trace[63922653] 'process raft request' (duration: 143.963535ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:50:36.738534Z","caller":"traceutil/trace.go:171","msg":"trace[1999959538] linearizableReadLoop","detail":"{readStateIndex:766; appliedIndex:765; }","duration":"111.05172ms","start":"2026-01-31T18:50:36.627468Z","end":"2026-01-31T18:50:36.73852Z","steps":["trace[1999959538] 'read index received' (duration: 110.967948ms)","trace[1999959538] 'applied index is now lower than readState.Index' (duration: 83.232µs)"],"step_count":2} {"level":"warn","ts":"2026-01-31T18:50:36.738619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.157201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T18:50:36.738638Z","caller":"traceutil/trace.go:171","msg":"trace[675066356] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:739; }","duration":"111.215683ms","start":"2026-01-31T18:50:36.627415Z","end":"2026-01-31T18:50:36.738631Z","steps":["trace[675066356] 'agreement among raft nodes before linearized reading' (duration: 111.156472ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:50:36.738751Z","caller":"traceutil/trace.go:171","msg":"trace[1871829500] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"367.171738ms","start":"2026-01-31T18:50:36.371572Z","end":"2026-01-31T18:50:36.738744Z","steps":["trace[1871829500] 'process raft request' (duration: 366.864711ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:50:36.7388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:50:36.371556Z","time spent":"367.207428ms","remote":"127.0.0.1:43614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4725,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T18:50:40.697397Z","caller":"traceutil/trace.go:171","msg":"trace[128674540] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"227.059807ms","start":"2026-01-31T18:50:40.470324Z","end":"2026-01-31T18:50:40.697384Z","steps":["trace[128674540] 'process raft request' (duration: 226.813412ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:50:41.02079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.220673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-01-31T18:50:41.020837Z","caller":"traceutil/trace.go:171","msg":"trace[282045560] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:750; }","duration":"242.299094ms","start":"2026-01-31T18:50:40.778525Z","end":"2026-01-31T18:50:41.020824Z","steps":["trace[282045560] 'range keys from in-memory index tree' (duration: 242.103289ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:50:41.020965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.299402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-01-31T18:50:41.020979Z","caller":"traceutil/trace.go:171","msg":"trace[1704367271] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:750; }","duration":"231.335532ms","start":"2026-01-31T18:50:40.789638Z","end":"2026-01-31T18:50:41.020974Z","steps":["trace[1704367271] 'range keys from in-memory index tree' (duration: 231.2419ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:50:41.231394Z","caller":"traceutil/trace.go:171","msg":"trace[1433767468] linearizableReadLoop","detail":"{readStateIndex:782; appliedIndex:779; }","duration":"156.373979ms","start":"2026-01-31T18:50:41.075003Z","end":"2026-01-31T18:50:41.231377Z","steps":["trace[1433767468] 'read index received' (duration: 78.89868ms)","trace[1433767468] 'applied index is now lower than readState.Index' (duration: 77.474459ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T18:50:41.231503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.488531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T18:50:41.231548Z","caller":"traceutil/trace.go:171","msg":"trace[106705433] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:754; }","duration":"156.572294ms","start":"2026-01-31T18:50:41.074969Z","end":"2026-01-31T18:50:41.231541Z","steps":["trace[106705433] 'agreement among raft nodes before linearized reading' (duration: 156.477262ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:50:41.231752Z","caller":"traceutil/trace.go:171","msg":"trace[556234825] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"205.995964ms","start":"2026-01-31T18:50:41.025748Z","end":"2026-01-31T18:50:41.231744Z","steps":["trace[556234825] 'process raft request' (duration: 128.210448ms)","trace[556234825] 'compare' (duration: 77.163713ms)"],"step_count":2} {"level":"info","ts":"2026-01-31T18:50:41.231843Z","caller":"traceutil/trace.go:171","msg":"trace[1673941171] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"205.082973ms","start":"2026-01-31T18:50:41.026753Z","end":"2026-01-31T18:50:41.231836Z","steps":["trace[1673941171] 'process raft request' (duration: 204.550612ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:50:41.231926Z","caller":"traceutil/trace.go:171","msg":"trace[788271593] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"203.112439ms","start":"2026-01-31T18:50:41.028807Z","end":"2026-01-31T18:50:41.231919Z","steps":["trace[788271593] 'process raft request' (duration: 202.542567ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:50:42.344583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.319612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T18:50:42.344629Z","caller":"traceutil/trace.go:171","msg":"trace[1365718646] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:754; }","duration":"475.414774ms","start":"2026-01-31T18:50:41.869202Z","end":"2026-01-31T18:50:42.344617Z","steps":["trace[1365718646] 'count revisions from in-memory index tree' (duration: 475.260651ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:50:42.34465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:50:41.869187Z","time spent":"475.457465ms","remote":"127.0.0.1:43590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"info","ts":"2026-01-31T18:50:53.285761Z","caller":"traceutil/trace.go:171","msg":"trace[1740984411] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"192.877549ms","start":"2026-01-31T18:50:53.092869Z","end":"2026-01-31T18:50:53.285747Z","steps":["trace[1740984411] 'process raft request' (duration: 192.707965ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:50:53.442209Z","caller":"traceutil/trace.go:171","msg":"trace[1337986854] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"149.259889ms","start":"2026-01-31T18:50:53.292927Z","end":"2026-01-31T18:50:53.442187Z","steps":["trace[1337986854] 'process raft request' (duration: 84.665143ms)","trace[1337986854] 'compare' (duration: 64.241079ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T18:50:54.427873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"883.615247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.70\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-01-31T18:50:54.427922Z","caller":"traceutil/trace.go:171","msg":"trace[2108361443] range","detail":"{range_begin:/registry/masterleases/162.253.55.70; range_end:; response_count:1; response_revision:787; }","duration":"883.711109ms","start":"2026-01-31T18:50:53.544194Z","end":"2026-01-31T18:50:54.427905Z","steps":["trace[2108361443] 'range keys from in-memory index tree' (duration: 883.494444ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:50:54.427942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:50:53.544171Z","time spent":"883.766641ms","remote":"127.0.0.1:43452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/162.253.55.70\" "} {"level":"warn","ts":"2026-01-31T18:50:54.428136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"800.387666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T18:50:54.42815Z","caller":"traceutil/trace.go:171","msg":"trace[98028914] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:787; }","duration":"800.425167ms","start":"2026-01-31T18:50:53.62772Z","end":"2026-01-31T18:50:54.428145Z","steps":["trace[98028914] 'range keys from in-memory index tree' (duration: 800.329425ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:50:54.428161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:50:53.627707Z","time spent":"800.450998ms","remote":"127.0.0.1:43406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-31T18:50:54.741583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:50:54.429763Z","time spent":"311.817953ms","remote":"127.0.0.1:43452","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-01-31T18:50:54.868643Z","caller":"traceutil/trace.go:171","msg":"trace[1928654809] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"238.146465ms","start":"2026-01-31T18:50:54.63048Z","end":"2026-01-31T18:50:54.868626Z","steps":["trace[1928654809] 'process raft request' (duration: 220.222507ms)","trace[1928654809] 'compare' (duration: 17.490408ms)"],"step_count":2} {"level":"info","ts":"2026-01-31T18:50:54.868752Z","caller":"traceutil/trace.go:171","msg":"trace[888962679] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"126.529373ms","start":"2026-01-31T18:50:54.742217Z","end":"2026-01-31T18:50:54.868746Z","steps":["trace[888962679] 'process raft request' (duration: 126.082994ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:50:55.106883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.169441ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-31T18:50:55.106953Z","caller":"traceutil/trace.go:171","msg":"trace[2135206951] linearizableReadLoop","detail":"{readStateIndex:821; appliedIndex:820; }","duration":"232.411768ms","start":"2026-01-31T18:50:54.874532Z","end":"2026-01-31T18:50:55.106944Z","steps":["trace[2135206951] 'read index received' (duration: 99.005701ms)","trace[2135206951] 'applied index is now lower than readState.Index' (duration: 133.405417ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T18:50:55.107024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.505009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-01-31T18:50:55.107036Z","caller":"traceutil/trace.go:171","msg":"trace[1289478292] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:790; }","duration":"232.546681ms","start":"2026-01-31T18:50:54.874485Z","end":"2026-01-31T18:50:55.107032Z","steps":["trace[1289478292] 'agreement among raft nodes before linearized reading' (duration: 232.483009ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:50:55.107271Z","caller":"traceutil/trace.go:171","msg":"trace[20144095] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"233.506191ms","start":"2026-01-31T18:50:54.87375Z","end":"2026-01-31T18:50:55.107256Z","steps":["trace[20144095] 'process raft request' (duration: 99.828729ms)","trace[20144095] 'compare' (duration: 133.074579ms)"],"step_count":2} {"level":"info","ts":"2026-01-31T18:50:55.536645Z","caller":"traceutil/trace.go:171","msg":"trace[1314112341] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"187.896788ms","start":"2026-01-31T18:50:55.348728Z","end":"2026-01-31T18:50:55.536625Z","steps":["trace[1314112341] 'process raft request' (duration: 103.513742ms)","trace[1314112341] 'compare' (duration: 84.025228ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T18:50:56.148239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.141524ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-31T18:50:56.148312Z","caller":"traceutil/trace.go:171","msg":"trace[1429621223] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"605.675247ms","start":"2026-01-31T18:50:55.542626Z","end":"2026-01-31T18:50:56.148301Z","steps":["trace[1429621223] 'process raft request' (duration: 278.421161ms)","trace[1429621223] 'compare' (duration: 327.014911ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T18:50:56.148341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:50:55.54261Z","time spent":"605.718748ms","remote":"127.0.0.1:43722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T18:50:56.157208Z","caller":"traceutil/trace.go:171","msg":"trace[1307980038] linearizableReadLoop","detail":"{readStateIndex:825; appliedIndex:823; }","duration":"530.417914ms","start":"2026-01-31T18:50:55.62678Z","end":"2026-01-31T18:50:56.157198Z","steps":["trace[1307980038] 'read index received' (duration: 194.27494ms)","trace[1307980038] 'applied index is now lower than readState.Index' (duration: 336.142444ms)"],"step_count":2} {"level":"info","ts":"2026-01-31T18:50:56.15734Z","caller":"traceutil/trace.go:171","msg":"trace[585981346] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"614.534364ms","start":"2026-01-31T18:50:55.5428Z","end":"2026-01-31T18:50:56.157334Z","steps":["trace[585981346] 'process raft request' (duration: 614.35265ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:50:56.157384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:50:55.542793Z","time spent":"614.563974ms","remote":"127.0.0.1:43722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T18:50:56.157471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.699479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T18:50:56.157489Z","caller":"traceutil/trace.go:171","msg":"trace[680626273] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:794; }","duration":"530.751411ms","start":"2026-01-31T18:50:55.626733Z","end":"2026-01-31T18:50:56.157484Z","steps":["trace[680626273] 'agreement among raft nodes before linearized reading' (duration: 530.72295ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:50:56.157503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:50:55.626703Z","time spent":"530.796621ms","remote":"127.0.0.1:43406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-31T18:50:56.157587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.940643ms","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-01-31T18:50:56.157604Z","caller":"traceutil/trace.go:171","msg":"trace[1354230027] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:794; }","duration":"323.981163ms","start":"2026-01-31T18:50:55.833618Z","end":"2026-01-31T18:50:56.157599Z","steps":["trace[1354230027] 'agreement among raft nodes before linearized reading' (duration: 323.949633ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:50:56.157617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:50:55.833607Z","time spent":"324.006995ms","remote":"127.0.0.1:43882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":29,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-01-31T18:50:58.839917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.698425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-01-31T18:50:58.840319Z","caller":"traceutil/trace.go:171","msg":"trace[815404119] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:798; }","duration":"685.300767ms","start":"2026-01-31T18:50:58.154998Z","end":"2026-01-31T18:50:58.840298Z","steps":["trace[815404119] 'range keys from in-memory index tree' (duration: 684.539271ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:50:58.840511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:50:58.15498Z","time spent":"685.516973ms","remote":"127.0.0.1:43722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-31T18:50:58.841242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.967471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-01-31T18:50:58.841436Z","caller":"traceutil/trace.go:171","msg":"trace[407650070] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:798; }","duration":"681.182175ms","start":"2026-01-31T18:50:58.160241Z","end":"2026-01-31T18:50:58.841424Z","steps":["trace[407650070] 'range keys from in-memory index tree' (duration: 680.801177ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:50:58.84152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:50:58.160231Z","time spent":"681.276007ms","remote":"127.0.0.1:43722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-01-31T18:50:58.842235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.168944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T18:50:58.842528Z","caller":"traceutil/trace.go:171","msg":"trace[1447917422] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:798; }","duration":"439.493611ms","start":"2026-01-31T18:50:58.403018Z","end":"2026-01-31T18:50:58.842512Z","steps":["trace[1447917422] 'count revisions from in-memory index tree' (duration: 439.078982ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:50:58.842704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:50:58.403001Z","time spent":"439.687456ms","remote":"127.0.0.1:43568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":27,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-01-31T18:50:58.843068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.593295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-31T18:50:58.843275Z","caller":"traceutil/trace.go:171","msg":"trace[1341735246] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:798; }","duration":"372.838479ms","start":"2026-01-31T18:50:58.470423Z","end":"2026-01-31T18:50:58.843262Z","steps":["trace[1341735246] 'count revisions from in-memory index tree' (duration: 372.515113ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:50:58.843453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:50:58.470404Z","time spent":"373.033184ms","remote":"127.0.0.1:43810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":51,"response size":29,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-01-31T18:50:59.123326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.537172ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-01-31T18:50:59.129543Z","caller":"traceutil/trace.go:171","msg":"trace[1657773046] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"278.711207ms","start":"2026-01-31T18:50:58.850815Z","end":"2026-01-31T18:50:59.129526Z","steps":["trace[1657773046] 'process raft request' (duration: 278.321429ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:50:59.129649Z","caller":"traceutil/trace.go:171","msg":"trace[701765983] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"278.755529ms","start":"2026-01-31T18:50:58.850887Z","end":"2026-01-31T18:50:59.129643Z","steps":["trace[701765983] 'process raft request' (duration: 278.33857ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:50:59.582833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.424413ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-31T18:50:59.582982Z","caller":"traceutil/trace.go:171","msg":"trace[1080405057] linearizableReadLoop","detail":"{readStateIndex:833; appliedIndex:832; }","duration":"136.964406ms","start":"2026-01-31T18:50:59.446007Z","end":"2026-01-31T18:50:59.582972Z","steps":["trace[1080405057] 'read index received' (duration: 17.617451ms)","trace[1080405057] 'applied index is now lower than readState.Index' (duration: 119.344265ms)"],"step_count":2} {"level":"info","ts":"2026-01-31T18:50:59.583346Z","caller":"traceutil/trace.go:171","msg":"trace[2029606628] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"450.959576ms","start":"2026-01-31T18:50:59.132371Z","end":"2026-01-31T18:50:59.583331Z","steps":["trace[2029606628] 'process raft request' (duration: 331.312876ms)","trace[2029606628] 'compare' (duration: 118.343481ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T18:50:59.58446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:50:59.132359Z","time spent":"452.037851ms","remote":"127.0.0.1:43722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T18:50:59.584121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.10489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-01-31T18:50:59.587457Z","caller":"traceutil/trace.go:171","msg":"trace[176344004] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:801; }","duration":"141.467525ms","start":"2026-01-31T18:50:59.445965Z","end":"2026-01-31T18:50:59.587432Z","steps":["trace[176344004] 'agreement among raft nodes before linearized reading' (duration: 138.07875ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:51:10.414431Z","caller":"traceutil/trace.go:171","msg":"trace[1511455088] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"701.900936ms","start":"2026-01-31T18:51:09.712514Z","end":"2026-01-31T18:51:10.414415Z","steps":["trace[1511455088] 'process raft request' (duration: 701.769353ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:51:10.41454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:51:09.712495Z","time spent":"701.990178ms","remote":"127.0.0.1:43722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T18:51:10.416065Z","caller":"traceutil/trace.go:171","msg":"trace[1929532004] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"702.096572ms","start":"2026-01-31T18:51:09.713961Z","end":"2026-01-31T18:51:10.416057Z","steps":["trace[1929532004] 'process raft request' (duration: 701.951239ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:51:10.416127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:51:09.713945Z","time spent":"702.144442ms","remote":"127.0.0.1:43722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T18:51:10.421114Z","caller":"traceutil/trace.go:171","msg":"trace[1146879193] linearizableReadLoop","detail":"{readStateIndex:919; appliedIndex:917; }","duration":"161.293937ms","start":"2026-01-31T18:51:10.259812Z","end":"2026-01-31T18:51:10.421106Z","steps":["trace[1146879193] 'read index received' (duration: 155.107709ms)","trace[1146879193] 'applied index is now lower than readState.Index' (duration: 6.185698ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T18:51:10.421226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.415909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-31T18:51:10.421243Z","caller":"traceutil/trace.go:171","msg":"trace[1671453549] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:883; }","duration":"161.462911ms","start":"2026-01-31T18:51:10.259775Z","end":"2026-01-31T18:51:10.421237Z","steps":["trace[1671453549] 'agreement among raft nodes before linearized reading' (duration: 161.42849ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:51:10.421327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.264602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T18:51:10.421341Z","caller":"traceutil/trace.go:171","msg":"trace[1768708773] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:883; }","duration":"128.305543ms","start":"2026-01-31T18:51:10.293031Z","end":"2026-01-31T18:51:10.421336Z","steps":["trace[1768708773] 'agreement among raft nodes before linearized reading' (duration: 128.285973ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:51:12.59496Z","caller":"traceutil/trace.go:171","msg":"trace[555931783] linearizableReadLoop","detail":"{readStateIndex:923; appliedIndex:922; }","duration":"173.676232ms","start":"2026-01-31T18:51:12.421263Z","end":"2026-01-31T18:51:12.594939Z","steps":["trace[555931783] 'read index received' (duration: 173.548309ms)","trace[555931783] 'applied index is now lower than readState.Index' (duration: 127.133µs)"],"step_count":2} {"level":"warn","ts":"2026-01-31T18:51:12.595122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.835735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-01-31T18:51:12.595145Z","caller":"traceutil/trace.go:171","msg":"trace[1047471792] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:887; }","duration":"173.885636ms","start":"2026-01-31T18:51:12.421252Z","end":"2026-01-31T18:51:12.595138Z","steps":["trace[1047471792] 'agreement among raft nodes before linearized reading' (duration: 173.758733ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:51:12.595224Z","caller":"traceutil/trace.go:171","msg":"trace[1615031904] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"174.931819ms","start":"2026-01-31T18:51:12.420271Z","end":"2026-01-31T18:51:12.595203Z","steps":["trace[1615031904] 'process raft request' (duration: 174.560731ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:51:13.023392Z","caller":"traceutil/trace.go:171","msg":"trace[939325598] linearizableReadLoop","detail":"{readStateIndex:924; appliedIndex:923; }","duration":"394.844099ms","start":"2026-01-31T18:51:12.628534Z","end":"2026-01-31T18:51:13.023378Z","steps":["trace[939325598] 'read index received' (duration: 391.460883ms)","trace[939325598] 'applied index is now lower than readState.Index' (duration: 3.382536ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T18:51:13.023458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.915201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T18:51:13.023473Z","caller":"traceutil/trace.go:171","msg":"trace[280873453] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:888; }","duration":"394.963352ms","start":"2026-01-31T18:51:12.628505Z","end":"2026-01-31T18:51:13.023469Z","steps":["trace[280873453] 'agreement among raft nodes before linearized reading' (duration: 394.924481ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:51:13.023489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:51:12.628489Z","time spent":"394.996382ms","remote":"127.0.0.1:43406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-01-31T18:51:13.023591Z","caller":"traceutil/trace.go:171","msg":"trace[219837534] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"424.766785ms","start":"2026-01-31T18:51:12.598801Z","end":"2026-01-31T18:51:13.023568Z","steps":["trace[219837534] 'process raft request' (duration: 421.200255ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:51:13.023733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:51:12.598786Z","time spent":"424.877167ms","remote":"127.0.0.1:43722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T18:51:14.046143Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132439766851494,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-31T18:51:14.048399Z","caller":"traceutil/trace.go:171","msg":"trace[543505019] linearizableReadLoop","detail":"{readStateIndex:926; appliedIndex:925; }","duration":"503.066995ms","start":"2026-01-31T18:51:13.545319Z","end":"2026-01-31T18:51:14.048386Z","steps":["trace[543505019] 'read index received' (duration: 502.985213ms)","trace[543505019] 'applied index is now lower than readState.Index' (duration: 81.202µs)"],"step_count":2} {"level":"info","ts":"2026-01-31T18:51:14.048448Z","caller":"traceutil/trace.go:171","msg":"trace[726772583] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"744.158677ms","start":"2026-01-31T18:51:13.304285Z","end":"2026-01-31T18:51:14.048443Z","steps":["trace[726772583] 'process raft request' (duration: 744.013903ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:51:14.048507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:51:13.304277Z","time spent":"744.184268ms","remote":"127.0.0.1:43722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T18:51:14.048619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.299251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.70\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-01-31T18:51:14.048633Z","caller":"traceutil/trace.go:171","msg":"trace[207450002] range","detail":"{range_begin:/registry/masterleases/162.253.55.70; range_end:; response_count:1; response_revision:890; }","duration":"503.337862ms","start":"2026-01-31T18:51:13.545291Z","end":"2026-01-31T18:51:14.048629Z","steps":["trace[207450002] 'agreement among raft nodes before linearized reading' (duration: 503.278861ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:51:14.048646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:51:13.545278Z","time spent":"503.365303ms","remote":"127.0.0.1:43452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/162.253.55.70\" "} {"level":"warn","ts":"2026-01-31T18:51:14.048793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.519804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-31T18:51:14.048811Z","caller":"traceutil/trace.go:171","msg":"trace[1616864257] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:890; }","duration":"502.545415ms","start":"2026-01-31T18:51:13.546258Z","end":"2026-01-31T18:51:14.048803Z","steps":["trace[1616864257] 'agreement among raft nodes before linearized reading' (duration: 502.513444ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:51:14.048826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:51:13.546252Z","time spent":"502.568455ms","remote":"127.0.0.1:43468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":11,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-01-31T18:51:14.048912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.757666ms","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-01-31T18:51:14.048926Z","caller":"traceutil/trace.go:171","msg":"trace[1068927077] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:890; }","duration":"308.805608ms","start":"2026-01-31T18:51:13.740116Z","end":"2026-01-31T18:51:14.048922Z","steps":["trace[1068927077] 'agreement among raft nodes before linearized reading' (duration: 308.768017ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:51:14.048937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:51:13.734733Z","time spent":"314.201926ms","remote":"127.0.0.1:43722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-01-31T18:51:14.207364Z","caller":"traceutil/trace.go:171","msg":"trace[702047829] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"155.768553ms","start":"2026-01-31T18:51:14.051581Z","end":"2026-01-31T18:51:14.207349Z","steps":["trace[702047829] 'process raft request' (duration: 155.692841ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:51:22.460233Z","caller":"traceutil/trace.go:171","msg":"trace[299387377] linearizableReadLoop","detail":"{readStateIndex:951; appliedIndex:950; }","duration":"194.726371ms","start":"2026-01-31T18:51:22.265472Z","end":"2026-01-31T18:51:22.460198Z","steps":["trace[299387377] 'read index received' (duration: 194.550887ms)","trace[299387377] 'applied index is now lower than readState.Index' (duration: 174.544µs)"],"step_count":2} {"level":"warn","ts":"2026-01-31T18:51:22.460447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.953586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-01-31T18:51:22.460486Z","caller":"traceutil/trace.go:171","msg":"trace[1502320359] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:913; }","duration":"195.035338ms","start":"2026-01-31T18:51:22.265441Z","end":"2026-01-31T18:51:22.460477Z","steps":["trace[1502320359] 'agreement among raft nodes before linearized reading' (duration: 194.855124ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:51:22.460787Z","caller":"traceutil/trace.go:171","msg":"trace[1438310853] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"389.864898ms","start":"2026-01-31T18:51:22.070912Z","end":"2026-01-31T18:51:22.460777Z","steps":["trace[1438310853] 'process raft request' (duration: 389.163622ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:51:22.460871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:51:22.070903Z","time spent":"389.9169ms","remote":"127.0.0.1:43722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T18:51:23.40503Z","caller":"traceutil/trace.go:171","msg":"trace[306579285] linearizableReadLoop","detail":"{readStateIndex:954; appliedIndex:953; }","duration":"105.080407ms","start":"2026-01-31T18:51:23.299933Z","end":"2026-01-31T18:51:23.405013Z","steps":["trace[306579285] 'read index received' (duration: 104.944113ms)","trace[306579285] 'applied index is now lower than readState.Index' (duration: 135.664µs)"],"step_count":2} {"level":"warn","ts":"2026-01-31T18:51:23.40514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.195079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T18:51:23.405162Z","caller":"traceutil/trace.go:171","msg":"trace[922754803] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:916; }","duration":"105.254071ms","start":"2026-01-31T18:51:23.299902Z","end":"2026-01-31T18:51:23.405156Z","steps":["trace[922754803] 'agreement among raft nodes before linearized reading' (duration: 105.183019ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:51:23.40529Z","caller":"traceutil/trace.go:171","msg":"trace[655539864] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"269.334389ms","start":"2026-01-31T18:51:23.135933Z","end":"2026-01-31T18:51:23.405267Z","steps":["trace[655539864] 'process raft request' (duration: 268.979511ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:51:23.824136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.235953ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"info","ts":"2026-01-31T18:51:23.824194Z","caller":"traceutil/trace.go:171","msg":"trace[1530163254] linearizableReadLoop","detail":"{readStateIndex:956; appliedIndex:955; }","duration":"198.921033ms","start":"2026-01-31T18:51:23.625263Z","end":"2026-01-31T18:51:23.824184Z","steps":["trace[1530163254] 'read index received' (duration: 60.585168ms)","trace[1530163254] 'applied index is now lower than readState.Index' (duration: 138.335005ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T18:51:23.824238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.965484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T18:51:23.824251Z","caller":"traceutil/trace.go:171","msg":"trace[370400229] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:917; }","duration":"199.008334ms","start":"2026-01-31T18:51:23.625238Z","end":"2026-01-31T18:51:23.824246Z","steps":["trace[370400229] 'agreement among raft nodes before linearized reading' (duration: 198.971424ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:51:24.743809Z","caller":"traceutil/trace.go:171","msg":"trace[73378355] linearizableReadLoop","detail":"{readStateIndex:959; appliedIndex:958; }","duration":"116.624692ms","start":"2026-01-31T18:51:24.627172Z","end":"2026-01-31T18:51:24.743797Z","steps":["trace[73378355] 'read index received' (duration: 116.52574ms)","trace[73378355] 'applied index is now lower than readState.Index' (duration: 98.252µs)"],"step_count":2} {"level":"info","ts":"2026-01-31T18:51:24.743922Z","caller":"traceutil/trace.go:171","msg":"trace[354273753] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"190.293541ms","start":"2026-01-31T18:51:24.553622Z","end":"2026-01-31T18:51:24.743915Z","steps":["trace[354273753] 'process raft request' (duration: 190.106897ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:51:24.744025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.842447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T18:51:24.744039Z","caller":"traceutil/trace.go:171","msg":"trace[1319121051] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:920; }","duration":"116.892558ms","start":"2026-01-31T18:51:24.627143Z","end":"2026-01-31T18:51:24.744035Z","steps":["trace[1319121051] 'agreement among raft nodes before linearized reading' (duration: 116.859828ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:51:25.945802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.816217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-01-31T18:51:25.945886Z","caller":"traceutil/trace.go:171","msg":"trace[1404592441] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:922; }","duration":"394.928891ms","start":"2026-01-31T18:51:25.550927Z","end":"2026-01-31T18:51:25.945856Z","steps":["trace[1404592441] 'range keys from in-memory index tree' (duration: 394.684514ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:51:25.945927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:51:25.550915Z","time spent":"395.002722ms","remote":"127.0.0.1:43722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-01-31T18:51:25.946274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.400955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-31T18:51:25.946308Z","caller":"traceutil/trace.go:171","msg":"trace[1952946668] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:922; }","duration":"253.474487ms","start":"2026-01-31T18:51:25.692823Z","end":"2026-01-31T18:51:25.946298Z","steps":["trace[1952946668] 'count revisions from in-memory index tree' (duration: 253.332354ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:51:29.095116Z","caller":"traceutil/trace.go:171","msg":"trace[899471478] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"529.892202ms","start":"2026-01-31T18:51:28.565218Z","end":"2026-01-31T18:51:29.09511Z","steps":["trace[899471478] 'process raft request' (duration: 529.658327ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:51:29.095188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:51:28.565208Z","time spent":"529.936983ms","remote":"127.0.0.1:43722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T18:51:29.094998Z","caller":"traceutil/trace.go:171","msg":"trace[770876458] linearizableReadLoop","detail":"{readStateIndex:970; appliedIndex:969; }","duration":"467.637337ms","start":"2026-01-31T18:51:28.627346Z","end":"2026-01-31T18:51:29.094984Z","steps":["trace[770876458] 'read index received' (duration: 467.484684ms)","trace[770876458] 'applied index is now lower than readState.Index' (duration: 151.513µs)"],"step_count":2} {"level":"warn","ts":"2026-01-31T18:51:29.09542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.192418ms","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-01-31T18:51:29.095435Z","caller":"traceutil/trace.go:171","msg":"trace[609850959] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:931; }","duration":"342.231319ms","start":"2026-01-31T18:51:28.753197Z","end":"2026-01-31T18:51:29.095429Z","steps":["trace[609850959] 'agreement among raft nodes before linearized reading' (duration: 342.178698ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:51:29.095448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:51:28.753181Z","time spent":"342.26429ms","remote":"127.0.0.1:43722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-31T18:51:29.095554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.036558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-01-31T18:51:29.095567Z","caller":"traceutil/trace.go:171","msg":"trace[558669071] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:931; }","duration":"307.073858ms","start":"2026-01-31T18:51:28.788489Z","end":"2026-01-31T18:51:29.095563Z","steps":["trace[558669071] 'agreement among raft nodes before linearized reading' (duration: 307.010367ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:51:29.095579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:51:28.788477Z","time spent":"307.099178ms","remote":"127.0.0.1:43722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-31T18:51:29.095356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.011346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T18:51:29.098317Z","caller":"traceutil/trace.go:171","msg":"trace[495472063] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:931; }","duration":"470.992282ms","start":"2026-01-31T18:51:28.627316Z","end":"2026-01-31T18:51:29.098308Z","steps":["trace[495472063] 'agreement among raft nodes before linearized reading' (duration: 468.025996ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:51:29.098339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:51:28.627302Z","time spent":"471.029512ms","remote":"127.0.0.1:43406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-01-31T18:51:30.42602Z","caller":"traceutil/trace.go:171","msg":"trace[278042227] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"459.108318ms","start":"2026-01-31T18:51:29.966894Z","end":"2026-01-31T18:51:30.426002Z","steps":["trace[278042227] 'process raft request' (duration: 458.994596ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:51:30.426129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:51:29.966871Z","time spent":"459.201311ms","remote":"127.0.0.1:43722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T18:51:33.588328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.466639ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-31T18:51:33.588418Z","caller":"traceutil/trace.go:171","msg":"trace[1875834286] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"412.021661ms","start":"2026-01-31T18:51:33.176379Z","end":"2026-01-31T18:51:33.5884Z","steps":["trace[1875834286] 'process raft request' (duration: 94.417949ms)","trace[1875834286] 'compare' (duration: 317.174432ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T18:51:33.588499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:51:33.176368Z","time spent":"412.102983ms","remote":"127.0.0.1:43722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T18:51:33.679995Z","caller":"traceutil/trace.go:171","msg":"trace[1088968775] linearizableReadLoop","detail":"{readStateIndex:991; appliedIndex:990; }","duration":"179.968851ms","start":"2026-01-31T18:51:33.50001Z","end":"2026-01-31T18:51:33.679978Z","steps":["trace[1088968775] 'read index received' (duration: 179.851828ms)","trace[1088968775] 'applied index is now lower than readState.Index' (duration: 116.503µs)"],"step_count":2} {"level":"warn","ts":"2026-01-31T18:51:33.680226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.210767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-01-31T18:51:33.680252Z","caller":"traceutil/trace.go:171","msg":"trace[2035323367] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:951; }","duration":"180.306218ms","start":"2026-01-31T18:51:33.499938Z","end":"2026-01-31T18:51:33.680244Z","steps":["trace[2035323367] 'agreement among raft nodes before linearized reading' (duration: 180.209206ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:51:33.680287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.507141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.70\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-01-31T18:51:33.680359Z","caller":"traceutil/trace.go:171","msg":"trace[964040133] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"501.994312ms","start":"2026-01-31T18:51:33.178359Z","end":"2026-01-31T18:51:33.680353Z","steps":["trace[964040133] 'process raft request' (duration: 501.547822ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:51:33.680355Z","caller":"traceutil/trace.go:171","msg":"trace[155308249] range","detail":"{range_begin:/registry/masterleases/162.253.55.70; range_end:; response_count:1; response_revision:951; }","duration":"134.610562ms","start":"2026-01-31T18:51:33.545726Z","end":"2026-01-31T18:51:33.680336Z","steps":["trace[155308249] 'agreement among raft nodes before linearized reading' (duration: 134.403638ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:51:33.680413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:51:33.178343Z","time spent":"502.032952ms","remote":"127.0.0.1:43722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T18:51:33.986243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.963164ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"info","ts":"2026-01-31T18:51:33.986457Z","caller":"traceutil/trace.go:171","msg":"trace[782809675] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"300.510102ms","start":"2026-01-31T18:51:33.68593Z","end":"2026-01-31T18:51:33.98644Z","steps":["trace[782809675] 'process raft request' (duration: 300.39967ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:51:33.986553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:51:33.685918Z","time spent":"300.579383ms","remote":"127.0.0.1:43722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T18:51:33.986733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:51:33.684156Z","time spent":"302.575478ms","remote":"127.0.0.1:43452","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-01-31T18:51:34.297157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.028625ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-31T18:51:34.297243Z","caller":"traceutil/trace.go:171","msg":"trace[199882044] linearizableReadLoop","detail":"{readStateIndex:994; appliedIndex:993; }","duration":"251.993013ms","start":"2026-01-31T18:51:34.045236Z","end":"2026-01-31T18:51:34.297229Z","steps":["trace[199882044] 'read index received' (duration: 137.740493ms)","trace[199882044] 'applied index is now lower than readState.Index' (duration: 114.25141ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T18:51:34.297334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.081755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-31T18:51:34.297365Z","caller":"traceutil/trace.go:171","msg":"trace[636053770] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:953; }","duration":"252.126936ms","start":"2026-01-31T18:51:34.045231Z","end":"2026-01-31T18:51:34.297358Z","steps":["trace[636053770] 'agreement among raft nodes before linearized reading' (duration: 252.038664ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:51:34.297581Z","caller":"traceutil/trace.go:171","msg":"trace[1387800134] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"307.843965ms","start":"2026-01-31T18:51:33.989725Z","end":"2026-01-31T18:51:34.297569Z","steps":["trace[1387800134] 'process raft request' (duration: 193.346789ms)","trace[1387800134] 'compare' (duration: 113.912623ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T18:51:34.297641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:51:33.989703Z","time spent":"307.907656ms","remote":"127.0.0.1:43452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T18:51:34.996163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.436448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-01-31T18:51:34.996224Z","caller":"traceutil/trace.go:171","msg":"trace[859895380] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:953; }","duration":"559.531611ms","start":"2026-01-31T18:51:34.43668Z","end":"2026-01-31T18:51:34.996212Z","steps":["trace[859895380] 'range keys from in-memory index tree' (duration: 559.314995ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:51:34.996262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:51:34.436667Z","time spent":"559.578522ms","remote":"127.0.0.1:43722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-01-31T18:51:34.996517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.976768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T18:51:34.996543Z","caller":"traceutil/trace.go:171","msg":"trace[1014030186] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:953; }","duration":"367.03322ms","start":"2026-01-31T18:51:34.629504Z","end":"2026-01-31T18:51:34.996537Z","steps":["trace[1014030186] 'range keys from in-memory index tree' (duration: 366.923397ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:51:34.996564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:51:34.629489Z","time spent":"367.070941ms","remote":"127.0.0.1:43406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-01-31T18:51:35.33275Z","caller":"traceutil/trace.go:171","msg":"trace[1815097647] linearizableReadLoop","detail":"{readStateIndex:995; appliedIndex:994; }","duration":"155.730372ms","start":"2026-01-31T18:51:35.176996Z","end":"2026-01-31T18:51:35.332726Z","steps":["trace[1815097647] 'read index received' (duration: 155.549689ms)","trace[1815097647] 'applied index is now lower than readState.Index' (duration: 179.533µs)"],"step_count":2} {"level":"info","ts":"2026-01-31T18:51:35.332873Z","caller":"traceutil/trace.go:171","msg":"trace[600780299] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"329.015755ms","start":"2026-01-31T18:51:35.003848Z","end":"2026-01-31T18:51:35.332864Z","steps":["trace[600780299] 'process raft request' (duration: 328.734509ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:51:35.332994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:51:35.003837Z","time spent":"329.066056ms","remote":"127.0.0.1:43722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T18:51:35.333337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.331916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-01-31T18:51:35.333402Z","caller":"traceutil/trace.go:171","msg":"trace[327225269] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:954; }","duration":"156.439229ms","start":"2026-01-31T18:51:35.176946Z","end":"2026-01-31T18:51:35.333385Z","steps":["trace[327225269] 'agreement among raft nodes before linearized reading' (duration: 156.276424ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:51:35.731888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.269055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-01-31T18:51:35.731944Z","caller":"traceutil/trace.go:171","msg":"trace[469473862] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:955; }","duration":"138.383777ms","start":"2026-01-31T18:51:35.593548Z","end":"2026-01-31T18:51:35.731932Z","steps":["trace[469473862] 'range keys from in-memory index tree' (duration: 138.162772ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:51:35.841812Z","caller":"traceutil/trace.go:171","msg":"trace[316040298] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"104.470933ms","start":"2026-01-31T18:51:35.737327Z","end":"2026-01-31T18:51:35.841797Z","steps":["trace[316040298] 'process raft request' (duration: 104.380661ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:51:36.129974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.547771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-01-31T18:51:36.130024Z","caller":"traceutil/trace.go:171","msg":"trace[1519989325] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:957; }","duration":"138.638963ms","start":"2026-01-31T18:51:35.991372Z","end":"2026-01-31T18:51:36.130011Z","steps":["trace[1519989325] 'range keys from in-memory index tree' (duration: 138.424469ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:51:36.345486Z","caller":"traceutil/trace.go:171","msg":"trace[894123930] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"210.534201ms","start":"2026-01-31T18:51:36.134932Z","end":"2026-01-31T18:51:36.345466Z","steps":["trace[894123930] 'process raft request' (duration: 210.425019ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:51:36.970684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.678527ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-31T18:51:36.970777Z","caller":"traceutil/trace.go:171","msg":"trace[330866274] linearizableReadLoop","detail":"{readStateIndex:1000; appliedIndex:999; }","duration":"341.114685ms","start":"2026-01-31T18:51:36.629646Z","end":"2026-01-31T18:51:36.970761Z","steps":["trace[330866274] 'read index received' (duration: 133.213903ms)","trace[330866274] 'applied index is now lower than readState.Index' (duration: 207.899582ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T18:51:36.970857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.221987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T18:51:36.970881Z","caller":"traceutil/trace.go:171","msg":"trace[264517569] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:959; }","duration":"341.280938ms","start":"2026-01-31T18:51:36.629592Z","end":"2026-01-31T18:51:36.970873Z","steps":["trace[264517569] 'agreement among raft nodes before linearized reading' (duration: 341.212997ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:51:36.970913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:51:36.629572Z","time spent":"341.335179ms","remote":"127.0.0.1:43406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-01-31T18:51:36.971208Z","caller":"traceutil/trace.go:171","msg":"trace[1624351079] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"647.077187ms","start":"2026-01-31T18:51:36.324118Z","end":"2026-01-31T18:51:36.971195Z","steps":["trace[1624351079] 'process raft request' (duration: 438.792337ms)","trace[1624351079] 'compare' (duration: 207.493533ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T18:51:36.971269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:51:36.324095Z","time spent":"647.140519ms","remote":"127.0.0.1:43722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T18:51:42.321746Z","caller":"traceutil/trace.go:171","msg":"trace[1799349337] linearizableReadLoop","detail":"{readStateIndex:1042; appliedIndex:1041; }","duration":"422.124216ms","start":"2026-01-31T18:51:41.899599Z","end":"2026-01-31T18:51:42.321723Z","steps":["trace[1799349337] 'read index received' (duration: 421.956873ms)","trace[1799349337] 'applied index is now lower than readState.Index' (duration: 166.473µs)"],"step_count":2} {"level":"info","ts":"2026-01-31T18:51:42.322178Z","caller":"traceutil/trace.go:171","msg":"trace[1524508310] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"469.407347ms","start":"2026-01-31T18:51:41.852757Z","end":"2026-01-31T18:51:42.322164Z","steps":["trace[1524508310] 'process raft request' (duration: 468.851505ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:51:42.322378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:51:41.852746Z","time spent":"469.524969ms","remote":"127.0.0.1:43722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T18:51:42.322717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.107479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T18:51:42.323658Z","caller":"traceutil/trace.go:171","msg":"trace[568481015] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1000; }","duration":"424.07151ms","start":"2026-01-31T18:51:41.89957Z","end":"2026-01-31T18:51:42.323641Z","steps":["trace[568481015] 'agreement among raft nodes before linearized reading' (duration: 423.105239ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:51:42.324184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:51:41.899556Z","time spent":"424.607152ms","remote":"127.0.0.1:43576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-01-31T18:51:42.32458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.047041ms","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-01-31T18:51:42.324641Z","caller":"traceutil/trace.go:171","msg":"trace[1334977433] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1000; }","duration":"251.134903ms","start":"2026-01-31T18:51:42.073499Z","end":"2026-01-31T18:51:42.324634Z","steps":["trace[1334977433] 'agreement among raft nodes before linearized reading' (duration: 250.9995ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:51:42.619123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.697742ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-31T18:51:42.625206Z","caller":"traceutil/trace.go:171","msg":"trace[766326269] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1000; }","duration":"108.813338ms","start":"2026-01-31T18:51:42.516372Z","end":"2026-01-31T18:51:42.625185Z","steps":["trace[766326269] 'range keys from in-memory index tree' (duration: 102.686972ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:51:42.625508Z","caller":"traceutil/trace.go:171","msg":"trace[1283260670] linearizableReadLoop","detail":"{readStateIndex:1043; appliedIndex:1042; }","duration":"233.278638ms","start":"2026-01-31T18:51:42.392218Z","end":"2026-01-31T18:51:42.625496Z","steps":["trace[1283260670] 'read index received' (duration: 233.122885ms)","trace[1283260670] 'applied index is now lower than readState.Index' (duration: 155.023µs)"],"step_count":2} {"level":"warn","ts":"2026-01-31T18:51:42.625651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.425951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-01-31T18:51:42.625677Z","caller":"traceutil/trace.go:171","msg":"trace[852522226] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1001; }","duration":"233.488592ms","start":"2026-01-31T18:51:42.39218Z","end":"2026-01-31T18:51:42.625669Z","steps":["trace[852522226] 'agreement among raft nodes before linearized reading' (duration: 233.355099ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:51:42.625975Z","caller":"traceutil/trace.go:171","msg":"trace[1711239072] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"297.829552ms","start":"2026-01-31T18:51:42.328132Z","end":"2026-01-31T18:51:42.625962Z","steps":["trace[1711239072] 'process raft request' (duration: 297.25107ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:51:50.79265Z","caller":"traceutil/trace.go:171","msg":"trace[1075952236] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"140.057664ms","start":"2026-01-31T18:51:50.652577Z","end":"2026-01-31T18:51:50.792635Z","steps":["trace[1075952236] 'process raft request' (duration: 139.679486ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:51:50.793203Z","caller":"traceutil/trace.go:171","msg":"trace[1021765486] linearizableReadLoop","detail":"{readStateIndex:1100; appliedIndex:1099; }","duration":"134.550422ms","start":"2026-01-31T18:51:50.657845Z","end":"2026-01-31T18:51:50.792395Z","steps":["trace[1021765486] 'read index received' (duration: 134.366728ms)","trace[1021765486] 'applied index is now lower than readState.Index' (duration: 180.734µs)"],"step_count":2} {"level":"warn","ts":"2026-01-31T18:51:50.793368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.503523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-01-31T18:51:50.793987Z","caller":"traceutil/trace.go:171","msg":"trace[21920607] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1056; }","duration":"136.145256ms","start":"2026-01-31T18:51:50.657826Z","end":"2026-01-31T18:51:50.793971Z","steps":["trace[21920607] 'agreement among raft nodes before linearized reading' (duration: 135.42525ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:54:58.863074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.090789ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"warn","ts":"2026-01-31T18:54:59.64709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.125646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-01-31T18:54:59.647164Z","caller":"traceutil/trace.go:171","msg":"trace[417130327] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1856; }","duration":"129.229019ms","start":"2026-01-31T18:54:59.517917Z","end":"2026-01-31T18:54:59.647146Z","steps":["trace[417130327] 'range keys from in-memory index tree' (duration: 128.909852ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:54:59.828065Z","caller":"traceutil/trace.go:171","msg":"trace[1357890211] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"174.09433ms","start":"2026-01-31T18:54:59.653948Z","end":"2026-01-31T18:54:59.828043Z","steps":["trace[1357890211] 'process raft request' (duration: 173.973118ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:55:00.034553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.38408ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-31T18:55:00.034691Z","caller":"traceutil/trace.go:171","msg":"trace[1219454954] linearizableReadLoop","detail":"{readStateIndex:1942; appliedIndex:1940; }","duration":"281.996792ms","start":"2026-01-31T18:54:59.75268Z","end":"2026-01-31T18:55:00.034676Z","steps":["trace[1219454954] 'read index received' (duration: 75.777254ms)","trace[1219454954] 'applied index is now lower than readState.Index' (duration: 206.218838ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T18:55:00.034835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.150785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-01-31T18:55:00.034866Z","caller":"traceutil/trace.go:171","msg":"trace[688732293] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1859; }","duration":"282.215887ms","start":"2026-01-31T18:54:59.752642Z","end":"2026-01-31T18:55:00.034858Z","steps":["trace[688732293] 'agreement among raft nodes before linearized reading' (duration: 282.082614ms)"],"step_count":1} {"level":"info","ts":"2026-01-31T18:55:00.03513Z","caller":"traceutil/trace.go:171","msg":"trace[1092590347] transaction","detail":"{read_only:false; response_revision:1858; number_of_response:1; }","duration":"378.41835ms","start":"2026-01-31T18:54:59.656696Z","end":"2026-01-31T18:55:00.035114Z","steps":["trace[1092590347] 'process raft request' (duration: 215.390545ms)","trace[1092590347] 'compare' (duration: 162.295978ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T18:55:00.035219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:54:59.656678Z","time spent":"378.508451ms","remote":"127.0.0.1:43722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-31T18:55:00.035348Z","caller":"traceutil/trace.go:171","msg":"trace[1630670448] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"378.513071ms","start":"2026-01-31T18:54:59.656824Z","end":"2026-01-31T18:55:00.035337Z","steps":["trace[1630670448] 'process raft request' (duration: 377.809575ms)"],"step_count":1} {"level":"warn","ts":"2026-01-31T18:55:00.035651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:54:59.656811Z","time spent":"378.650374ms","remote":"127.0.0.1:43722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-31T18:55:00.473049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.666882ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-31T18:55:00.473561Z","caller":"traceutil/trace.go:171","msg":"trace[396208014] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"431.394384ms","start":"2026-01-31T18:55:00.042149Z","end":"2026-01-31T18:55:00.473544Z","steps":["trace[396208014] 'process raft request' (duration: 326.1775ms)","trace[396208014] 'compare' (duration: 104.54829ms)"],"step_count":2} {"level":"warn","ts":"2026-01-31T18:55:00.474716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-31T18:55:00.042136Z","time spent":"431.567909ms","remote":"127.0.0.1:43722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":38,"request content":"compare: success:> failure: >"}