{"level":"warn","ts":"2026-02-05T05:20:50.397748Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-05T05:20:50.397868Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.49: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.49:2380","--initial-cluster=instance=https://162.253.55.49:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.49:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.49:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-05T05:20:50.398094Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-05T05:20:50.398127Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.49:2380"]} {"level":"info","ts":"2026-02-05T05:20:50.398268Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-05T05:20:50.399026Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.49:2379"]} {"level":"info","ts":"2026-02-05T05:20:50.399811Z","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.49:2380"],"listen-peer-urls":["https://162.253.55.49:2380"],"advertise-client-urls":["https://162.253.55.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.49:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-05T05:20:50.405425Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.673259ms"} {"level":"info","ts":"2026-02-05T05:20:50.435595Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6a9ba61e55fef5be","cluster-id":"9612db6e19ac6ce7"} {"level":"info","ts":"2026-02-05T05:20:50.435789Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be switched to configuration voters=()"} {"level":"info","ts":"2026-02-05T05:20:50.436066Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became follower at term 0"} {"level":"info","ts":"2026-02-05T05:20:50.43636Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6a9ba61e55fef5be [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-05T05:20:50.436415Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became follower at term 1"} {"level":"info","ts":"2026-02-05T05:20:50.440614Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be switched to configuration voters=(7681916238632515006)"} {"level":"warn","ts":"2026-02-05T05:20:50.454987Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-05T05:20:50.456259Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-05T05:20:50.45759Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-02-05T05:20:50.463573Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6a9ba61e55fef5be","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-05T05:20:50.471171Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6a9ba61e55fef5be","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-05T05:20:50.471743Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-05T05:20:50.471793Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-05T05:20:50.471817Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-05T05:20:50.472203Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be switched to configuration voters=(7681916238632515006)"} {"level":"info","ts":"2026-02-05T05:20:50.473028Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9612db6e19ac6ce7","local-member-id":"6a9ba61e55fef5be","added-peer-id":"6a9ba61e55fef5be","added-peer-peer-urls":["https://162.253.55.49:2380"]} {"level":"info","ts":"2026-02-05T05:20:50.486633Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-05T05:20:50.486877Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.49:2380"} {"level":"info","ts":"2026-02-05T05:20:50.486922Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.49:2380"} {"level":"info","ts":"2026-02-05T05:20:50.487087Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6a9ba61e55fef5be","initial-advertise-peer-urls":["https://162.253.55.49:2380"],"listen-peer-urls":["https://162.253.55.49:2380"],"advertise-client-urls":["https://162.253.55.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-05T05:20:50.48718Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-05T05:20:51.041965Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be is starting a new election at term 1"} {"level":"info","ts":"2026-02-05T05:20:51.042011Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became pre-candidate at term 1"} {"level":"info","ts":"2026-02-05T05:20:51.042032Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be received MsgPreVoteResp from 6a9ba61e55fef5be at term 1"} {"level":"info","ts":"2026-02-05T05:20:51.042043Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became candidate at term 2"} {"level":"info","ts":"2026-02-05T05:20:51.042055Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be received MsgVoteResp from 6a9ba61e55fef5be at term 2"} {"level":"info","ts":"2026-02-05T05:20:51.042063Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became leader at term 2"} {"level":"info","ts":"2026-02-05T05:20:51.042072Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6a9ba61e55fef5be elected leader 6a9ba61e55fef5be at term 2"} {"level":"info","ts":"2026-02-05T05:20:51.04744Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6a9ba61e55fef5be","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.49:2379]}","request-path":"/0/members/6a9ba61e55fef5be/attributes","cluster-id":"9612db6e19ac6ce7","publish-timeout":"7s"} {"level":"info","ts":"2026-02-05T05:20:51.047522Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-05T05:20:51.047932Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T05:20:51.05049Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-05T05:20:51.050709Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-05T05:20:51.050722Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-05T05:20:51.051227Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-05T05:20:51.053145Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9612db6e19ac6ce7","local-member-id":"6a9ba61e55fef5be","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T05:20:51.053254Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T05:20:51.053306Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T05:20:51.054205Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.49:2379"} {"level":"warn","ts":"2026-02-05T05:20:53.766864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.211398ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-05T05:20:53.767542Z","caller":"traceutil/trace.go:171","msg":"trace[1345350031] transaction","detail":"{read_only:false; response_revision:71; number_of_response:1; }","duration":"200.100263ms","start":"2026-02-05T05:20:53.567423Z","end":"2026-02-05T05:20:53.767523Z","steps":["trace[1345350031] 'process raft request' (duration: 200.069122ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:20:53.767517Z","caller":"traceutil/trace.go:171","msg":"trace[1474065999] transaction","detail":"{read_only:false; response_revision:68; number_of_response:1; }","duration":"201.111544ms","start":"2026-02-05T05:20:53.566358Z","end":"2026-02-05T05:20:53.76747Z","steps":["trace[1474065999] 'process raft request' (duration: 99.811825ms)","trace[1474065999] 'compare' (duration: 100.025797ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:20:53.767849Z","caller":"traceutil/trace.go:171","msg":"trace[235092948] transaction","detail":"{read_only:false; response_revision:69; number_of_response:1; }","duration":"201.040644ms","start":"2026-02-05T05:20:53.5668Z","end":"2026-02-05T05:20:53.76784Z","steps":["trace[235092948] 'process raft request' (duration: 200.605079ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:20:53.768237Z","caller":"traceutil/trace.go:171","msg":"trace[1945989017] transaction","detail":"{read_only:false; response_revision:70; number_of_response:1; }","duration":"201.094773ms","start":"2026-02-05T05:20:53.567133Z","end":"2026-02-05T05:20:53.768228Z","steps":["trace[1945989017] 'process raft request' (duration: 200.333795ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:20:53.941552Z","caller":"traceutil/trace.go:171","msg":"trace[65552747] transaction","detail":"{read_only:false; response_revision:74; number_of_response:1; }","duration":"146.085999ms","start":"2026-02-05T05:20:53.795437Z","end":"2026-02-05T05:20:53.941523Z","steps":["trace[65552747] 'process raft request' (duration: 91.490561ms)","trace[65552747] 'compare' (duration: 53.602949ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:20:54.193601Z","caller":"traceutil/trace.go:171","msg":"trace[328500542] linearizableReadLoop","detail":"{readStateIndex:83; appliedIndex:81; }","duration":"207.322656ms","start":"2026-02-05T05:20:53.98623Z","end":"2026-02-05T05:20:54.193553Z","steps":["trace[328500542] 'read index received' (duration: 165.631791ms)","trace[328500542] 'applied index is now lower than readState.Index' (duration: 41.689274ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:20:54.193892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.645658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18914223ab31b9d8\" ","response":"range_response_count:1 size:648"} {"level":"info","ts":"2026-02-05T05:20:54.193937Z","caller":"traceutil/trace.go:171","msg":"trace[1589981125] range","detail":"{range_begin:/registry/events/default/instance.18914223ab31b9d8; range_end:; response_count:1; response_revision:78; }","duration":"207.751579ms","start":"2026-02-05T05:20:53.986175Z","end":"2026-02-05T05:20:54.193926Z","steps":["trace[1589981125] 'agreement among raft nodes before linearized reading' (duration: 207.498597ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:20:54.194338Z","caller":"traceutil/trace.go:171","msg":"trace[310744244] transaction","detail":"{read_only:false; response_revision:77; number_of_response:1; }","duration":"245.580549ms","start":"2026-02-05T05:20:53.948743Z","end":"2026-02-05T05:20:54.194324Z","steps":["trace[310744244] 'process raft request' (duration: 203.207738ms)","trace[310744244] 'compare' (duration: 41.316041ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:20:54.194661Z","caller":"traceutil/trace.go:171","msg":"trace[1834975943] transaction","detail":"{read_only:false; response_revision:78; number_of_response:1; }","duration":"245.761152ms","start":"2026-02-05T05:20:53.948885Z","end":"2026-02-05T05:20:54.194646Z","steps":["trace[1834975943] 'process raft request' (duration: 244.57393ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:20:55.282718Z","caller":"traceutil/trace.go:171","msg":"trace[134859954] transaction","detail":"{read_only:false; response_revision:224; number_of_response:1; }","duration":"330.519098ms","start":"2026-02-05T05:20:54.95217Z","end":"2026-02-05T05:20:55.282689Z","steps":["trace[134859954] 'process raft request' (duration: 266.494568ms)","trace[134859954] 'compare' (duration: 63.878539ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:20:55.282872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:20:54.952163Z","time spent":"330.636069ms","remote":"127.0.0.1:50110","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":748,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-05T05:21:01.940314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.786553ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T05:21:01.941671Z","caller":"traceutil/trace.go:171","msg":"trace[521975975] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"189.641234ms","start":"2026-02-05T05:21:01.752011Z","end":"2026-02-05T05:21:01.941653Z","steps":["trace[521975975] 'process raft request' (duration: 62.147175ms)","trace[521975975] 'compare' (duration: 125.605242ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:21:01.942451Z","caller":"traceutil/trace.go:171","msg":"trace[888049271] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"189.377651ms","start":"2026-02-05T05:21:01.753063Z","end":"2026-02-05T05:21:01.94244Z","steps":["trace[888049271] 'process raft request' (duration: 188.395773ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:21:01.942532Z","caller":"traceutil/trace.go:171","msg":"trace[1361888852] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"190.24737ms","start":"2026-02-05T05:21:01.752278Z","end":"2026-02-05T05:21:01.942525Z","steps":["trace[1361888852] 'process raft request' (duration: 189.10725ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:21:01.942567Z","caller":"traceutil/trace.go:171","msg":"trace[1920738826] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"189.468833ms","start":"2026-02-05T05:21:01.753094Z","end":"2026-02-05T05:21:01.942563Z","steps":["trace[1920738826] 'process raft request' (duration: 188.388653ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:21:03.551302Z","caller":"traceutil/trace.go:171","msg":"trace[1666823642] linearizableReadLoop","detail":"{readStateIndex:410; appliedIndex:409; }","duration":"113.45744ms","start":"2026-02-05T05:21:03.437819Z","end":"2026-02-05T05:21:03.551277Z","steps":["trace[1666823642] 'read index received' (duration: 112.302739ms)","trace[1666823642] 'applied index is now lower than readState.Index' (duration: 1.153541ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:21:03.551408Z","caller":"traceutil/trace.go:171","msg":"trace[201258784] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"170.198406ms","start":"2026-02-05T05:21:03.3812Z","end":"2026-02-05T05:21:03.551398Z","steps":["trace[201258784] 'process raft request' (duration: 168.989234ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:21:03.551649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.812224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-05T05:21:03.551688Z","caller":"traceutil/trace.go:171","msg":"trace[364574388] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:399; }","duration":"113.887945ms","start":"2026-02-05T05:21:03.437787Z","end":"2026-02-05T05:21:03.551675Z","steps":["trace[364574388] 'agreement among raft nodes before linearized reading' (duration: 113.806954ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:21:04.105491Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17707762498716371839,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T05:21:06.15702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000306977s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-05T05:21:06.157115Z","caller":"traceutil/trace.go:171","msg":"trace[1053301687] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000458489s","start":"2026-02-05T05:21:04.156639Z","end":"2026-02-05T05:21:06.157098Z","steps":["trace[1053301687] 'agreement among raft nodes before linearized reading' (duration: 2.000337678s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:21:06.157173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:21:04.156623Z","time spent":"2.00053173s","remote":"127.0.0.1:49714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/05 05:21:06 WARNING: [core] [Server #5] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-05T05:21:06.183488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.880353284s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T05:21:06.183648Z","caller":"traceutil/trace.go:171","msg":"trace[694971117] linearizableReadLoop","detail":"{readStateIndex:414; appliedIndex:410; }","duration":"2.6216892s","start":"2026-02-05T05:21:03.561944Z","end":"2026-02-05T05:21:06.183633Z","steps":["trace[694971117] 'read index received' (duration: 741.154275ms)","trace[694971117] 'applied index is now lower than readState.Index' (duration: 1.880534015s)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:21:06.183828Z","caller":"traceutil/trace.go:171","msg":"trace[690738281] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"2.62392674s","start":"2026-02-05T05:21:03.559892Z","end":"2026-02-05T05:21:06.183819Z","steps":["trace[690738281] 'process raft request' (duration: 743.194143ms)","trace[690738281] 'compare' (duration: 1.880221623s)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:21:06.183874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:21:03.559877Z","time spent":"2.62397476s","remote":"127.0.0.1:50016","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-02-05T05:21:06.184Z","caller":"traceutil/trace.go:171","msg":"trace[2026354053] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"2.61504618s","start":"2026-02-05T05:21:03.568946Z","end":"2026-02-05T05:21:06.183992Z","steps":["trace[2026354053] 'process raft request' (duration: 2.614599876s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:21:06.184061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:21:03.568938Z","time spent":"2.6150819s","remote":"127.0.0.1:50016","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-02-05T05:21:06.18415Z","caller":"traceutil/trace.go:171","msg":"trace[1616844392] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"2.614598786s","start":"2026-02-05T05:21:03.569545Z","end":"2026-02-05T05:21:06.184143Z","steps":["trace[1616844392] 'process raft request' (duration: 2.614031181s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:21:06.184197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:21:03.56954Z","time spent":"2.614633357s","remote":"127.0.0.1:50016","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-02-05T05:21:06.184286Z","caller":"traceutil/trace.go:171","msg":"trace[1722615012] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"2.471473517s","start":"2026-02-05T05:21:03.712808Z","end":"2026-02-05T05:21:06.184281Z","steps":["trace[1722615012] 'process raft request' (duration: 2.47078929s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:21:06.184327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:21:03.712789Z","time spent":"2.471519377s","remote":"127.0.0.1:50016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:21:06.184423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.622477358s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-dblpj\" ","response":"range_response_count:1 size:4417"} {"level":"info","ts":"2026-02-05T05:21:06.184449Z","caller":"traceutil/trace.go:171","msg":"trace[1392965412] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-dblpj; range_end:; response_count:1; response_revision:403; }","duration":"2.622519528s","start":"2026-02-05T05:21:03.561923Z","end":"2026-02-05T05:21:06.184442Z","steps":["trace[1392965412] 'agreement among raft nodes before linearized reading' (duration: 2.622474748s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:21:06.184473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:21:03.561917Z","time spent":"2.62255158s","remote":"127.0.0.1:49920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":4440,"request content":"key:\"/registry/pods/kube-system/kube-proxy-dblpj\" "} {"level":"warn","ts":"2026-02-05T05:21:06.184579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.344564725s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/coredns-5dd5756b68-fwvbj.1891422696361abd\" ","response":"range_response_count:1 size:845"} {"level":"info","ts":"2026-02-05T05:21:06.184601Z","caller":"traceutil/trace.go:171","msg":"trace[704364734] range","detail":"{range_begin:/registry/events/kube-system/coredns-5dd5756b68-fwvbj.1891422696361abd; range_end:; response_count:1; response_revision:403; }","duration":"2.344587205s","start":"2026-02-05T05:21:03.840008Z","end":"2026-02-05T05:21:06.184595Z","steps":["trace[704364734] 'agreement among raft nodes before linearized reading' (duration: 2.344548664s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:21:06.184622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:21:03.839918Z","time spent":"2.344700045s","remote":"127.0.0.1:49802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":868,"request content":"key:\"/registry/events/kube-system/coredns-5dd5756b68-fwvbj.1891422696361abd\" "} {"level":"warn","ts":"2026-02-05T05:21:06.184883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.754883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.49\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-05T05:21:06.184907Z","caller":"traceutil/trace.go:171","msg":"trace[1244612431] range","detail":"{range_begin:/registry/masterleases/162.253.55.49; range_end:; response_count:1; response_revision:403; }","duration":"657.820923ms","start":"2026-02-05T05:21:05.527081Z","end":"2026-02-05T05:21:06.184902Z","steps":["trace[1244612431] 'agreement among raft nodes before linearized reading' (duration: 657.781403ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:21:06.184924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:21:05.527062Z","time spent":"657.857804ms","remote":"127.0.0.1:49744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/162.253.55.49\" "} {"level":"warn","ts":"2026-02-05T05:21:06.185013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.19118032s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-05T05:21:06.185029Z","caller":"traceutil/trace.go:171","msg":"trace[1089930965] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:403; }","duration":"2.19119668s","start":"2026-02-05T05:21:03.993828Z","end":"2026-02-05T05:21:06.185024Z","steps":["trace[1089930965] 'agreement among raft nodes before linearized reading' (duration: 2.19116585s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:21:06.185044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:21:03.993763Z","time spent":"2.191278212s","remote":"127.0.0.1:50016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-05T05:21:06.371841Z","caller":"traceutil/trace.go:171","msg":"trace[1387384125] linearizableReadLoop","detail":"{readStateIndex:418; appliedIndex:415; }","duration":"157.012188ms","start":"2026-02-05T05:21:06.214811Z","end":"2026-02-05T05:21:06.371823Z","steps":["trace[1387384125] 'read index received' (duration: 53.399962ms)","trace[1387384125] 'applied index is now lower than readState.Index' (duration: 103.611036ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:21:06.371924Z","caller":"traceutil/trace.go:171","msg":"trace[2061935962] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"174.788626ms","start":"2026-02-05T05:21:06.197129Z","end":"2026-02-05T05:21:06.371917Z","steps":["trace[2061935962] 'process raft request' (duration: 174.533743ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:21:06.372041Z","caller":"traceutil/trace.go:171","msg":"trace[1011867130] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"172.766478ms","start":"2026-02-05T05:21:06.199269Z","end":"2026-02-05T05:21:06.372035Z","steps":["trace[1011867130] 'process raft request' (duration: 172.442125ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:21:06.372386Z","caller":"traceutil/trace.go:171","msg":"trace[1625856289] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"156.045639ms","start":"2026-02-05T05:21:06.21633Z","end":"2026-02-05T05:21:06.372376Z","steps":["trace[1625856289] 'process raft request' (duration: 155.405833ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:21:06.372667Z","caller":"traceutil/trace.go:171","msg":"trace[453494773] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"103.922139ms","start":"2026-02-05T05:21:06.268737Z","end":"2026-02-05T05:21:06.372659Z","steps":["trace[453494773] 'process raft request' (duration: 103.04214ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:21:06.372763Z","caller":"traceutil/trace.go:171","msg":"trace[1676337499] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"181.362424ms","start":"2026-02-05T05:21:06.191395Z","end":"2026-02-05T05:21:06.372757Z","steps":["trace[1676337499] 'process raft request' (duration: 171.551047ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:21:06.372879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.055677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:21:06.372913Z","caller":"traceutil/trace.go:171","msg":"trace[376139433] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:408; }","duration":"158.115278ms","start":"2026-02-05T05:21:06.214779Z","end":"2026-02-05T05:21:06.372894Z","steps":["trace[376139433] 'agreement among raft nodes before linearized reading' (duration: 157.117219ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:21:25.977874Z","caller":"traceutil/trace.go:171","msg":"trace[947589002] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"124.691228ms","start":"2026-02-05T05:21:25.853161Z","end":"2026-02-05T05:21:25.977852Z","steps":["trace[947589002] 'process raft request' (duration: 124.388345ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:21:26.494594Z","caller":"traceutil/trace.go:171","msg":"trace[436969992] linearizableReadLoop","detail":"{readStateIndex:509; appliedIndex:508; }","duration":"116.459379ms","start":"2026-02-05T05:21:26.378111Z","end":"2026-02-05T05:21:26.494571Z","steps":["trace[436969992] 'read index received' (duration: 116.154507ms)","trace[436969992] 'applied index is now lower than readState.Index' (duration: 304.092µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:21:26.495743Z","caller":"traceutil/trace.go:171","msg":"trace[341128047] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"152.991161ms","start":"2026-02-05T05:21:26.34274Z","end":"2026-02-05T05:21:26.495731Z","steps":["trace[341128047] 'process raft request' (duration: 151.607991ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:21:26.503095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.966065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-05T05:21:26.503156Z","caller":"traceutil/trace.go:171","msg":"trace[182369178] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:493; }","duration":"125.034556ms","start":"2026-02-05T05:21:26.378106Z","end":"2026-02-05T05:21:26.503141Z","steps":["trace[182369178] 'agreement among raft nodes before linearized reading' (duration: 116.841122ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:21:26.800011Z","caller":"traceutil/trace.go:171","msg":"trace[1506518880] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"209.120934ms","start":"2026-02-05T05:21:26.590857Z","end":"2026-02-05T05:21:26.799978Z","steps":["trace[1506518880] 'process raft request' (duration: 208.900902ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:21:26.980142Z","caller":"traceutil/trace.go:171","msg":"trace[903351650] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"385.431045ms","start":"2026-02-05T05:21:26.594676Z","end":"2026-02-05T05:21:26.980107Z","steps":["trace[903351650] 'process raft request' (duration: 340.924441ms)","trace[903351650] 'compare' (duration: 43.132574ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:21:26.980629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:21:26.594662Z","time spent":"385.861779ms","remote":"127.0.0.1:50240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4155,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-05T05:21:26.980682Z","caller":"traceutil/trace.go:171","msg":"trace[210153230] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"172.352ms","start":"2026-02-05T05:21:26.808278Z","end":"2026-02-05T05:21:26.98063Z","steps":["trace[210153230] 'process raft request' (duration: 171.291122ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:21:26.981247Z","caller":"traceutil/trace.go:171","msg":"trace[1658562836] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"172.807133ms","start":"2026-02-05T05:21:26.808424Z","end":"2026-02-05T05:21:26.981231Z","steps":["trace[1658562836] 'process raft request' (duration: 171.483274ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:21:26.981784Z","caller":"traceutil/trace.go:171","msg":"trace[534325370] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"172.804324ms","start":"2026-02-05T05:21:26.808968Z","end":"2026-02-05T05:21:26.981772Z","steps":["trace[534325370] 'process raft request' (duration: 171.00396ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:21:26.984069Z","caller":"traceutil/trace.go:171","msg":"trace[1691459567] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"321.213989ms","start":"2026-02-05T05:21:26.662838Z","end":"2026-02-05T05:21:26.984052Z","steps":["trace[1691459567] 'process raft request' (duration: 316.273842ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:21:26.989858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:21:26.662817Z","time spent":"326.943534ms","remote":"127.0.0.1:49824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":317259,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:21:26.98425Z","caller":"traceutil/trace.go:171","msg":"trace[1931121021] linearizableReadLoop","detail":"{readStateIndex:516; appliedIndex:514; }","duration":"374.274699ms","start":"2026-02-05T05:21:26.609961Z","end":"2026-02-05T05:21:26.984236Z","steps":["trace[1931121021] 'read index received' (duration: 189.695865ms)","trace[1931121021] 'applied index is now lower than readState.Index' (duration: 184.577224ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:21:26.984622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.625582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-05T05:21:26.990241Z","caller":"traceutil/trace.go:171","msg":"trace[1861417238] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:505; }","duration":"380.297206ms","start":"2026-02-05T05:21:26.609933Z","end":"2026-02-05T05:21:26.99023Z","steps":["trace[1861417238] 'agreement among raft nodes before linearized reading' (duration: 374.598972ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:21:26.990331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:21:26.609917Z","time spent":"380.403956ms","remote":"127.0.0.1:50016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-05T05:21:26.9852Z","caller":"traceutil/trace.go:171","msg":"trace[1425210298] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"169.948192ms","start":"2026-02-05T05:21:26.815226Z","end":"2026-02-05T05:21:26.985174Z","steps":["trace[1425210298] 'process raft request' (duration: 164.800933ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:21:27.175154Z","caller":"traceutil/trace.go:171","msg":"trace[1179709878] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"102.055854ms","start":"2026-02-05T05:21:27.073056Z","end":"2026-02-05T05:21:27.175111Z","steps":["trace[1179709878] 'process raft request' (duration: 60.149452ms)","trace[1179709878] 'compare' (duration: 41.744571ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:21:27.191346Z","caller":"traceutil/trace.go:171","msg":"trace[1101614128] linearizableReadLoop","detail":"{readStateIndex:529; appliedIndex:526; }","duration":"113.548792ms","start":"2026-02-05T05:21:27.077782Z","end":"2026-02-05T05:21:27.191331Z","steps":["trace[1101614128] 'read index received' (duration: 55.509177ms)","trace[1101614128] 'applied index is now lower than readState.Index' (duration: 58.038795ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:21:27.191693Z","caller":"traceutil/trace.go:171","msg":"trace[1547507029] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"118.287569ms","start":"2026-02-05T05:21:27.073374Z","end":"2026-02-05T05:21:27.191662Z","steps":["trace[1547507029] 'process raft request' (duration: 117.719924ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:21:27.192178Z","caller":"traceutil/trace.go:171","msg":"trace[379602897] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"118.721153ms","start":"2026-02-05T05:21:27.073448Z","end":"2026-02-05T05:21:27.192169Z","steps":["trace[379602897] 'process raft request' (duration: 117.761475ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:21:27.192317Z","caller":"traceutil/trace.go:171","msg":"trace[346961113] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"102.85824ms","start":"2026-02-05T05:21:27.08945Z","end":"2026-02-05T05:21:27.192309Z","steps":["trace[346961113] 'process raft request' (duration: 101.839111ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:21:27.192552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.750121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/cilium-operator\" ","response":"range_response_count:1 size:4313"} {"level":"info","ts":"2026-02-05T05:21:27.192585Z","caller":"traceutil/trace.go:171","msg":"trace[1081128063] range","detail":"{range_begin:/registry/deployments/kube-system/cilium-operator; range_end:; response_count:1; response_revision:514; }","duration":"114.816202ms","start":"2026-02-05T05:21:27.077759Z","end":"2026-02-05T05:21:27.192575Z","steps":["trace[1081128063] 'agreement among raft nodes before linearized reading' (duration: 114.666241ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:21:27.47886Z","caller":"traceutil/trace.go:171","msg":"trace[1462647212] linearizableReadLoop","detail":"{readStateIndex:535; appliedIndex:534; }","duration":"110.207356ms","start":"2026-02-05T05:21:27.368627Z","end":"2026-02-05T05:21:27.478834Z","steps":["trace[1462647212] 'read index received' (duration: 109.36005ms)","trace[1462647212] 'applied index is now lower than readState.Index' (duration: 846.366µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:21:27.479129Z","caller":"traceutil/trace.go:171","msg":"trace[2034744509] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"144.766601ms","start":"2026-02-05T05:21:27.334341Z","end":"2026-02-05T05:21:27.479107Z","steps":["trace[2034744509] 'process raft request' (duration: 143.706203ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:21:27.479433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.78551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-gl9zt\" ","response":"range_response_count:1 size:4635"} {"level":"info","ts":"2026-02-05T05:21:27.479467Z","caller":"traceutil/trace.go:171","msg":"trace[814883367] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-gl9zt; range_end:; response_count:1; response_revision:519; }","duration":"110.834101ms","start":"2026-02-05T05:21:27.368622Z","end":"2026-02-05T05:21:27.479456Z","steps":["trace[814883367] 'agreement among raft nodes before linearized reading' (duration: 110.70264ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:21:30.846933Z","caller":"traceutil/trace.go:171","msg":"trace[416131550] linearizableReadLoop","detail":"{readStateIndex:544; appliedIndex:543; }","duration":"177.691314ms","start":"2026-02-05T05:21:30.669221Z","end":"2026-02-05T05:21:30.846912Z","steps":["trace[416131550] 'read index received' (duration: 177.486793ms)","trace[416131550] 'applied index is now lower than readState.Index' (duration: 203.561µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:21:30.847033Z","caller":"traceutil/trace.go:171","msg":"trace[149957605] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"222.170131ms","start":"2026-02-05T05:21:30.624853Z","end":"2026-02-05T05:21:30.847023Z","steps":["trace[149957605] 'process raft request' (duration: 221.917659ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:21:30.847133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.73355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-05T05:21:30.847155Z","caller":"traceutil/trace.go:171","msg":"trace[2132338550] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:528; }","duration":"167.79269ms","start":"2026-02-05T05:21:30.679356Z","end":"2026-02-05T05:21:30.847149Z","steps":["trace[2132338550] 'agreement among raft nodes before linearized reading' (duration: 167.709198ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:21:30.847218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.017227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-05T05:21:30.847229Z","caller":"traceutil/trace.go:171","msg":"trace[1585410785] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:528; }","duration":"178.028448ms","start":"2026-02-05T05:21:30.669197Z","end":"2026-02-05T05:21:30.847225Z","steps":["trace[1585410785] 'agreement among raft nodes before linearized reading' (duration: 177.995227ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:21:30.995873Z","caller":"traceutil/trace.go:171","msg":"trace[354691909] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"145.22435ms","start":"2026-02-05T05:21:30.850629Z","end":"2026-02-05T05:21:30.995853Z","steps":["trace[354691909] 'process raft request' (duration: 138.701919ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:21:31.000498Z","caller":"traceutil/trace.go:171","msg":"trace[328378361] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"149.740863ms","start":"2026-02-05T05:21:30.850748Z","end":"2026-02-05T05:21:31.000489Z","steps":["trace[328378361] 'process raft request' (duration: 149.693713ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:21:32.214637Z","caller":"traceutil/trace.go:171","msg":"trace[2020862883] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"116.172242ms","start":"2026-02-05T05:21:32.098441Z","end":"2026-02-05T05:21:32.214613Z","steps":["trace[2020862883] 'process raft request' (duration: 87.794459ms)","trace[2020862883] 'compare' (duration: 28.254772ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:21:32.220059Z","caller":"traceutil/trace.go:171","msg":"trace[90617963] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"121.344681ms","start":"2026-02-05T05:21:32.098697Z","end":"2026-02-05T05:21:32.220042Z","steps":["trace[90617963] 'process raft request' (duration: 121.029158ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:21:32.220488Z","caller":"traceutil/trace.go:171","msg":"trace[2141026452] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"119.285135ms","start":"2026-02-05T05:21:32.101196Z","end":"2026-02-05T05:21:32.220481Z","steps":["trace[2141026452] 'process raft request' (duration: 118.60415ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:21:32.418806Z","caller":"traceutil/trace.go:171","msg":"trace[1055063768] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"137.440191ms","start":"2026-02-05T05:21:32.28134Z","end":"2026-02-05T05:21:32.41878Z","steps":["trace[1055063768] 'process raft request' (duration: 137.267109ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:21:32.652301Z","caller":"traceutil/trace.go:171","msg":"trace[2097074573] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"135.168243ms","start":"2026-02-05T05:21:32.517113Z","end":"2026-02-05T05:21:32.652281Z","steps":["trace[2097074573] 'process raft request' (duration: 129.38302ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:21:32.838908Z","caller":"traceutil/trace.go:171","msg":"trace[185277172] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"176.191891ms","start":"2026-02-05T05:21:32.662691Z","end":"2026-02-05T05:21:32.838883Z","steps":["trace[185277172] 'process raft request' (duration: 124.668254ms)","trace[185277172] 'compare' (duration: 51.330745ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:21:32.868699Z","caller":"traceutil/trace.go:171","msg":"trace[1587067278] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"205.514841ms","start":"2026-02-05T05:21:32.663168Z","end":"2026-02-05T05:21:32.868683Z","steps":["trace[1587067278] 'process raft request' (duration: 205.3041ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:21:33.14917Z","caller":"traceutil/trace.go:171","msg":"trace[1104598123] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"123.965926ms","start":"2026-02-05T05:21:33.025187Z","end":"2026-02-05T05:21:33.149153Z","steps":["trace[1104598123] 'process raft request' (duration: 123.903365ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:21:33.149375Z","caller":"traceutil/trace.go:171","msg":"trace[1912592470] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"124.234467ms","start":"2026-02-05T05:21:33.025131Z","end":"2026-02-05T05:21:33.149365Z","steps":["trace[1912592470] 'process raft request' (duration: 80.627212ms)","trace[1912592470] 'compare' (duration: 43.222253ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:21:33.149586Z","caller":"traceutil/trace.go:171","msg":"trace[1643031480] linearizableReadLoop","detail":"{readStateIndex:572; appliedIndex:567; }","duration":"118.847007ms","start":"2026-02-05T05:21:33.030733Z","end":"2026-02-05T05:21:33.14958Z","steps":["trace[1643031480] 'read index received' (duration: 56.540022ms)","trace[1643031480] 'applied index is now lower than readState.Index' (duration: 62.306555ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:21:33.149736Z","caller":"traceutil/trace.go:171","msg":"trace[2026860755] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"124.508449ms","start":"2026-02-05T05:21:33.025222Z","end":"2026-02-05T05:21:33.149731Z","steps":["trace[2026860755] 'process raft request' (duration: 123.909435ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:21:33.149875Z","caller":"traceutil/trace.go:171","msg":"trace[598230275] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"120.884504ms","start":"2026-02-05T05:21:33.028985Z","end":"2026-02-05T05:21:33.14987Z","steps":["trace[598230275] 'process raft request' (duration: 120.55348ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:21:33.149992Z","caller":"traceutil/trace.go:171","msg":"trace[1294756614] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"120.831733ms","start":"2026-02-05T05:21:33.029153Z","end":"2026-02-05T05:21:33.149985Z","steps":["trace[1294756614] 'process raft request' (duration: 120.407519ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:21:33.150169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.424562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:3653"} {"level":"info","ts":"2026-02-05T05:21:33.150236Z","caller":"traceutil/trace.go:171","msg":"trace[651679048] range","detail":"{range_begin:/registry/deployments/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:555; }","duration":"119.538343ms","start":"2026-02-05T05:21:33.030689Z","end":"2026-02-05T05:21:33.150228Z","steps":["trace[651679048] 'agreement among raft nodes before linearized reading' (duration: 119.425243ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:21:34.514519Z","caller":"traceutil/trace.go:171","msg":"trace[1281080206] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"104.033753ms","start":"2026-02-05T05:21:34.410466Z","end":"2026-02-05T05:21:34.5145Z","steps":["trace[1281080206] 'process raft request' (duration: 103.918242ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:21:35.311537Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17707762498716372195,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-05T05:21:35.48631Z","caller":"traceutil/trace.go:171","msg":"trace[271199171] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"968.360285ms","start":"2026-02-05T05:21:34.517898Z","end":"2026-02-05T05:21:35.486258Z","steps":["trace[271199171] 'process raft request' (duration: 967.464848ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:21:35.489073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:21:34.517885Z","time spent":"970.894033ms","remote":"127.0.0.1:49760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4059,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:21:35.725599Z","caller":"traceutil/trace.go:171","msg":"trace[1837420436] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:601; }","duration":"914.77053ms","start":"2026-02-05T05:21:34.810788Z","end":"2026-02-05T05:21:35.725559Z","steps":["trace[1837420436] 'read index received' (duration: 674.642932ms)","trace[1837420436] 'applied index is now lower than readState.Index' (duration: 240.127028ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:21:35.726051Z","caller":"traceutil/trace.go:171","msg":"trace[82306463] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"1.111139909s","start":"2026-02-05T05:21:34.6149Z","end":"2026-02-05T05:21:35.72604Z","steps":["trace[82306463] 'process raft request' (duration: 1.110466534s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:21:35.726241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:21:34.614885Z","time spent":"1.111193199s","remote":"127.0.0.1:49760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-05T05:21:35.726737Z","caller":"traceutil/trace.go:171","msg":"trace[721191344] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"712.455815ms","start":"2026-02-05T05:21:35.014251Z","end":"2026-02-05T05:21:35.726707Z","steps":["trace[721191344] 'process raft request' (duration: 711.258987ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:21:35.726959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:21:35.014237Z","time spent":"712.591636ms","remote":"127.0.0.1:49760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-05T05:21:35.727306Z","caller":"traceutil/trace.go:171","msg":"trace[2142138469] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"513.656714ms","start":"2026-02-05T05:21:35.213605Z","end":"2026-02-05T05:21:35.727262Z","steps":["trace[2142138469] 'process raft request' (duration: 511.933331ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:21:35.727494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:21:35.213585Z","time spent":"513.747055ms","remote":"127.0.0.1:49760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-05T05:21:35.727806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"917.011847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:21:35.727919Z","caller":"traceutil/trace.go:171","msg":"trace[1344231669] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:588; }","duration":"917.085257ms","start":"2026-02-05T05:21:34.810746Z","end":"2026-02-05T05:21:35.727832Z","steps":["trace[1344231669] 'agreement among raft nodes before linearized reading' (duration: 917.017377ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:21:35.728589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:21:34.810727Z","time spent":"917.216508ms","remote":"127.0.0.1:49760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-02-05T05:21:35.730382Z","caller":"traceutil/trace.go:171","msg":"trace[1948763632] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"232.744263ms","start":"2026-02-05T05:21:35.49763Z","end":"2026-02-05T05:21:35.730374Z","steps":["trace[1948763632] 'process raft request' (duration: 232.681553ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:21:35.730503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"818.332252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-05T05:21:35.730523Z","caller":"traceutil/trace.go:171","msg":"trace[160547800] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:589; }","duration":"818.382932ms","start":"2026-02-05T05:21:34.912135Z","end":"2026-02-05T05:21:35.730518Z","steps":["trace[160547800] 'agreement among raft nodes before linearized reading' (duration: 818.307922ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:21:35.730535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:21:34.912118Z","time spent":"818.413702ms","remote":"127.0.0.1:50016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-05T05:21:35.730663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.100999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-02-05T05:21:35.730679Z","caller":"traceutil/trace.go:171","msg":"trace[1558377814] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:589; }","duration":"120.115989ms","start":"2026-02-05T05:21:35.610558Z","end":"2026-02-05T05:21:35.730674Z","steps":["trace[1558377814] 'agreement among raft nodes before linearized reading' (duration: 120.076129ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:21:35.730722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.365201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.49\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-05T05:21:35.730732Z","caller":"traceutil/trace.go:171","msg":"trace[1679735808] range","detail":"{range_begin:/registry/masterleases/162.253.55.49; range_end:; response_count:1; response_revision:589; }","duration":"186.406581ms","start":"2026-02-05T05:21:35.544322Z","end":"2026-02-05T05:21:35.730729Z","steps":["trace[1679735808] 'agreement among raft nodes before linearized reading' (duration: 186.387851ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:21:35.730812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.855054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-05T05:21:35.730824Z","caller":"traceutil/trace.go:171","msg":"trace[1133942002] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:589; }","duration":"236.895804ms","start":"2026-02-05T05:21:35.493925Z","end":"2026-02-05T05:21:35.73082Z","steps":["trace[1133942002] 'agreement among raft nodes before linearized reading' (duration: 236.860124ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:21:35.730884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.348422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:21:35.7309Z","caller":"traceutil/trace.go:171","msg":"trace[515462832] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:589; }","duration":"320.398202ms","start":"2026-02-05T05:21:35.410498Z","end":"2026-02-05T05:21:35.730897Z","steps":["trace[515462832] 'agreement among raft nodes before linearized reading' (duration: 320.376172ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:21:35.730912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:21:35.410482Z","time spent":"320.427312ms","remote":"127.0.0.1:49760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-05T05:21:35.730965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.197492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-05T05:21:35.730975Z","caller":"traceutil/trace.go:171","msg":"trace[1404900714] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:589; }","duration":"520.230262ms","start":"2026-02-05T05:21:35.210742Z","end":"2026-02-05T05:21:35.730972Z","steps":["trace[1404900714] 'agreement among raft nodes before linearized reading' (duration: 520.197622ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:21:35.730985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:21:35.210729Z","time spent":"520.253312ms","remote":"127.0.0.1:50016","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-02-05T05:21:35.73106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.019117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-05T05:21:35.731072Z","caller":"traceutil/trace.go:171","msg":"trace[1753393101] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:589; }","duration":"537.062397ms","start":"2026-02-05T05:21:35.194006Z","end":"2026-02-05T05:21:35.731068Z","steps":["trace[1753393101] 'agreement among raft nodes before linearized reading' (duration: 537.023737ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:21:35.731083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:21:35.193999Z","time spent":"537.081047ms","remote":"127.0.0.1:50016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-05T05:21:35.731151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.086733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-05T05:21:35.731162Z","caller":"traceutil/trace.go:171","msg":"trace[1111718033] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:589; }","duration":"570.119963ms","start":"2026-02-05T05:21:35.161038Z","end":"2026-02-05T05:21:35.731158Z","steps":["trace[1111718033] 'agreement among raft nodes before linearized reading' (duration: 570.085972ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:21:35.731174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:21:35.161025Z","time spent":"570.146903ms","remote":"127.0.0.1:50016","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-02-05T05:21:35.731228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.198466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:21:35.73124Z","caller":"traceutil/trace.go:171","msg":"trace[1047848606] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:589; }","duration":"603.245587ms","start":"2026-02-05T05:21:35.127991Z","end":"2026-02-05T05:21:35.731236Z","steps":["trace[1047848606] 'agreement among raft nodes before linearized reading' (duration: 603.227877ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:21:35.731252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:21:35.127973Z","time spent":"603.274147ms","remote":"127.0.0.1:49714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-05T05:21:41.928183Z","caller":"traceutil/trace.go:171","msg":"trace[1947390204] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:639; }","duration":"103.215593ms","start":"2026-02-05T05:21:41.824952Z","end":"2026-02-05T05:21:41.928167Z","steps":["trace[1947390204] 'read index received' (duration: 103.081968ms)","trace[1947390204] 'applied index is now lower than readState.Index' (duration: 132.875µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:21:41.928382Z","caller":"traceutil/trace.go:171","msg":"trace[1032376338] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"118.818928ms","start":"2026-02-05T05:21:41.809558Z","end":"2026-02-05T05:21:41.928376Z","steps":["trace[1032376338] 'process raft request' (duration: 118.519677ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:21:41.928552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.587617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-05T05:21:41.92857Z","caller":"traceutil/trace.go:171","msg":"trace[414851700] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:621; }","duration":"103.636489ms","start":"2026-02-05T05:21:41.824928Z","end":"2026-02-05T05:21:41.928564Z","steps":["trace[414851700] 'agreement among raft nodes before linearized reading' (duration: 103.557266ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:21:42.34317Z","caller":"traceutil/trace.go:171","msg":"trace[1263372970] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"411.75739ms","start":"2026-02-05T05:21:41.931397Z","end":"2026-02-05T05:21:42.343154Z","steps":["trace[1263372970] 'process raft request' (duration: 411.622845ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:21:42.343453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:21:41.931387Z","time spent":"412.004219ms","remote":"127.0.0.1:50016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T05:21:42.37681Z","caller":"traceutil/trace.go:171","msg":"trace[1785316967] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"442.686434ms","start":"2026-02-05T05:21:41.934108Z","end":"2026-02-05T05:21:42.376795Z","steps":["trace[1785316967] 'process raft request' (duration: 442.666583ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:21:42.376906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:21:41.934101Z","time spent":"442.755077ms","remote":"127.0.0.1:50016","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-02-05T05:21:42.377112Z","caller":"traceutil/trace.go:171","msg":"trace[2061207531] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"445.478023ms","start":"2026-02-05T05:21:41.931629Z","end":"2026-02-05T05:21:42.377107Z","steps":["trace[2061207531] 'process raft request' (duration: 445.055817ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:21:42.377137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:21:41.931623Z","time spent":"445.500304ms","remote":"127.0.0.1:50016","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-02-05T05:21:42.377188Z","caller":"traceutil/trace.go:171","msg":"trace[1616995190] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"444.176602ms","start":"2026-02-05T05:21:41.933007Z","end":"2026-02-05T05:21:42.377184Z","steps":["trace[1616995190] 'process raft request' (duration: 443.750856ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:21:42.377209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:21:41.933002Z","time spent":"444.195642ms","remote":"127.0.0.1:50016","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-02-05T05:21:44.448033Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17707762498716372303,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T05:21:44.9484Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17707762498716372303,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T05:21:45.448711Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17707762498716372303,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T05:21:45.905716Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.381363152s","expected-duration":"1s"} {"level":"info","ts":"2026-02-05T05:21:45.906367Z","caller":"traceutil/trace.go:171","msg":"trace[1174815541] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:647; }","duration":"1.971792679s","start":"2026-02-05T05:21:43.934552Z","end":"2026-02-05T05:21:45.906345Z","steps":["trace[1174815541] 'read index received' (duration: 1.971598002s)","trace[1174815541] 'applied index is now lower than readState.Index' (duration: 193.717µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:21:45.906861Z","caller":"traceutil/trace.go:171","msg":"trace[359221347] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"2.382598557s","start":"2026-02-05T05:21:43.524247Z","end":"2026-02-05T05:21:45.906846Z","steps":["trace[359221347] 'process raft request' (duration: 2.381987174s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:21:45.907345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:21:43.524231Z","time spent":"2.382810925s","remote":"127.0.0.1:49920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:21:45.907938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.973386078s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-05T05:21:45.908141Z","caller":"traceutil/trace.go:171","msg":"trace[477090070] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:629; }","duration":"1.973623968s","start":"2026-02-05T05:21:43.934503Z","end":"2026-02-05T05:21:45.908127Z","steps":["trace[477090070] 'agreement among raft nodes before linearized reading' (duration: 1.973308266s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:21:45.908378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:21:43.93448Z","time spent":"1.973882577s","remote":"127.0.0.1:50016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-05T05:21:45.908841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.779612476s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:21:45.909047Z","caller":"traceutil/trace.go:171","msg":"trace[1235438914] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:629; }","duration":"1.779876746s","start":"2026-02-05T05:21:44.129156Z","end":"2026-02-05T05:21:45.909033Z","steps":["trace[1235438914] 'agreement among raft nodes before linearized reading' (duration: 1.779649758s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:21:45.909337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:21:44.129126Z","time spent":"1.780197118s","remote":"127.0.0.1:49714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T05:21:45.916362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.248442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.49\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-05T05:21:45.917028Z","caller":"traceutil/trace.go:171","msg":"trace[1267550027] range","detail":"{range_begin:/registry/masterleases/162.253.55.49; range_end:; response_count:1; response_revision:629; }","duration":"380.934008ms","start":"2026-02-05T05:21:45.536083Z","end":"2026-02-05T05:21:45.917017Z","steps":["trace[1267550027] 'agreement among raft nodes before linearized reading' (duration: 380.200131ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:21:45.917175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:21:45.53607Z","time spent":"381.095673ms","remote":"127.0.0.1:49744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/162.253.55.49\" "} {"level":"warn","ts":"2026-02-05T05:21:45.917458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.534948157s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-05T05:21:45.917668Z","caller":"traceutil/trace.go:171","msg":"trace[344614010] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:629; }","duration":"1.535113222s","start":"2026-02-05T05:21:44.382481Z","end":"2026-02-05T05:21:45.917594Z","steps":["trace[344614010] 'agreement among raft nodes before linearized reading' (duration: 1.534929785s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:21:45.917801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:21:44.382469Z","time spent":"1.535304509s","remote":"127.0.0.1:50016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-05T05:21:45.91805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.53561371s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-05T05:21:45.918146Z","caller":"traceutil/trace.go:171","msg":"trace[1478170248] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:629; }","duration":"1.535726845s","start":"2026-02-05T05:21:44.38241Z","end":"2026-02-05T05:21:45.918137Z","steps":["trace[1478170248] 'agreement among raft nodes before linearized reading' (duration: 1.53559031s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:21:45.918272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:21:44.382402Z","time spent":"1.535864089s","remote":"127.0.0.1:50016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-05T05:21:45.918466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.536707801s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-05T05:21:45.91855Z","caller":"traceutil/trace.go:171","msg":"trace[172721242] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:629; }","duration":"1.536823207s","start":"2026-02-05T05:21:44.381721Z","end":"2026-02-05T05:21:45.918544Z","steps":["trace[172721242] 'agreement among raft nodes before linearized reading' (duration: 1.536711042s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:21:45.918622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:21:44.381696Z","time spent":"1.536916889s","remote":"127.0.0.1:50016","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-02-05T05:21:45.965191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.61810682s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-05T05:21:45.965332Z","caller":"traceutil/trace.go:171","msg":"trace[1508132644] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:629; }","duration":"1.61837133s","start":"2026-02-05T05:21:44.34693Z","end":"2026-02-05T05:21:45.965302Z","steps":["trace[1508132644] 'agreement among raft nodes before linearized reading' (duration: 1.571811466s)","trace[1508132644] 'range keys from in-memory index tree' (duration: 46.133978ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:21:45.965411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:21:44.346906Z","time spent":"1.618492504s","remote":"127.0.0.1:50016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-05T05:22:01.349633Z","caller":"traceutil/trace.go:171","msg":"trace[433338189] linearizableReadLoop","detail":"{readStateIndex:796; appliedIndex:795; }","duration":"223.859461ms","start":"2026-02-05T05:22:01.125755Z","end":"2026-02-05T05:22:01.349614Z","steps":["trace[433338189] 'read index received' (duration: 131.986598ms)","trace[433338189] 'applied index is now lower than readState.Index' (duration: 91.871833ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:22:01.349783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.014416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:22:01.34981Z","caller":"traceutil/trace.go:171","msg":"trace[110858754] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:771; }","duration":"224.080098ms","start":"2026-02-05T05:22:01.125723Z","end":"2026-02-05T05:22:01.349803Z","steps":["trace[110858754] 'agreement among raft nodes before linearized reading' (duration: 224.016536ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:22:01.350004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.873839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:22:01.350045Z","caller":"traceutil/trace.go:171","msg":"trace[1901556843] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:771; }","duration":"173.93662ms","start":"2026-02-05T05:22:01.176102Z","end":"2026-02-05T05:22:01.350038Z","steps":["trace[1901556843] 'agreement among raft nodes before linearized reading' (duration: 173.881279ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:22:01.35022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.725322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:22:01.350246Z","caller":"traceutil/trace.go:171","msg":"trace[1416457326] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:771; }","duration":"152.760852ms","start":"2026-02-05T05:22:01.197479Z","end":"2026-02-05T05:22:01.35024Z","steps":["trace[1416457326] 'agreement among raft nodes before linearized reading' (duration: 152.718511ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:22:01.350285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.805384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:22:01.350304Z","caller":"traceutil/trace.go:171","msg":"trace[1011166744] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:771; }","duration":"152.832345ms","start":"2026-02-05T05:22:01.197467Z","end":"2026-02-05T05:22:01.350299Z","steps":["trace[1011166744] 'agreement among raft nodes before linearized reading' (duration: 152.804314ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:22:01.350398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.935298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:22:01.35044Z","caller":"traceutil/trace.go:171","msg":"trace[996475111] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:771; }","duration":"152.978419ms","start":"2026-02-05T05:22:01.197456Z","end":"2026-02-05T05:22:01.350434Z","steps":["trace[996475111] 'agreement among raft nodes before linearized reading' (duration: 152.927858ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:22:01.350526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.123382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:22:01.350548Z","caller":"traceutil/trace.go:171","msg":"trace[294707643] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:771; }","duration":"160.158863ms","start":"2026-02-05T05:22:01.190384Z","end":"2026-02-05T05:22:01.350543Z","steps":["trace[294707643] 'agreement among raft nodes before linearized reading' (duration: 160.124172ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:22:01.350637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.252055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:22:01.350659Z","caller":"traceutil/trace.go:171","msg":"trace[1051625421] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:771; }","duration":"160.282426ms","start":"2026-02-05T05:22:01.190372Z","end":"2026-02-05T05:22:01.350654Z","steps":["trace[1051625421] 'agreement among raft nodes before linearized reading' (duration: 160.251385ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:22:01.350741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.38177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:22:01.350763Z","caller":"traceutil/trace.go:171","msg":"trace[528576249] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:771; }","duration":"160.456742ms","start":"2026-02-05T05:22:01.190301Z","end":"2026-02-05T05:22:01.350758Z","steps":["trace[528576249] 'agreement among raft nodes before linearized reading' (duration: 160.425001ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:22:01.350838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.680404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:22:01.35086Z","caller":"traceutil/trace.go:171","msg":"trace[1380098526] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:771; }","duration":"174.707494ms","start":"2026-02-05T05:22:01.176148Z","end":"2026-02-05T05:22:01.350855Z","steps":["trace[1380098526] 'agreement among raft nodes before linearized reading' (duration: 174.678953ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:22:01.350935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.787997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:22:01.350957Z","caller":"traceutil/trace.go:171","msg":"trace[1311457327] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:771; }","duration":"174.816717ms","start":"2026-02-05T05:22:01.176135Z","end":"2026-02-05T05:22:01.350952Z","steps":["trace[1311457327] 'agreement among raft nodes before linearized reading' (duration: 174.787816ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:22:29.645186Z","caller":"traceutil/trace.go:171","msg":"trace[1669117787] transaction","detail":"{read_only:false; response_revision:861; number_of_response:1; }","duration":"125.695544ms","start":"2026-02-05T05:22:29.519475Z","end":"2026-02-05T05:22:29.645171Z","steps":["trace[1669117787] 'process raft request' (duration: 125.584542ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:22:30.811619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.690518ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-05T05:22:34.484028Z","caller":"traceutil/trace.go:171","msg":"trace[1754766244] transaction","detail":"{read_only:false; response_revision:872; number_of_response:1; }","duration":"126.285611ms","start":"2026-02-05T05:22:34.357724Z","end":"2026-02-05T05:22:34.48401Z","steps":["trace[1754766244] 'process raft request' (duration: 126.170679ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:22:59.112188Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17707762498716373080,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T05:22:59.369945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.541456ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T05:22:59.370121Z","caller":"traceutil/trace.go:171","msg":"trace[2070457658] linearizableReadLoop","detail":"{readStateIndex:991; appliedIndex:990; }","duration":"757.950188ms","start":"2026-02-05T05:22:58.612078Z","end":"2026-02-05T05:22:59.370028Z","steps":["trace[2070457658] 'read index received' (duration: 571.041855ms)","trace[2070457658] 'applied index is now lower than readState.Index' (duration: 186.906743ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:22:59.370603Z","caller":"traceutil/trace.go:171","msg":"trace[985439449] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"959.037412ms","start":"2026-02-05T05:22:58.411551Z","end":"2026-02-05T05:22:59.370588Z","steps":["trace[985439449] 'process raft request' (duration: 771.736022ms)","trace[985439449] 'compare' (duration: 185.882955ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:22:59.370732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:22:58.411545Z","time spent":"959.100613ms","remote":"127.0.0.1:50016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:22:59.371137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.032289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-05T05:22:59.371225Z","caller":"traceutil/trace.go:171","msg":"trace[37302941] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:954; }","duration":"759.147151ms","start":"2026-02-05T05:22:58.612022Z","end":"2026-02-05T05:22:59.371169Z","steps":["trace[37302941] 'agreement among raft nodes before linearized reading' (duration: 759.035689ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:22:59.371274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:22:58.612001Z","time spent":"759.257524ms","remote":"127.0.0.1:50140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":30,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-02-05T05:22:59.371667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.65008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-05T05:22:59.371709Z","caller":"traceutil/trace.go:171","msg":"trace[1671288191] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:954; }","duration":"670.739632ms","start":"2026-02-05T05:22:58.700958Z","end":"2026-02-05T05:22:59.371697Z","steps":["trace[1671288191] 'agreement among raft nodes before linearized reading' (duration: 670.604039ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:22:59.37178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:22:58.700925Z","time spent":"670.846274ms","remote":"127.0.0.1:50016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-05T05:22:59.372111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.627495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-05T05:22:59.372159Z","caller":"traceutil/trace.go:171","msg":"trace[771744321] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:954; }","duration":"236.700367ms","start":"2026-02-05T05:22:59.135449Z","end":"2026-02-05T05:22:59.372149Z","steps":["trace[771744321] 'agreement among raft nodes before linearized reading' (duration: 236.593545ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:22:59.372563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.372968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-62065dbc-f108-4de0-a6f6-7cb475f51dc6\" ","response":"range_response_count:1 size:3534"} {"level":"info","ts":"2026-02-05T05:22:59.372604Z","caller":"traceutil/trace.go:171","msg":"trace[2099116710] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-62065dbc-f108-4de0-a6f6-7cb475f51dc6; range_end:; response_count:1; response_revision:954; }","duration":"667.436409ms","start":"2026-02-05T05:22:58.705157Z","end":"2026-02-05T05:22:59.372594Z","steps":["trace[2099116710] 'agreement among raft nodes before linearized reading' (duration: 667.317137ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:22:59.372678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:22:58.705147Z","time spent":"667.52085ms","remote":"127.0.0.1:49920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3557,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-62065dbc-f108-4de0-a6f6-7cb475f51dc6\" "} {"level":"warn","ts":"2026-02-05T05:22:59.373367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.585002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-62065dbc-f108-4de0-a6f6-7cb475f51dc6\" ","response":"range_response_count:1 size:3534"} {"level":"info","ts":"2026-02-05T05:22:59.373413Z","caller":"traceutil/trace.go:171","msg":"trace[1310939463] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-62065dbc-f108-4de0-a6f6-7cb475f51dc6; range_end:; response_count:1; response_revision:954; }","duration":"539.655894ms","start":"2026-02-05T05:22:58.833746Z","end":"2026-02-05T05:22:59.373402Z","steps":["trace[1310939463] 'agreement among raft nodes before linearized reading' (duration: 539.548862ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:22:59.373533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:22:58.833735Z","time spent":"539.786707ms","remote":"127.0.0.1:49920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3557,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-62065dbc-f108-4de0-a6f6-7cb475f51dc6\" "} {"level":"warn","ts":"2026-02-05T05:22:59.373478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.101318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-05T05:22:59.374258Z","caller":"traceutil/trace.go:171","msg":"trace[872432832] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:954; }","duration":"604.819951ms","start":"2026-02-05T05:22:58.769406Z","end":"2026-02-05T05:22:59.374226Z","steps":["trace[872432832] 'agreement among raft nodes before linearized reading' (duration: 603.030437ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:22:59.373789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.761188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:22:59.374377Z","caller":"traceutil/trace.go:171","msg":"trace[1983739009] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:954; }","duration":"244.347068ms","start":"2026-02-05T05:22:59.130018Z","end":"2026-02-05T05:22:59.374365Z","steps":["trace[1983739009] 'agreement among raft nodes before linearized reading' (duration: 243.743857ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:22:59.374491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:22:58.769377Z","time spent":"604.975863ms","remote":"127.0.0.1:50016","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-02-05T05:22:59.373981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.889361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-05T05:22:59.374725Z","caller":"traceutil/trace.go:171","msg":"trace[526554910] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:954; }","duration":"576.649765ms","start":"2026-02-05T05:22:58.798064Z","end":"2026-02-05T05:22:59.374714Z","steps":["trace[526554910] 'agreement among raft nodes before linearized reading' (duration: 575.85752ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:22:59.374905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:22:58.798055Z","time spent":"576.762566ms","remote":"127.0.0.1:50016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-05T05:22:59.374053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.331357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-05T05:22:59.375135Z","caller":"traceutil/trace.go:171","msg":"trace[1383285194] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:954; }","duration":"578.407867ms","start":"2026-02-05T05:22:58.796716Z","end":"2026-02-05T05:22:59.375124Z","steps":["trace[1383285194] 'agreement among raft nodes before linearized reading' (duration: 577.293277ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:22:59.375371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:22:58.796675Z","time spent":"578.5482ms","remote":"127.0.0.1:50016","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-02-05T05:22:59.566436Z","caller":"traceutil/trace.go:171","msg":"trace[67032061] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"182.602474ms","start":"2026-02-05T05:22:59.383737Z","end":"2026-02-05T05:22:59.566339Z","steps":["trace[67032061] 'process raft request' (duration: 146.596477ms)","trace[67032061] 'compare' (duration: 35.59403ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:22:59.587449Z","caller":"traceutil/trace.go:171","msg":"trace[1596470727] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"198.04797ms","start":"2026-02-05T05:22:59.389383Z","end":"2026-02-05T05:22:59.587431Z","steps":["trace[1596470727] 'process raft request' (duration: 197.874827ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:22:59.587481Z","caller":"traceutil/trace.go:171","msg":"trace[1664490908] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"196.740646ms","start":"2026-02-05T05:22:59.390715Z","end":"2026-02-05T05:22:59.587456Z","steps":["trace[1664490908] 'process raft request' (duration: 196.688575ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:22:59.587931Z","caller":"traceutil/trace.go:171","msg":"trace[1617411243] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"197.447428ms","start":"2026-02-05T05:22:59.39047Z","end":"2026-02-05T05:22:59.587917Z","steps":["trace[1617411243] 'process raft request' (duration: 196.863518ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:22:59.587968Z","caller":"traceutil/trace.go:171","msg":"trace[1727413177] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"197.325736ms","start":"2026-02-05T05:22:59.390635Z","end":"2026-02-05T05:22:59.587961Z","steps":["trace[1727413177] 'process raft request' (duration: 196.736545ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T05:22:59.588239Z","caller":"traceutil/trace.go:171","msg":"trace[2137680198] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"201.199739ms","start":"2026-02-05T05:22:59.387029Z","end":"2026-02-05T05:22:59.588229Z","steps":["trace[2137680198] 'process raft request' (duration: 200.073538ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:23:12.137699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.736782ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T05:23:12.138092Z","caller":"traceutil/trace.go:171","msg":"trace[794432189] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"324.630753ms","start":"2026-02-05T05:23:11.813433Z","end":"2026-02-05T05:23:12.138063Z","steps":["trace[794432189] 'process raft request' (duration: 53.350242ms)","trace[794432189] 'compare' (duration: 270.635551ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T05:23:12.138266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:23:11.813418Z","time spent":"324.809516ms","remote":"127.0.0.1:50016","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-02-05T05:23:12.165861Z","caller":"traceutil/trace.go:171","msg":"trace[2055528032] linearizableReadLoop","detail":"{readStateIndex:1058; appliedIndex:1056; }","duration":"111.079576ms","start":"2026-02-05T05:23:12.054761Z","end":"2026-02-05T05:23:12.165841Z","steps":["trace[2055528032] 'read index received' (duration: 110.899353ms)","trace[2055528032] 'applied index is now lower than readState.Index' (duration: 179.403µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T05:23:12.166337Z","caller":"traceutil/trace.go:171","msg":"trace[624841967] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"351.150653ms","start":"2026-02-05T05:23:11.815173Z","end":"2026-02-05T05:23:12.166324Z","steps":["trace[624841967] 'process raft request' (duration: 350.538973ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:23:12.168918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:23:11.815164Z","time spent":"353.692746ms","remote":"127.0.0.1:50016","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-02-05T05:23:12.166801Z","caller":"traceutil/trace.go:171","msg":"trace[285631750] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"350.3763ms","start":"2026-02-05T05:23:11.816407Z","end":"2026-02-05T05:23:12.166783Z","steps":["trace[285631750] 'process raft request' (duration: 349.402084ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:23:12.169267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T05:23:11.816399Z","time spent":"352.73358ms","remote":"127.0.0.1:50016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T05:23:12.166863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.086303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T05:23:12.169453Z","caller":"traceutil/trace.go:171","msg":"trace[136479137] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1017; }","duration":"114.689018ms","start":"2026-02-05T05:23:12.054755Z","end":"2026-02-05T05:23:12.169444Z","steps":["trace[136479137] 'agreement among raft nodes before linearized reading' (duration: 112.073734ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T05:23:20.401527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.873188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:kube-apiserver-client-approver\" ","response":"range_response_count:1 size:702"} {"level":"info","ts":"2026-02-05T05:23:20.402278Z","caller":"traceutil/trace.go:171","msg":"trace[1353137371] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:kube-apiserver-client-approver; range_end:; response_count:1; response_revision:1043; }","duration":"115.65091ms","start":"2026-02-05T05:23:20.286608Z","end":"2026-02-05T05:23:20.402259Z","steps":["trace[1353137371] 'range keys from in-memory index tree' (duration: 114.771186ms)"],"step_count":1}