{"level":"warn","ts":"2026-03-19T13:30:32.239464Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-19T13:30:32.239585Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.162:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.162:2380","--initial-cluster=instance=https://199.19.213.162:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.162:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.162: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-03-19T13:30:32.239891Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-19T13:30:32.239917Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.162:2380"]} {"level":"info","ts":"2026-03-19T13:30:32.239945Z","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-03-19T13:30:32.240863Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.162:2379"]} {"level":"info","ts":"2026-03-19T13:30:32.24096Z","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":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.162:2380"],"listen-peer-urls":["https://199.19.213.162:2380"],"advertise-client-urls":["https://199.19.213.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.162: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-03-19T13:30:32.246038Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.771177ms"} {"level":"info","ts":"2026-03-19T13:30:32.252617Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"8c0a14645b8502c5","cluster-id":"49fe27222e1c6db4"} {"level":"info","ts":"2026-03-19T13:30:32.252711Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 switched to configuration voters=()"} {"level":"info","ts":"2026-03-19T13:30:32.252739Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 became follower at term 0"} {"level":"info","ts":"2026-03-19T13:30:32.252776Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8c0a14645b8502c5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-19T13:30:32.252788Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 became follower at term 1"} {"level":"info","ts":"2026-03-19T13:30:32.25284Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 switched to configuration voters=(10090900336341746373)"} {"level":"warn","ts":"2026-03-19T13:30:32.263093Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-19T13:30:32.265043Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-19T13:30:32.266268Z","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-03-19T13:30:32.269314Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"8c0a14645b8502c5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-19T13:30:32.269459Z","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-03-19T13:30:32.269569Z","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-03-19T13:30:32.269592Z","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-03-19T13:30:32.270125Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"8c0a14645b8502c5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-19T13:30:32.272979Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 switched to configuration voters=(10090900336341746373)"} {"level":"info","ts":"2026-03-19T13:30:32.273049Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"49fe27222e1c6db4","local-member-id":"8c0a14645b8502c5","added-peer-id":"8c0a14645b8502c5","added-peer-peer-urls":["https://199.19.213.162:2380"]} {"level":"info","ts":"2026-03-19T13:30:32.273726Z","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-03-19T13:30:32.273853Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.162:2380"} {"level":"info","ts":"2026-03-19T13:30:32.273885Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.162:2380"} {"level":"info","ts":"2026-03-19T13:30:32.274091Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"8c0a14645b8502c5","initial-advertise-peer-urls":["https://199.19.213.162:2380"],"listen-peer-urls":["https://199.19.213.162:2380"],"advertise-client-urls":["https://199.19.213.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-19T13:30:32.274135Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-19T13:30:32.85487Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 is starting a new election at term 1"} {"level":"info","ts":"2026-03-19T13:30:32.854947Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-19T13:30:32.85497Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 received MsgPreVoteResp from 8c0a14645b8502c5 at term 1"} {"level":"info","ts":"2026-03-19T13:30:32.854989Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 became candidate at term 2"} {"level":"info","ts":"2026-03-19T13:30:32.855Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 received MsgVoteResp from 8c0a14645b8502c5 at term 2"} {"level":"info","ts":"2026-03-19T13:30:32.855018Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 became leader at term 2"} {"level":"info","ts":"2026-03-19T13:30:32.855185Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8c0a14645b8502c5 elected leader 8c0a14645b8502c5 at term 2"} {"level":"info","ts":"2026-03-19T13:30:32.856217Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"8c0a14645b8502c5","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.162:2379]}","request-path":"/0/members/8c0a14645b8502c5/attributes","cluster-id":"49fe27222e1c6db4","publish-timeout":"7s"} {"level":"info","ts":"2026-03-19T13:30:32.856224Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-19T13:30:32.856252Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T13:30:32.85637Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-19T13:30:32.857401Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"49fe27222e1c6db4","local-member-id":"8c0a14645b8502c5","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T13:30:32.857521Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T13:30:32.857555Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T13:30:32.858473Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-19T13:30:32.863183Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-19T13:30:32.86327Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-19T13:30:32.86327Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.162:2379"} {"level":"warn","ts":"2026-03-19T13:30:34.524695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.805713ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-03-19T13:30:34.525089Z","caller":"traceutil/trace.go:171","msg":"trace[509167728] transaction","detail":"{read_only:false; response_revision:24; number_of_response:1; }","duration":"244.672655ms","start":"2026-03-19T13:30:34.280377Z","end":"2026-03-19T13:30:34.52505Z","steps":["trace[509167728] 'process raft request' (duration: 244.472068ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:30:34.525222Z","caller":"traceutil/trace.go:171","msg":"trace[2033353059] linearizableReadLoop","detail":"{readStateIndex:28; appliedIndex:26; }","duration":"243.89211ms","start":"2026-03-19T13:30:34.281308Z","end":"2026-03-19T13:30:34.5252Z","steps":["trace[2033353059] 'read index received' (duration: 44.975279ms)","trace[2033353059] 'applied index is now lower than readState.Index' (duration: 198.915831ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:30:34.525231Z","caller":"traceutil/trace.go:171","msg":"trace[1838199989] transaction","detail":"{read_only:false; response_revision:25; number_of_response:1; }","duration":"242.907266ms","start":"2026-03-19T13:30:34.282256Z","end":"2026-03-19T13:30:34.525163Z","steps":["trace[1838199989] 'process raft request' (duration: 242.667397ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:30:34.525333Z","caller":"traceutil/trace.go:171","msg":"trace[1171880277] transaction","detail":"{read_only:false; response_revision:26; number_of_response:1; }","duration":"242.869964ms","start":"2026-03-19T13:30:34.28245Z","end":"2026-03-19T13:30:34.52532Z","steps":["trace[1171880277] 'process raft request' (duration: 242.512591ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:30:34.525442Z","caller":"traceutil/trace.go:171","msg":"trace[13966027] transaction","detail":"{read_only:false; response_revision:29; number_of_response:1; }","duration":"242.860784ms","start":"2026-03-19T13:30:34.282562Z","end":"2026-03-19T13:30:34.525423Z","steps":["trace[13966027] 'process raft request' (duration: 242.596665ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:30:34.525468Z","caller":"traceutil/trace.go:171","msg":"trace[1705154928] transaction","detail":"{read_only:false; response_revision:23; number_of_response:1; }","duration":"265.664254ms","start":"2026-03-19T13:30:34.259785Z","end":"2026-03-19T13:30:34.525449Z","steps":["trace[1705154928] 'process raft request' (duration: 66.414651ms)","trace[1705154928] 'compare' (duration: 197.631947ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:30:34.525528Z","caller":"traceutil/trace.go:171","msg":"trace[1295467405] transaction","detail":"{read_only:false; response_revision:27; number_of_response:1; }","duration":"243.02397ms","start":"2026-03-19T13:30:34.282496Z","end":"2026-03-19T13:30:34.525519Z","steps":["trace[1295467405] 'process raft request' (duration: 242.496491ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:30:34.525605Z","caller":"traceutil/trace.go:171","msg":"trace[1787457141] transaction","detail":"{read_only:false; response_revision:28; number_of_response:1; }","duration":"243.073762ms","start":"2026-03-19T13:30:34.282524Z","end":"2026-03-19T13:30:34.525598Z","steps":["trace[1787457141] 'process raft request' (duration: 242.497801ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:30:34.52577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.684849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-19T13:30:34.525845Z","caller":"traceutil/trace.go:171","msg":"trace[1651406839] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:29; }","duration":"111.804502ms","start":"2026-03-19T13:30:34.414018Z","end":"2026-03-19T13:30:34.525823Z","steps":["trace[1651406839] 'agreement among raft nodes before linearized reading' (duration: 111.648067ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:30:34.525848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.516821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-19T13:30:34.5259Z","caller":"traceutil/trace.go:171","msg":"trace[1571290904] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:0; response_revision:29; }","duration":"244.607734ms","start":"2026-03-19T13:30:34.281275Z","end":"2026-03-19T13:30:34.525883Z","steps":["trace[1571290904] 'agreement among raft nodes before linearized reading' (duration: 244.357536ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:30:34.815949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.696767ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-03-19T13:30:34.81604Z","caller":"traceutil/trace.go:171","msg":"trace[105651586] transaction","detail":"{read_only:false; response_revision:30; number_of_response:1; }","duration":"287.337655ms","start":"2026-03-19T13:30:34.52869Z","end":"2026-03-19T13:30:34.816028Z","steps":["trace[105651586] 'process raft request' (duration: 154.511483ms)","trace[105651586] 'compare' (duration: 132.555553ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:30:34.823184Z","caller":"traceutil/trace.go:171","msg":"trace[1350978096] transaction","detail":"{read_only:false; response_revision:31; number_of_response:1; }","duration":"293.757205ms","start":"2026-03-19T13:30:34.529407Z","end":"2026-03-19T13:30:34.823164Z","steps":["trace[1350978096] 'process raft request' (duration: 293.398113ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:30:34.823232Z","caller":"traceutil/trace.go:171","msg":"trace[1486756656] linearizableReadLoop","detail":"{readStateIndex:36; appliedIndex:33; }","duration":"293.515298ms","start":"2026-03-19T13:30:34.529685Z","end":"2026-03-19T13:30:34.8232Z","steps":["trace[1486756656] 'read index received' (duration: 153.609393ms)","trace[1486756656] 'applied index is now lower than readState.Index' (duration: 139.904434ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:30:34.823313Z","caller":"traceutil/trace.go:171","msg":"trace[605662278] transaction","detail":"{read_only:false; response_revision:34; number_of_response:1; }","duration":"293.436024ms","start":"2026-03-19T13:30:34.529784Z","end":"2026-03-19T13:30:34.82322Z","steps":["trace[605662278] 'process raft request' (duration: 293.279459ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:30:34.82343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.713434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-node-lease/\" range_end:\"/registry/resourcequotas/kube-node-lease0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-19T13:30:34.823471Z","caller":"traceutil/trace.go:171","msg":"trace[969685954] transaction","detail":"{read_only:false; response_revision:32; number_of_response:1; }","duration":"293.88704ms","start":"2026-03-19T13:30:34.529554Z","end":"2026-03-19T13:30:34.823441Z","steps":["trace[969685954] 'process raft request' (duration: 293.412664ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:30:34.82353Z","caller":"traceutil/trace.go:171","msg":"trace[280329565] transaction","detail":"{read_only:false; response_revision:35; number_of_response:1; }","duration":"293.385613ms","start":"2026-03-19T13:30:34.530135Z","end":"2026-03-19T13:30:34.823521Z","steps":["trace[280329565] 'process raft request' (duration: 292.960449ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:30:34.823394Z","caller":"traceutil/trace.go:171","msg":"trace[101206533] transaction","detail":"{read_only:false; response_revision:33; number_of_response:1; }","duration":"293.661832ms","start":"2026-03-19T13:30:34.529706Z","end":"2026-03-19T13:30:34.823368Z","steps":["trace[101206533] 'process raft request' (duration: 293.31541ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:30:34.82362Z","caller":"traceutil/trace.go:171","msg":"trace[1980169579] transaction","detail":"{read_only:false; response_revision:36; number_of_response:1; }","duration":"293.353732ms","start":"2026-03-19T13:30:34.530246Z","end":"2026-03-19T13:30:34.823599Z","steps":["trace[1980169579] 'process raft request' (duration: 292.887046ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:30:34.823488Z","caller":"traceutil/trace.go:171","msg":"trace[672400882] range","detail":"{range_begin:/registry/resourcequotas/kube-node-lease/; range_end:/registry/resourcequotas/kube-node-lease0; response_count:0; response_revision:37; }","duration":"293.822818ms","start":"2026-03-19T13:30:34.52965Z","end":"2026-03-19T13:30:34.823473Z","steps":["trace[672400882] 'agreement among raft nodes before linearized reading' (duration: 293.661182ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:30:34.82382Z","caller":"traceutil/trace.go:171","msg":"trace[66937788] transaction","detail":"{read_only:false; response_revision:37; number_of_response:1; }","duration":"293.477686ms","start":"2026-03-19T13:30:34.530335Z","end":"2026-03-19T13:30:34.823812Z","steps":["trace[66937788] 'process raft request' (duration: 292.826274ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:30:59.826328Z","caller":"traceutil/trace.go:171","msg":"trace[1404303791] linearizableReadLoop","detail":"{readStateIndex:446; appliedIndex:445; }","duration":"275.300881ms","start":"2026-03-19T13:30:59.551Z","end":"2026-03-19T13:30:59.826301Z","steps":["trace[1404303791] 'read index received' (duration: 275.080435ms)","trace[1404303791] 'applied index is now lower than readState.Index' (duration: 219.546µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:30:59.826465Z","caller":"traceutil/trace.go:171","msg":"trace[228591515] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"277.642951ms","start":"2026-03-19T13:30:59.548765Z","end":"2026-03-19T13:30:59.826408Z","steps":["trace[228591515] 'process raft request' (duration: 277.382804ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:30:59.826517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.482675ms","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-03-19T13:30:59.826551Z","caller":"traceutil/trace.go:171","msg":"trace[1459396961] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:428; }","duration":"275.565627ms","start":"2026-03-19T13:30:59.550976Z","end":"2026-03-19T13:30:59.826542Z","steps":["trace[1459396961] 'agreement among raft nodes before linearized reading' (duration: 275.467665ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:30:59.826699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.827449ms","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-03-19T13:30:59.82674Z","caller":"traceutil/trace.go:171","msg":"trace[390340510] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:428; }","duration":"274.896601ms","start":"2026-03-19T13:30:59.551831Z","end":"2026-03-19T13:30:59.826728Z","steps":["trace[390340510] 'agreement among raft nodes before linearized reading' (duration: 274.825439ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:31:00.783324Z","caller":"traceutil/trace.go:171","msg":"trace[383048684] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"946.47648ms","start":"2026-03-19T13:30:59.836804Z","end":"2026-03-19T13:31:00.78328Z","steps":["trace[383048684] 'process raft request' (duration: 905.803695ms)","trace[383048684] 'compare' (duration: 40.365708ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:31:00.784169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:30:59.836781Z","time spent":"946.648355ms","remote":"127.0.0.1:49098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T13:31:00.857706Z","caller":"traceutil/trace.go:171","msg":"trace[276144163] linearizableReadLoop","detail":"{readStateIndex:448; appliedIndex:446; }","duration":"501.595593ms","start":"2026-03-19T13:31:00.356085Z","end":"2026-03-19T13:31:00.857681Z","steps":["trace[276144163] 'read index received' (duration: 386.578436ms)","trace[276144163] 'applied index is now lower than readState.Index' (duration: 115.016147ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:31:00.857896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.789969ms","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-03-19T13:31:00.85794Z","caller":"traceutil/trace.go:171","msg":"trace[276298798] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:430; }","duration":"501.908611ms","start":"2026-03-19T13:31:00.356019Z","end":"2026-03-19T13:31:00.857928Z","steps":["trace[276298798] 'agreement among raft nodes before linearized reading' (duration: 501.788208ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:31:00.857993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:31:00.35599Z","time spent":"501.982323ms","remote":"127.0.0.1:49098","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":"info","ts":"2026-03-19T13:31:00.857949Z","caller":"traceutil/trace.go:171","msg":"trace[1389784242] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"1.020715149s","start":"2026-03-19T13:30:59.837134Z","end":"2026-03-19T13:31:00.857849Z","steps":["trace[1389784242] 'process raft request' (duration: 1.020419922s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:31:00.858319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.640041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-19T13:31:00.858389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:30:59.837128Z","time spent":"1.021027878s","remote":"127.0.0.1:49098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T13:31:00.858452Z","caller":"traceutil/trace.go:171","msg":"trace[671144218] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:430; }","duration":"469.771014ms","start":"2026-03-19T13:31:00.388609Z","end":"2026-03-19T13:31:00.85838Z","steps":["trace[671144218] 'agreement among raft nodes before linearized reading' (duration: 469.291951ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:31:00.858564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:31:00.388583Z","time spent":"469.963449ms","remote":"127.0.0.1:48826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-19T13:31:05.952207Z","caller":"traceutil/trace.go:171","msg":"trace[894786893] linearizableReadLoop","detail":"{readStateIndex:494; appliedIndex:493; }","duration":"124.206777ms","start":"2026-03-19T13:31:05.827974Z","end":"2026-03-19T13:31:05.952181Z","steps":["trace[894786893] 'read index received' (duration: 124.021843ms)","trace[894786893] 'applied index is now lower than readState.Index' (duration: 183.984µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:31:05.952327Z","caller":"traceutil/trace.go:171","msg":"trace[211338598] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"284.837835ms","start":"2026-03-19T13:31:05.667479Z","end":"2026-03-19T13:31:05.952317Z","steps":["trace[211338598] 'process raft request' (duration: 284.565968ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:31:05.952553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.561336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.162\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-19T13:31:05.952634Z","caller":"traceutil/trace.go:171","msg":"trace[224790351] range","detail":"{range_begin:/registry/masterleases/199.19.213.162; range_end:; response_count:1; response_revision:475; }","duration":"124.684599ms","start":"2026-03-19T13:31:05.82793Z","end":"2026-03-19T13:31:05.952615Z","steps":["trace[224790351] 'agreement among raft nodes before linearized reading' (duration: 124.577386ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:31:06.216764Z","caller":"traceutil/trace.go:171","msg":"trace[1609364199] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"260.106996ms","start":"2026-03-19T13:31:05.956634Z","end":"2026-03-19T13:31:06.216741Z","steps":["trace[1609364199] 'process raft request' (duration: 259.971882ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:31:07.994802Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":199738408831402027,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-19T13:31:08.49551Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":199738408831402027,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-19T13:31:08.927585Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.014569894s","expected-duration":"1s"} {"level":"info","ts":"2026-03-19T13:31:08.927969Z","caller":"traceutil/trace.go:171","msg":"trace[42717734] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"2.014971723s","start":"2026-03-19T13:31:06.912962Z","end":"2026-03-19T13:31:08.927933Z","steps":["trace[42717734] 'process raft request' (duration: 2.014826829s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:31:08.928155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:31:06.912954Z","time spent":"2.015115476s","remote":"127.0.0.1:49098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T13:31:08.983878Z","caller":"traceutil/trace.go:171","msg":"trace[276667281] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"2.069772155s","start":"2026-03-19T13:31:06.914072Z","end":"2026-03-19T13:31:08.983844Z","steps":["trace[276667281] 'process raft request' (duration: 2.051553519s)","trace[276667281] 'compare' (duration: 17.893969ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:31:08.984085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:31:06.914057Z","time spent":"2.069928408s","remote":"127.0.0.1:49098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T13:31:08.984188Z","caller":"traceutil/trace.go:171","msg":"trace[846385330] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"2.068868612s","start":"2026-03-19T13:31:06.915295Z","end":"2026-03-19T13:31:08.984163Z","steps":["trace[846385330] 'process raft request' (duration: 2.068484734s)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:31:08.984248Z","caller":"traceutil/trace.go:171","msg":"trace[1921950823] linearizableReadLoop","detail":"{readStateIndex:501; appliedIndex:497; }","duration":"1.515198873s","start":"2026-03-19T13:31:07.469029Z","end":"2026-03-19T13:31:08.984228Z","steps":["trace[1921950823] 'read index received' (duration: 1.458879115s)","trace[1921950823] 'applied index is now lower than readState.Index' (duration: 56.319098ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:31:08.984297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:31:06.915288Z","time spent":"2.068958534s","remote":"127.0.0.1:49098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T13:31:08.984343Z","caller":"traceutil/trace.go:171","msg":"trace[1272094840] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"1.243945119s","start":"2026-03-19T13:31:07.74039Z","end":"2026-03-19T13:31:08.984336Z","steps":["trace[1272094840] 'process raft request' (duration: 1.243609132s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:31:08.984435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:31:07.740362Z","time spent":"1.244005731s","remote":"127.0.0.1:49098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T13:31:08.98446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.515416158s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-19T13:31:08.984476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.749983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T13:31:08.984605Z","caller":"traceutil/trace.go:171","msg":"trace[1433385236] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:481; }","duration":"599.915767ms","start":"2026-03-19T13:31:08.384668Z","end":"2026-03-19T13:31:08.984584Z","steps":["trace[1433385236] 'agreement among raft nodes before linearized reading' (duration: 599.732393ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:31:08.984521Z","caller":"traceutil/trace.go:171","msg":"trace[1804986267] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:0; response_revision:481; }","duration":"1.51550037s","start":"2026-03-19T13:31:07.469002Z","end":"2026-03-19T13:31:08.984502Z","steps":["trace[1804986267] 'agreement among raft nodes before linearized reading' (duration: 1.515379297s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:31:08.984652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:31:08.384649Z","time spent":"599.993538ms","remote":"127.0.0.1:48826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-19T13:31:08.984685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:31:07.468986Z","time spent":"1.515673354s","remote":"127.0.0.1:48910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" "} {"level":"warn","ts":"2026-03-19T13:31:08.984535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"763.151697ms","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-03-19T13:31:08.984833Z","caller":"traceutil/trace.go:171","msg":"trace[535484070] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:481; }","duration":"763.479773ms","start":"2026-03-19T13:31:08.221338Z","end":"2026-03-19T13:31:08.984817Z","steps":["trace[535484070] 'agreement among raft nodes before linearized reading' (duration: 763.053664ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:31:08.984886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:31:08.221322Z","time spent":"763.547866ms","remote":"127.0.0.1:49098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-19T13:31:13.205947Z","caller":"traceutil/trace.go:171","msg":"trace[2026257543] linearizableReadLoop","detail":"{readStateIndex:533; appliedIndex:532; }","duration":"208.483419ms","start":"2026-03-19T13:31:12.997445Z","end":"2026-03-19T13:31:13.205928Z","steps":["trace[2026257543] 'read index received' (duration: 208.411147ms)","trace[2026257543] 'applied index is now lower than readState.Index' (duration: 71.542µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:31:13.20627Z","caller":"traceutil/trace.go:171","msg":"trace[1852721407] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"261.152923ms","start":"2026-03-19T13:31:12.945065Z","end":"2026-03-19T13:31:13.206218Z","steps":["trace[1852721407] 'process raft request' (duration: 260.758464ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:31:13.206372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.990908ms","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-03-19T13:31:13.206269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.356761ms","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-03-19T13:31:13.206488Z","caller":"traceutil/trace.go:171","msg":"trace[448425614] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:512; }","duration":"202.620927ms","start":"2026-03-19T13:31:13.003847Z","end":"2026-03-19T13:31:13.206468Z","steps":["trace[448425614] 'agreement among raft nodes before linearized reading' (duration: 202.250589ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:31:13.206518Z","caller":"traceutil/trace.go:171","msg":"trace[317598746] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:512; }","duration":"200.164172ms","start":"2026-03-19T13:31:13.006325Z","end":"2026-03-19T13:31:13.20649Z","steps":["trace[317598746] 'agreement among raft nodes before linearized reading' (duration: 199.785534ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:31:13.206858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.391049ms","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-03-19T13:31:13.206902Z","caller":"traceutil/trace.go:171","msg":"trace[2112375815] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:512; }","duration":"209.494172ms","start":"2026-03-19T13:31:12.997399Z","end":"2026-03-19T13:31:13.206893Z","steps":["trace[2112375815] 'agreement among raft nodes before linearized reading' (duration: 208.660862ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:31:14.489535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.223997969s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T13:31:14.489904Z","caller":"traceutil/trace.go:171","msg":"trace[857759984] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"1.280216489s","start":"2026-03-19T13:31:13.209641Z","end":"2026-03-19T13:31:14.489858Z","steps":["trace[857759984] 'process raft request' (duration: 55.664797ms)","trace[857759984] 'compare' (duration: 1.22357367s)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:31:14.489949Z","caller":"traceutil/trace.go:171","msg":"trace[1718070177] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"1.279255599s","start":"2026-03-19T13:31:13.210668Z","end":"2026-03-19T13:31:14.489924Z","steps":["trace[1718070177] 'process raft request' (duration: 1.279132676s)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:31:14.48998Z","caller":"traceutil/trace.go:171","msg":"trace[1740813444] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"1.279164037s","start":"2026-03-19T13:31:13.210791Z","end":"2026-03-19T13:31:14.489955Z","steps":["trace[1740813444] 'process raft request' (duration: 1.279064154s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:31:14.490052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:31:13.210661Z","time spent":"1.279344681s","remote":"127.0.0.1:49098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T13:31:14.490132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:31:13.209623Z","time spent":"1.280355833s","remote":"127.0.0.1:49098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T13:31:14.490104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:31:13.210767Z","time spent":"1.279269189s","remote":"127.0.0.1:49098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T13:31:14.490218Z","caller":"traceutil/trace.go:171","msg":"trace[524252607] linearizableReadLoop","detail":"{readStateIndex:536; appliedIndex:533; }","duration":"1.027964479s","start":"2026-03-19T13:31:13.462242Z","end":"2026-03-19T13:31:14.490206Z","steps":["trace[524252607] 'read index received' (duration: 79.761664ms)","trace[524252607] 'applied index is now lower than readState.Index' (duration: 948.199665ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:31:14.490816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.028567271s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-q2x9w\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-03-19T13:31:14.490931Z","caller":"traceutil/trace.go:171","msg":"trace[1465397561] range","detail":"{range_begin:/registry/pods/kube-system/cilium-q2x9w; range_end:; response_count:1; response_revision:515; }","duration":"1.028721296s","start":"2026-03-19T13:31:13.462159Z","end":"2026-03-19T13:31:14.490881Z","steps":["trace[1465397561] 'agreement among raft nodes before linearized reading' (duration: 1.028082682s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:31:14.490975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:31:13.462143Z","time spent":"1.028822937s","remote":"127.0.0.1:49026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-q2x9w\" "} {"level":"info","ts":"2026-03-19T13:31:15.019543Z","caller":"traceutil/trace.go:171","msg":"trace[523810696] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"513.449395ms","start":"2026-03-19T13:31:14.50605Z","end":"2026-03-19T13:31:15.019499Z","steps":["trace[523810696] 'process raft request' (duration: 513.303692ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:31:15.019689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:31:14.506027Z","time spent":"513.589568ms","remote":"127.0.0.1:49026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T13:31:16.88617Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":199738408831402102,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-19T13:31:17.387283Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":199738408831402102,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-19T13:31:17.804835Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.734126976s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-19T13:31:17.805248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.594409132s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-03-19T13:31:17.805393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.976745754s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.162\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-19T13:31:17.80541Z","caller":"traceutil/trace.go:171","msg":"trace[797894721] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:516; }","duration":"2.594621337s","start":"2026-03-19T13:31:15.210764Z","end":"2026-03-19T13:31:17.805385Z","steps":["trace[797894721] 'range keys from in-memory index tree' (duration: 2.594198868s)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:31:17.805451Z","caller":"traceutil/trace.go:171","msg":"trace[1163109336] range","detail":"{range_begin:/registry/masterleases/199.19.213.162; range_end:; response_count:1; response_revision:516; }","duration":"1.976860786s","start":"2026-03-19T13:31:15.828573Z","end":"2026-03-19T13:31:17.805434Z","steps":["trace[1163109336] 'range keys from in-memory index tree' (duration: 1.976640571s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:31:17.805472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:31:15.210743Z","time spent":"2.59471336s","remote":"127.0.0.1:49098","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-03-19T13:31:17.805493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:31:15.828546Z","time spent":"1.976937588s","remote":"127.0.0.1:48872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.162\" "} {"level":"info","ts":"2026-03-19T13:31:17.805486Z","caller":"traceutil/trace.go:171","msg":"trace[1652677154] linearizableReadLoop","detail":"{readStateIndex:538; appliedIndex:537; }","duration":"1.419629536s","start":"2026-03-19T13:31:16.385839Z","end":"2026-03-19T13:31:17.805469Z","steps":["trace[1652677154] 'read index received' (duration: 1.419113835s)","trace[1652677154] 'applied index is now lower than readState.Index' (duration: 514.761µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:31:17.805685Z","caller":"traceutil/trace.go:171","msg":"trace[1119872375] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"1.735108287s","start":"2026-03-19T13:31:16.070567Z","end":"2026-03-19T13:31:17.805675Z","steps":["trace[1119872375] 'process raft request' (duration: 1.734435003s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:31:17.805771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:31:16.070533Z","time spent":"1.735176899s","remote":"127.0.0.1:49098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T13:31:17.805883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.311678334s","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-03-19T13:31:17.805933Z","caller":"traceutil/trace.go:171","msg":"trace[1041943447] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:517; }","duration":"1.311758246s","start":"2026-03-19T13:31:16.494163Z","end":"2026-03-19T13:31:17.805921Z","steps":["trace[1041943447] 'agreement among raft nodes before linearized reading' (duration: 1.311631564s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:31:17.80595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.311299077s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-03-19T13:31:17.805967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:31:16.494145Z","time spent":"1.311815737s","remote":"127.0.0.1:49098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-19T13:31:17.805989Z","caller":"traceutil/trace.go:171","msg":"trace[1831623710] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:517; }","duration":"1.311345468s","start":"2026-03-19T13:31:16.494632Z","end":"2026-03-19T13:31:17.805978Z","steps":["trace[1831623710] 'agreement among raft nodes before linearized reading' (duration: 1.311183494s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:31:17.806032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:31:16.494629Z","time spent":"1.311396889s","remote":"127.0.0.1:49098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-19T13:31:17.806073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.311445319s","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-03-19T13:31:17.806107Z","caller":"traceutil/trace.go:171","msg":"trace[1368227419] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:517; }","duration":"1.31149152s","start":"2026-03-19T13:31:16.494605Z","end":"2026-03-19T13:31:17.806097Z","steps":["trace[1368227419] 'agreement among raft nodes before linearized reading' (duration: 1.311396808s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:31:17.806124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.420294209s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-19T13:31:17.806136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:31:16.494599Z","time spent":"1.311530481s","remote":"127.0.0.1:49098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-19T13:31:17.806155Z","caller":"traceutil/trace.go:171","msg":"trace[703076463] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:517; }","duration":"1.420376752s","start":"2026-03-19T13:31:16.385764Z","end":"2026-03-19T13:31:17.806141Z","steps":["trace[703076463] 'agreement among raft nodes before linearized reading' (duration: 1.420334881s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:31:17.80618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:31:16.385714Z","time spent":"1.420461574s","remote":"127.0.0.1:48826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-19T13:31:20.660587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.178334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T13:31:20.660657Z","caller":"traceutil/trace.go:171","msg":"trace[2057351190] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:531; }","duration":"272.285385ms","start":"2026-03-19T13:31:20.388357Z","end":"2026-03-19T13:31:20.660642Z","steps":["trace[2057351190] 'range keys from in-memory index tree' (duration: 272.076991ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:31:20.660676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"838.475233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-03-19T13:31:20.660751Z","caller":"traceutil/trace.go:171","msg":"trace[502925149] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:531; }","duration":"838.587585ms","start":"2026-03-19T13:31:19.822147Z","end":"2026-03-19T13:31:20.660734Z","steps":["trace[502925149] 'range keys from in-memory index tree' (duration: 838.30061ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:31:20.660789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:31:19.82213Z","time spent":"838.650397ms","remote":"127.0.0.1:49098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-19T13:31:20.660981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.539535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-q2x9w\" ","response":"range_response_count:1 size:17524"} {"level":"info","ts":"2026-03-19T13:31:20.661017Z","caller":"traceutil/trace.go:171","msg":"trace[1113889699] range","detail":"{range_begin:/registry/pods/kube-system/cilium-q2x9w; range_end:; response_count:1; response_revision:531; }","duration":"178.603996ms","start":"2026-03-19T13:31:20.4824Z","end":"2026-03-19T13:31:20.661004Z","steps":["trace[1113889699] 'range keys from in-memory index tree' (duration: 178.383042ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:31:20.661016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"838.80227ms","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-03-19T13:31:20.661048Z","caller":"traceutil/trace.go:171","msg":"trace[248629810] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:531; }","duration":"838.861911ms","start":"2026-03-19T13:31:19.822177Z","end":"2026-03-19T13:31:20.661039Z","steps":["trace[248629810] 'range keys from in-memory index tree' (duration: 838.684768ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:31:20.661073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:31:19.822159Z","time spent":"838.908803ms","remote":"127.0.0.1:49098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-19T13:31:20.661334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"839.054456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-19T13:31:20.661359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"839.082307ms","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-03-19T13:31:20.661385Z","caller":"traceutil/trace.go:171","msg":"trace[885946659] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:531; }","duration":"839.147358ms","start":"2026-03-19T13:31:19.822225Z","end":"2026-03-19T13:31:20.661372Z","steps":["trace[885946659] 'range keys from in-memory index tree' (duration: 838.915463ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:31:20.661398Z","caller":"traceutil/trace.go:171","msg":"trace[543004394] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:531; }","duration":"839.152228ms","start":"2026-03-19T13:31:19.822236Z","end":"2026-03-19T13:31:20.661388Z","steps":["trace[543004394] 'range keys from in-memory index tree' (duration: 838.993485ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:31:20.661408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:31:19.822201Z","time spent":"839.202489ms","remote":"127.0.0.1:49098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-19T13:31:20.661425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:31:19.82222Z","time spent":"839.198359ms","remote":"127.0.0.1:49098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-19T13:31:20.775518Z","caller":"traceutil/trace.go:171","msg":"trace[833186786] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"101.038149ms","start":"2026-03-19T13:31:20.674455Z","end":"2026-03-19T13:31:20.775493Z","steps":["trace[833186786] 'process raft request' (duration: 100.981648ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:31:20.775775Z","caller":"traceutil/trace.go:171","msg":"trace[513873389] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"110.092027ms","start":"2026-03-19T13:31:20.665673Z","end":"2026-03-19T13:31:20.775765Z","steps":["trace[513873389] 'process raft request' (duration: 108.144297ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:31:20.775996Z","caller":"traceutil/trace.go:171","msg":"trace[592753302] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"110.055856ms","start":"2026-03-19T13:31:20.66593Z","end":"2026-03-19T13:31:20.775986Z","steps":["trace[592753302] 'process raft request' (duration: 109.348231ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:31:20.776085Z","caller":"traceutil/trace.go:171","msg":"trace[74357055] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"109.584897ms","start":"2026-03-19T13:31:20.666492Z","end":"2026-03-19T13:31:20.776077Z","steps":["trace[74357055] 'process raft request' (duration: 108.887302ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:31:36.049369Z","caller":"traceutil/trace.go:171","msg":"trace[1073051377] linearizableReadLoop","detail":"{readStateIndex:735; appliedIndex:734; }","duration":"219.139984ms","start":"2026-03-19T13:31:35.83021Z","end":"2026-03-19T13:31:36.04935Z","steps":["trace[1073051377] 'read index received' (duration: 219.017802ms)","trace[1073051377] 'applied index is now lower than readState.Index' (duration: 121.522µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:31:36.049416Z","caller":"traceutil/trace.go:171","msg":"trace[1577194325] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"391.834842ms","start":"2026-03-19T13:31:35.657549Z","end":"2026-03-19T13:31:36.049384Z","steps":["trace[1577194325] 'process raft request' (duration: 391.68461ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:31:36.049563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.341237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.162\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-03-19T13:31:36.04957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:31:35.657532Z","time spent":"391.973935ms","remote":"127.0.0.1:48902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":811,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-19T13:31:36.049592Z","caller":"traceutil/trace.go:171","msg":"trace[1505161469] range","detail":"{range_begin:/registry/masterleases/199.19.213.162; range_end:; response_count:1; response_revision:709; }","duration":"219.419419ms","start":"2026-03-19T13:31:35.830166Z","end":"2026-03-19T13:31:36.049585Z","steps":["trace[1505161469] 'agreement among raft nodes before linearized reading' (duration: 219.295377ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:31:36.049639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.407268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-19T13:31:36.049716Z","caller":"traceutil/trace.go:171","msg":"trace[393219406] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:709; }","duration":"171.531811ms","start":"2026-03-19T13:31:35.878165Z","end":"2026-03-19T13:31:36.049697Z","steps":["trace[393219406] 'agreement among raft nodes before linearized reading' (duration: 171.361578ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:31:38.532917Z","caller":"traceutil/trace.go:171","msg":"trace[730038732] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"384.095512ms","start":"2026-03-19T13:31:38.148793Z","end":"2026-03-19T13:31:38.532889Z","steps":["trace[730038732] 'process raft request' (duration: 383.904749ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:31:38.53309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:31:38.148763Z","time spent":"384.233855ms","remote":"127.0.0.1:49098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T13:32:41.392195Z","caller":"traceutil/trace.go:171","msg":"trace[1429673300] linearizableReadLoop","detail":"{readStateIndex:970; appliedIndex:969; }","duration":"140.585151ms","start":"2026-03-19T13:32:41.251596Z","end":"2026-03-19T13:32:41.392181Z","steps":["trace[1429673300] 'read index received' (duration: 140.45711ms)","trace[1429673300] 'applied index is now lower than readState.Index' (duration: 127.091µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:32:41.392303Z","caller":"traceutil/trace.go:171","msg":"trace[1148174509] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"149.535846ms","start":"2026-03-19T13:32:41.242758Z","end":"2026-03-19T13:32:41.392294Z","steps":["trace[1148174509] 'process raft request' (duration: 149.297443ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:32:41.392456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.839294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-19T13:32:41.392485Z","caller":"traceutil/trace.go:171","msg":"trace[911871912] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:929; }","duration":"140.935695ms","start":"2026-03-19T13:32:41.251543Z","end":"2026-03-19T13:32:41.392478Z","steps":["trace[911871912] 'agreement among raft nodes before linearized reading' (duration: 140.840194ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:32:41.837709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.054199ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T13:32:41.837835Z","caller":"traceutil/trace.go:171","msg":"trace[760484210] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"442.020901ms","start":"2026-03-19T13:32:41.395792Z","end":"2026-03-19T13:32:41.837813Z","steps":["trace[760484210] 'process raft request' (duration: 328.77794ms)","trace[760484210] 'compare' (duration: 112.891967ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:32:41.838005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:32:41.395772Z","time spent":"442.101992ms","remote":"127.0.0.1:49098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T13:32:41.843228Z","caller":"traceutil/trace.go:171","msg":"trace[1151362254] linearizableReadLoop","detail":"{readStateIndex:973; appliedIndex:970; }","duration":"403.934285ms","start":"2026-03-19T13:32:41.438815Z","end":"2026-03-19T13:32:41.84275Z","steps":["trace[1151362254] 'read index received' (duration: 285.771926ms)","trace[1151362254] 'applied index is now lower than readState.Index' (duration: 118.161419ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:32:41.843295Z","caller":"traceutil/trace.go:171","msg":"trace[541214547] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"446.633365ms","start":"2026-03-19T13:32:41.396151Z","end":"2026-03-19T13:32:41.842785Z","steps":["trace[541214547] 'process raft request' (duration: 446.435992ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:32:41.843385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:32:41.396139Z","time spent":"447.197071ms","remote":"127.0.0.1:49098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T13:32:41.843514Z","caller":"traceutil/trace.go:171","msg":"trace[987710983] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"446.822667ms","start":"2026-03-19T13:32:41.396659Z","end":"2026-03-19T13:32:41.843482Z","steps":["trace[987710983] 'process raft request' (duration: 446.049748ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:32:41.843676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:32:41.396647Z","time spent":"446.949068ms","remote":"127.0.0.1:49098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T13:32:41.843966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.07618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-19T13:32:41.844132Z","caller":"traceutil/trace.go:171","msg":"trace[1144342855] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:932; }","duration":"304.273792ms","start":"2026-03-19T13:32:41.539787Z","end":"2026-03-19T13:32:41.844061Z","steps":["trace[1144342855] 'agreement among raft nodes before linearized reading' (duration: 303.695626ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:32:41.844274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:32:41.539768Z","time spent":"304.459264ms","remote":"127.0.0.1:49098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":7,"response size":30,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-03-19T13:32:41.847255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.427399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-19T13:32:41.847467Z","caller":"traceutil/trace.go:171","msg":"trace[1254919105] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:932; }","duration":"408.679281ms","start":"2026-03-19T13:32:41.43877Z","end":"2026-03-19T13:32:41.847449Z","steps":["trace[1254919105] 'agreement among raft nodes before linearized reading' (duration: 407.76721ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:32:41.847567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:32:41.43875Z","time spent":"408.804913ms","remote":"127.0.0.1:49098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-19T13:32:49.262703Z","caller":"traceutil/trace.go:171","msg":"trace[2140493489] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"106.758722ms","start":"2026-03-19T13:32:49.155922Z","end":"2026-03-19T13:32:49.26268Z","steps":["trace[2140493489] 'process raft request' (duration: 106.62223ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:32:49.619636Z","caller":"traceutil/trace.go:171","msg":"trace[1835072892] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"153.851922ms","start":"2026-03-19T13:32:49.465749Z","end":"2026-03-19T13:32:49.619601Z","steps":["trace[1835072892] 'process raft request' (duration: 153.63591ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:32:50.096434Z","caller":"traceutil/trace.go:171","msg":"trace[1439527730] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"159.242759ms","start":"2026-03-19T13:32:49.937158Z","end":"2026-03-19T13:32:50.096401Z","steps":["trace[1439527730] 'process raft request' (duration: 89.629379ms)","trace[1439527730] 'compare' (duration: 69.494778ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:32:50.858627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.828982ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T13:32:50.858964Z","caller":"traceutil/trace.go:171","msg":"trace[196709555] linearizableReadLoop","detail":"{readStateIndex:1003; appliedIndex:1001; }","duration":"471.177831ms","start":"2026-03-19T13:32:50.387761Z","end":"2026-03-19T13:32:50.858939Z","steps":["trace[196709555] 'read index received' (duration: 53.02µs)","trace[196709555] 'applied index is now lower than readState.Index' (duration: 471.123631ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:32:50.859157Z","caller":"traceutil/trace.go:171","msg":"trace[1626415874] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"921.715275ms","start":"2026-03-19T13:32:49.937419Z","end":"2026-03-19T13:32:50.859134Z","steps":["trace[1626415874] 'process raft request' (duration: 372.275575ms)","trace[1626415874] 'compare' (duration: 548.459498ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:32:50.859509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:32:49.937392Z","time spent":"922.055669ms","remote":"127.0.0.1:49098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T13:32:50.859178Z","caller":"traceutil/trace.go:171","msg":"trace[1902254654] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"921.265639ms","start":"2026-03-19T13:32:49.937898Z","end":"2026-03-19T13:32:50.859164Z","steps":["trace[1902254654] 'process raft request' (duration: 920.939825ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:32:50.85978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:32:49.937885Z","time spent":"921.789946ms","remote":"127.0.0.1:49098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T13:32:50.859261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.494805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T13:32:50.859968Z","caller":"traceutil/trace.go:171","msg":"trace[2081334631] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:961; }","duration":"472.248434ms","start":"2026-03-19T13:32:50.387709Z","end":"2026-03-19T13:32:50.859957Z","steps":["trace[2081334631] 'agreement among raft nodes before linearized reading' (duration: 471.500256ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T13:32:50.859999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T13:32:50.387678Z","time spent":"472.313984ms","remote":"127.0.0.1:48826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-19T13:33:23.186484Z","caller":"traceutil/trace.go:171","msg":"trace[1437258858] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"109.187778ms","start":"2026-03-19T13:33:23.077273Z","end":"2026-03-19T13:33:23.186461Z","steps":["trace[1437258858] 'process raft request' (duration: 109.060426ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T13:33:23.309669Z","caller":"traceutil/trace.go:171","msg":"trace[1834165387] linearizableReadLoop","detail":"{readStateIndex:1105; appliedIndex:1104; }","duration":"194.827701ms","start":"2026-03-19T13:33:23.114807Z","end":"2026-03-19T13:33:23.309635Z","steps":["trace[1834165387] 'read index received' (duration: 71.678885ms)","trace[1834165387] 'applied index is now lower than readState.Index' (duration: 123.147346ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T13:33:23.309733Z","caller":"traceutil/trace.go:171","msg":"trace[800679699] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"232.295726ms","start":"2026-03-19T13:33:23.07741Z","end":"2026-03-19T13:33:23.309706Z","steps":["trace[800679699] 'process raft request' (duration: 148.770043ms)","trace[800679699] 'compare' (duration: 83.30628ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T13:33:23.309997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.108836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-mqbsg\" ","response":"range_response_count:1 size:4908"} {"level":"info","ts":"2026-03-19T13:33:23.310125Z","caller":"traceutil/trace.go:171","msg":"trace[825488502] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-mqbsg; range_end:; response_count:1; response_revision:1056; }","duration":"195.361438ms","start":"2026-03-19T13:33:23.114741Z","end":"2026-03-19T13:33:23.310103Z","steps":["trace[825488502] 'agreement among raft nodes before linearized reading' (duration: 195.021815ms)"],"step_count":1}