{"level":"warn","ts":"2026-02-12T16:50:33.983305Z","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-12T16:50:33.983552Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.87:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.87:2380","--initial-cluster=instance=https://199.19.213.87:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.87:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.87: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-12T16:50:33.983928Z","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-12T16:50:33.984055Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.87:2380"]} {"level":"info","ts":"2026-02-12T16:50:33.984137Z","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-12T16:50:33.986766Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.87:2379"]} {"level":"info","ts":"2026-02-12T16:50:33.986916Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.87:2380"],"listen-peer-urls":["https://199.19.213.87:2380"],"advertise-client-urls":["https://199.19.213.87:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.87:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.87: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-12T16:50:33.991156Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.922241ms"} {"level":"info","ts":"2026-02-12T16:50:33.998112Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"bc3cb673a921c717","cluster-id":"f315f77360ac6549"} {"level":"info","ts":"2026-02-12T16:50:33.998264Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 switched to configuration voters=()"} {"level":"info","ts":"2026-02-12T16:50:33.998304Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 became follower at term 0"} {"level":"info","ts":"2026-02-12T16:50:33.998335Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft bc3cb673a921c717 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-12T16:50:33.998351Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 became follower at term 1"} {"level":"info","ts":"2026-02-12T16:50:33.998409Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 switched to configuration voters=(13563916785608148759)"} {"level":"warn","ts":"2026-02-12T16:50:34.006554Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-12T16:50:34.014182Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-12T16:50:34.019454Z","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-12T16:50:34.05816Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"bc3cb673a921c717","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-12T16:50:34.060376Z","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-12T16:50:34.060694Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"bc3cb673a921c717","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-12T16:50:34.060893Z","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-12T16:50:34.060969Z","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-12T16:50:34.061031Z","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-12T16:50:34.062698Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.87:2380"} {"level":"info","ts":"2026-02-12T16:50:34.062768Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.87:2380"} {"level":"info","ts":"2026-02-12T16:50:34.063814Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"bc3cb673a921c717","initial-advertise-peer-urls":["https://199.19.213.87:2380"],"listen-peer-urls":["https://199.19.213.87:2380"],"advertise-client-urls":["https://199.19.213.87:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.87:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-12T16:50:34.064036Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-12T16:50:34.081271Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 switched to configuration voters=(13563916785608148759)"} {"level":"info","ts":"2026-02-12T16:50:34.081388Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f315f77360ac6549","local-member-id":"bc3cb673a921c717","added-peer-id":"bc3cb673a921c717","added-peer-peer-urls":["https://199.19.213.87:2380"]} {"level":"info","ts":"2026-02-12T16:50:34.699678Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 is starting a new election at term 1"} {"level":"info","ts":"2026-02-12T16:50:34.699817Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-12T16:50:34.699908Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 received MsgPreVoteResp from bc3cb673a921c717 at term 1"} {"level":"info","ts":"2026-02-12T16:50:34.699972Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 became candidate at term 2"} {"level":"info","ts":"2026-02-12T16:50:34.700026Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 received MsgVoteResp from bc3cb673a921c717 at term 2"} {"level":"info","ts":"2026-02-12T16:50:34.70007Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bc3cb673a921c717 became leader at term 2"} {"level":"info","ts":"2026-02-12T16:50:34.700123Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: bc3cb673a921c717 elected leader bc3cb673a921c717 at term 2"} {"level":"info","ts":"2026-02-12T16:50:34.707815Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T16:50:34.760499Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"bc3cb673a921c717","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.87:2379]}","request-path":"/0/members/bc3cb673a921c717/attributes","cluster-id":"f315f77360ac6549","publish-timeout":"7s"} {"level":"info","ts":"2026-02-12T16:50:34.76061Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-12T16:50:34.760936Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-12T16:50:34.762785Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-12T16:50:34.764533Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-12T16:50:34.764595Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-12T16:50:34.764707Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f315f77360ac6549","local-member-id":"bc3cb673a921c717","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T16:50:34.766856Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T16:50:34.766941Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T16:50:34.76659Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.87:2379"} {"level":"info","ts":"2026-02-12T16:50:36.795784Z","caller":"traceutil/trace.go:171","msg":"trace[451218410] transaction","detail":"{read_only:false; response_revision:74; number_of_response:1; }","duration":"127.272549ms","start":"2026-02-12T16:50:36.668488Z","end":"2026-02-12T16:50:36.79576Z","steps":["trace[451218410] 'process raft request' (duration: 85.87217ms)","trace[451218410] 'compare' (duration: 41.265814ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:50:37.095803Z","caller":"traceutil/trace.go:171","msg":"trace[48233614] transaction","detail":"{read_only:false; response_revision:75; number_of_response:1; }","duration":"103.942572ms","start":"2026-02-12T16:50:36.991827Z","end":"2026-02-12T16:50:37.09577Z","steps":["trace[48233614] 'process raft request' (duration: 103.767735ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:50:37.514053Z","caller":"traceutil/trace.go:171","msg":"trace[786204834] transaction","detail":"{read_only:false; response_revision:89; number_of_response:1; }","duration":"107.388612ms","start":"2026-02-12T16:50:37.406639Z","end":"2026-02-12T16:50:37.514027Z","steps":["trace[786204834] 'process raft request' (duration: 13.088133ms)","trace[786204834] 'compare' (duration: 94.114912ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:50:37.51443Z","caller":"traceutil/trace.go:171","msg":"trace[579243281] transaction","detail":"{read_only:false; response_revision:90; number_of_response:1; }","duration":"106.760479ms","start":"2026-02-12T16:50:37.407654Z","end":"2026-02-12T16:50:37.514414Z","steps":["trace[579243281] 'process raft request' (duration: 106.684316ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:50:37.808064Z","caller":"traceutil/trace.go:171","msg":"trace[1850970129] linearizableReadLoop","detail":"{readStateIndex:111; appliedIndex:110; }","duration":"108.099948ms","start":"2026-02-12T16:50:37.699915Z","end":"2026-02-12T16:50:37.808015Z","steps":["trace[1850970129] 'read index received' (duration: 64.137546ms)","trace[1850970129] 'applied index is now lower than readState.Index' (duration: 43.960742ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:50:37.808495Z","caller":"traceutil/trace.go:171","msg":"trace[758608059] transaction","detail":"{read_only:false; response_revision:106; number_of_response:1; }","duration":"150.095038ms","start":"2026-02-12T16:50:37.65838Z","end":"2026-02-12T16:50:37.808475Z","steps":["trace[758608059] 'process raft request' (duration: 105.729691ms)","trace[758608059] 'compare' (duration: 43.779165ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:50:37.808649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.695341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18938dd6f744c3ef\" ","response":"range_response_count:1 size:654"} {"level":"info","ts":"2026-02-12T16:50:37.809866Z","caller":"traceutil/trace.go:171","msg":"trace[143444325] range","detail":"{range_begin:/registry/events/default/instance.18938dd6f744c3ef; range_end:; response_count:1; response_revision:106; }","duration":"109.973298ms","start":"2026-02-12T16:50:37.699876Z","end":"2026-02-12T16:50:37.809849Z","steps":["trace[143444325] 'agreement among raft nodes before linearized reading' (duration: 108.662269ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:50:42.265081Z","caller":"traceutil/trace.go:171","msg":"trace[1701646811] transaction","detail":"{read_only:false; response_revision:331; number_of_response:1; }","duration":"134.206338ms","start":"2026-02-12T16:50:42.130848Z","end":"2026-02-12T16:50:42.265054Z","steps":["trace[1701646811] 'process raft request' (duration: 134.066492ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:50:42.591442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.928584ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-12T16:50:42.59159Z","caller":"traceutil/trace.go:171","msg":"trace[558310979] transaction","detail":"{read_only:false; response_revision:332; number_of_response:1; }","duration":"309.874005ms","start":"2026-02-12T16:50:42.281698Z","end":"2026-02-12T16:50:42.591572Z","steps":["trace[558310979] 'process raft request' (duration: 139.991026ms)","trace[558310979] 'compare' (duration: 168.821649ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:50:42.591639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:50:42.281677Z","time spent":"309.939088ms","remote":"127.0.0.1:34654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":192,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-12T16:50:53.225687Z","caller":"traceutil/trace.go:171","msg":"trace[3944808] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"106.701073ms","start":"2026-02-12T16:50:53.118969Z","end":"2026-02-12T16:50:53.22567Z","steps":["trace[3944808] 'process raft request' (duration: 106.484195ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:50:53.469971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.035154ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-12T16:50:53.470147Z","caller":"traceutil/trace.go:171","msg":"trace[788153440] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"238.55674ms","start":"2026-02-12T16:50:53.231572Z","end":"2026-02-12T16:50:53.470129Z","steps":["trace[788153440] 'process raft request' (duration: 238.486217ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:50:53.470417Z","caller":"traceutil/trace.go:171","msg":"trace[871574894] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"238.887123ms","start":"2026-02-12T16:50:53.231503Z","end":"2026-02-12T16:50:53.47039Z","steps":["trace[871574894] 'process raft request' (duration: 107.37399ms)","trace[871574894] 'compare' (duration: 130.92869ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:50:53.474419Z","caller":"traceutil/trace.go:171","msg":"trace[479028720] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"193.718463ms","start":"2026-02-12T16:50:53.280683Z","end":"2026-02-12T16:50:53.474402Z","steps":["trace[479028720] 'process raft request' (duration: 193.592818ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:50:53.474446Z","caller":"traceutil/trace.go:171","msg":"trace[611740464] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"191.526925ms","start":"2026-02-12T16:50:53.282902Z","end":"2026-02-12T16:50:53.474429Z","steps":["trace[611740464] 'process raft request' (duration: 191.497784ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:50:53.474625Z","caller":"traceutil/trace.go:171","msg":"trace[524157474] transaction","detail":"{read_only:false; response_revision:434; number_of_response:1; }","duration":"193.672701ms","start":"2026-02-12T16:50:53.280948Z","end":"2026-02-12T16:50:53.47462Z","steps":["trace[524157474] 'process raft request' (duration: 193.39547ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:50:53.474714Z","caller":"traceutil/trace.go:171","msg":"trace[578294896] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"192.39223ms","start":"2026-02-12T16:50:53.282262Z","end":"2026-02-12T16:50:53.474654Z","steps":["trace[578294896] 'process raft request' (duration: 192.101538ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:50:53.646516Z","caller":"traceutil/trace.go:171","msg":"trace[119845582] linearizableReadLoop","detail":"{readStateIndex:446; appliedIndex:445; }","duration":"163.295209ms","start":"2026-02-12T16:50:53.483201Z","end":"2026-02-12T16:50:53.646496Z","steps":["trace[119845582] 'read index received' (duration: 89.034789ms)","trace[119845582] 'applied index is now lower than readState.Index' (duration: 74.25817ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:50:53.646657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.435545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-77cccfdc44\" ","response":"range_response_count:1 size:3788"} {"level":"info","ts":"2026-02-12T16:50:53.646689Z","caller":"traceutil/trace.go:171","msg":"trace[1411876314] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-77cccfdc44; range_end:; response_count:1; response_revision:437; }","duration":"163.500798ms","start":"2026-02-12T16:50:53.483179Z","end":"2026-02-12T16:50:53.64668Z","steps":["trace[1411876314] 'agreement among raft nodes before linearized reading' (duration: 163.411724ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:50:53.646965Z","caller":"traceutil/trace.go:171","msg":"trace[852567822] transaction","detail":"{read_only:false; response_revision:437; number_of_response:1; }","duration":"169.32304ms","start":"2026-02-12T16:50:53.477619Z","end":"2026-02-12T16:50:53.646942Z","steps":["trace[852567822] 'process raft request' (duration: 94.677705ms)","trace[852567822] 'compare' (duration: 74.114734ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:50:53.896728Z","caller":"traceutil/trace.go:171","msg":"trace[1692157930] transaction","detail":"{read_only:false; response_revision:440; number_of_response:1; }","duration":"188.574166ms","start":"2026-02-12T16:50:53.708132Z","end":"2026-02-12T16:50:53.896706Z","steps":["trace[1692157930] 'process raft request' (duration: 188.521304ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:50:53.896999Z","caller":"traceutil/trace.go:171","msg":"trace[153509234] linearizableReadLoop","detail":"{readStateIndex:448; appliedIndex:447; }","duration":"192.955891ms","start":"2026-02-12T16:50:53.704031Z","end":"2026-02-12T16:50:53.896987Z","steps":["trace[153509234] 'read index received' (duration: 97.969966ms)","trace[153509234] 'applied index is now lower than readState.Index' (duration: 94.985125ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:50:53.897223Z","caller":"traceutil/trace.go:171","msg":"trace[1780004050] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"241.610131ms","start":"2026-02-12T16:50:53.655167Z","end":"2026-02-12T16:50:53.896777Z","steps":["trace[1780004050] 'process raft request' (duration: 146.827664ms)","trace[1780004050] 'compare' (duration: 94.535257ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:50:53.897289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.662683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4173"} {"level":"info","ts":"2026-02-12T16:50:53.897597Z","caller":"traceutil/trace.go:171","msg":"trace[753232584] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:440; }","duration":"241.996826ms","start":"2026-02-12T16:50:53.655586Z","end":"2026-02-12T16:50:53.897582Z","steps":["trace[753232584] 'agreement among raft nodes before linearized reading' (duration: 241.649082ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:50:53.897337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.769559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T16:50:53.897756Z","caller":"traceutil/trace.go:171","msg":"trace[995165570] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:440; }","duration":"157.185566ms","start":"2026-02-12T16:50:53.740562Z","end":"2026-02-12T16:50:53.897747Z","steps":["trace[995165570] 'agreement among raft nodes before linearized reading' (duration: 156.759089ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:50:54.297348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.763818ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-12T16:50:54.297517Z","caller":"traceutil/trace.go:171","msg":"trace[922690864] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"387.046718ms","start":"2026-02-12T16:50:53.910455Z","end":"2026-02-12T16:50:54.297501Z","steps":["trace[922690864] 'process raft request' (duration: 386.980375ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:50:54.297646Z","caller":"traceutil/trace.go:171","msg":"trace[429985885] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"388.948733ms","start":"2026-02-12T16:50:53.908664Z","end":"2026-02-12T16:50:54.297613Z","steps":["trace[429985885] 'process raft request' (duration: 135.855642ms)","trace[429985885] 'compare' (duration: 252.630353ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:50:54.29786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:50:53.908658Z","time spent":"389.13556ms","remote":"127.0.0.1:34536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":721,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-12T16:50:54.297706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:50:53.910449Z","time spent":"387.09931ms","remote":"127.0.0.1:34918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3818,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T16:50:54.52229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.18468ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T16:50:54.522826Z","caller":"traceutil/trace.go:171","msg":"trace[1288525760] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"579.889581ms","start":"2026-02-12T16:50:53.942917Z","end":"2026-02-12T16:50:54.522807Z","steps":["trace[1288525760] 'process raft request' (duration: 463.967711ms)","trace[1288525760] 'compare' (duration: 115.093987ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:50:54.522941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:50:53.942902Z","time spent":"579.974925ms","remote":"127.0.0.1:34918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3782,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T16:50:54.523171Z","caller":"traceutil/trace.go:171","msg":"trace[1779722447] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"579.158962ms","start":"2026-02-12T16:50:53.944003Z","end":"2026-02-12T16:50:54.523162Z","steps":["trace[1779722447] 'process raft request' (duration: 578.396002ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:50:54.5232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:50:53.943997Z","time spent":"579.188793ms","remote":"127.0.0.1:34536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-12T16:50:54.53097Z","caller":"traceutil/trace.go:171","msg":"trace[95138795] transaction","detail":"{read_only:false; response_revision:447; number_of_response:1; }","duration":"226.156773ms","start":"2026-02-12T16:50:54.304797Z","end":"2026-02-12T16:50:54.530954Z","steps":["trace[95138795] 'process raft request' (duration: 226.116641ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:50:54.531306Z","caller":"traceutil/trace.go:171","msg":"trace[1307143202] linearizableReadLoop","detail":"{readStateIndex:454; appliedIndex:450; }","duration":"586.570749ms","start":"2026-02-12T16:50:53.944727Z","end":"2026-02-12T16:50:54.531297Z","steps":["trace[1307143202] 'read index received' (duration: 99.802285ms)","trace[1307143202] 'applied index is now lower than readState.Index' (duration: 486.767634ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:50:54.531584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.83466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-77cccfdc44\" ","response":"range_response_count:1 size:3833"} {"level":"info","ts":"2026-02-12T16:50:54.531614Z","caller":"traceutil/trace.go:171","msg":"trace[418034503] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-77cccfdc44; range_end:; response_count:1; response_revision:447; }","duration":"586.927853ms","start":"2026-02-12T16:50:53.944678Z","end":"2026-02-12T16:50:54.531606Z","steps":["trace[418034503] 'agreement among raft nodes before linearized reading' (duration: 586.654192ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:50:54.531711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:50:53.944657Z","time spent":"587.046278ms","remote":"127.0.0.1:34918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":3857,"request content":"key:\"/registry/replicasets/kube-system/coredns-77cccfdc44\" "} {"level":"info","ts":"2026-02-12T16:50:54.532082Z","caller":"traceutil/trace.go:171","msg":"trace[91119119] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"227.271147ms","start":"2026-02-12T16:50:54.304783Z","end":"2026-02-12T16:50:54.532054Z","steps":["trace[91119119] 'process raft request' (duration: 226.070159ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:50:54.77716Z","caller":"traceutil/trace.go:171","msg":"trace[1249871242] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"230.59714ms","start":"2026-02-12T16:50:54.54654Z","end":"2026-02-12T16:50:54.777137Z","steps":["trace[1249871242] 'process raft request' (duration: 143.41857ms)","trace[1249871242] 'compare' (duration: 86.805155ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:50:54.777788Z","caller":"traceutil/trace.go:171","msg":"trace[655339401] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"231.023687ms","start":"2026-02-12T16:50:54.546749Z","end":"2026-02-12T16:50:54.777772Z","steps":["trace[655339401] 'process raft request' (duration: 230.125731ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:50:54.950327Z","caller":"traceutil/trace.go:171","msg":"trace[1584301610] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"161.419901ms","start":"2026-02-12T16:50:54.78889Z","end":"2026-02-12T16:50:54.95031Z","steps":["trace[1584301610] 'process raft request' (duration: 88.663819ms)","trace[1584301610] 'compare' (duration: 72.681619ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:50:54.953169Z","caller":"traceutil/trace.go:171","msg":"trace[384412784] linearizableReadLoop","detail":"{readStateIndex:466; appliedIndex:460; }","duration":"149.716772ms","start":"2026-02-12T16:50:54.803442Z","end":"2026-02-12T16:50:54.953159Z","steps":["trace[384412784] 'read index received' (duration: 74.119286ms)","trace[384412784] 'applied index is now lower than readState.Index' (duration: 75.596986ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:50:54.953381Z","caller":"traceutil/trace.go:171","msg":"trace[2075273643] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"161.268225ms","start":"2026-02-12T16:50:54.792103Z","end":"2026-02-12T16:50:54.953371Z","steps":["trace[2075273643] 'process raft request' (duration: 158.799796ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:50:54.953611Z","caller":"traceutil/trace.go:171","msg":"trace[1146646145] transaction","detail":"{read_only:false; response_revision:452; number_of_response:1; }","duration":"162.813457ms","start":"2026-02-12T16:50:54.790791Z","end":"2026-02-12T16:50:54.953604Z","steps":["trace[1146646145] 'process raft request' (duration: 160.057807ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:50:54.95388Z","caller":"traceutil/trace.go:171","msg":"trace[455977257] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"158.320257ms","start":"2026-02-12T16:50:54.795552Z","end":"2026-02-12T16:50:54.953873Z","steps":["trace[455977257] 'process raft request' (duration: 155.367769ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:50:54.954197Z","caller":"traceutil/trace.go:171","msg":"trace[105814655] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"157.39652ms","start":"2026-02-12T16:50:54.796793Z","end":"2026-02-12T16:50:54.954189Z","steps":["trace[105814655] 'process raft request' (duration: 156.328847ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:50:54.954555Z","caller":"traceutil/trace.go:171","msg":"trace[1426281745] transaction","detail":"{read_only:false; response_revision:453; number_of_response:1; }","duration":"162.473833ms","start":"2026-02-12T16:50:54.792072Z","end":"2026-02-12T16:50:54.954546Z","steps":["trace[1426281745] 'process raft request' (duration: 158.808676ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:50:54.955337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.88539ms","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-12T16:50:54.955372Z","caller":"traceutil/trace.go:171","msg":"trace[1288382749] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:456; }","duration":"151.958413ms","start":"2026-02-12T16:50:54.803405Z","end":"2026-02-12T16:50:54.955364Z","steps":["trace[1288382749] 'agreement among raft nodes before linearized reading' (duration: 151.89271ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:05.51705Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14346107017283998245,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T16:51:05.916017Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.056240882s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-12T16:51:05.917972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.126601323s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-12T16:51:05.918295Z","caller":"traceutil/trace.go:171","msg":"trace[975428438] linearizableReadLoop","detail":"{readStateIndex:494; appliedIndex:492; }","duration":"901.652213ms","start":"2026-02-12T16:51:05.016528Z","end":"2026-02-12T16:51:05.918181Z","steps":["trace[975428438] 'read index received' (duration: 899.67312ms)","trace[975428438] 'applied index is now lower than readState.Index' (duration: 1.978193ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:51:05.918504Z","caller":"traceutil/trace.go:171","msg":"trace[579996727] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"1.16383655s","start":"2026-02-12T16:51:04.754653Z","end":"2026-02-12T16:51:05.918489Z","steps":["trace[579996727] 'process raft request' (duration: 36.639202ms)","trace[579996727] 'compare' (duration: 1.12628789s)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:51:05.91857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:51:04.754639Z","time spent":"1.163900852s","remote":"127.0.0.1:34654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":505,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-12T16:51:05.918707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"827.319858ms","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-12T16:51:05.919239Z","caller":"traceutil/trace.go:171","msg":"trace[34381322] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:482; }","duration":"827.876601ms","start":"2026-02-12T16:51:05.091338Z","end":"2026-02-12T16:51:05.919215Z","steps":["trace[34381322] 'agreement among raft nodes before linearized reading' (duration: 827.244035ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:05.919336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:51:05.091327Z","time spent":"827.992645ms","remote":"127.0.0.1:34742","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-12T16:51:05.918915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.724682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T16:51:05.92046Z","caller":"traceutil/trace.go:171","msg":"trace[973242902] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:482; }","duration":"179.303808ms","start":"2026-02-12T16:51:05.741141Z","end":"2026-02-12T16:51:05.920444Z","steps":["trace[973242902] 'agreement among raft nodes before linearized reading' (duration: 177.732923ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:05.918951Z","caller":"traceutil/trace.go:171","msg":"trace[1723326648] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"1.164129751s","start":"2026-02-12T16:51:04.754811Z","end":"2026-02-12T16:51:05.91894Z","steps":["trace[1723326648] 'process raft request' (duration: 1.163258935s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:05.918998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"902.478838ms","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-12T16:51:05.922553Z","caller":"traceutil/trace.go:171","msg":"trace[728829268] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:482; }","duration":"906.002764ms","start":"2026-02-12T16:51:05.016475Z","end":"2026-02-12T16:51:05.922478Z","steps":["trace[728829268] 'agreement among raft nodes before linearized reading' (duration: 902.493138ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:05.922643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:51:05.016454Z","time spent":"906.172081ms","remote":"127.0.0.1:34742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-12T16:51:05.919048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"799.381354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-12T16:51:05.922946Z","caller":"traceutil/trace.go:171","msg":"trace[490844540] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:482; }","duration":"803.291897ms","start":"2026-02-12T16:51:05.119642Z","end":"2026-02-12T16:51:05.922934Z","steps":["trace[490844540] 'agreement among raft nodes before linearized reading' (duration: 799.383934ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:05.923065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:51:05.119627Z","time spent":"803.423632ms","remote":"127.0.0.1:34742","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-12T16:51:05.919092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"826.345537ms","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-12T16:51:05.923283Z","caller":"traceutil/trace.go:171","msg":"trace[417881843] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:482; }","duration":"830.573683ms","start":"2026-02-12T16:51:05.092702Z","end":"2026-02-12T16:51:05.923276Z","steps":["trace[417881843] 'agreement among raft nodes before linearized reading' (duration: 826.370928ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:05.923344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:51:05.092681Z","time spent":"830.632906ms","remote":"127.0.0.1:34742","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-12T16:51:05.923858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:51:04.754806Z","time spent":"1.165951407s","remote":"127.0.0.1:34654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-12T16:51:06.159338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.728079ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T16:51:06.159866Z","caller":"traceutil/trace.go:171","msg":"trace[1076999451] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"226.202642ms","start":"2026-02-12T16:51:05.933645Z","end":"2026-02-12T16:51:06.159848Z","steps":["trace[1076999451] 'process raft request' (duration: 226.1578ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:06.160585Z","caller":"traceutil/trace.go:171","msg":"trace[1407647281] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"230.21882ms","start":"2026-02-12T16:51:05.930347Z","end":"2026-02-12T16:51:06.160566Z","steps":["trace[1407647281] 'process raft request' (duration: 122.183266ms)","trace[1407647281] 'compare' (duration: 106.605284ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:51:06.161163Z","caller":"traceutil/trace.go:171","msg":"trace[959628074] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"230.747351ms","start":"2026-02-12T16:51:05.930402Z","end":"2026-02-12T16:51:06.16115Z","steps":["trace[959628074] 'process raft request' (duration: 229.061081ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:06.16156Z","caller":"traceutil/trace.go:171","msg":"trace[790546861] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"229.846054ms","start":"2026-02-12T16:51:05.931699Z","end":"2026-02-12T16:51:06.161545Z","steps":["trace[790546861] 'process raft request' (duration: 228.0724ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:06.165377Z","caller":"traceutil/trace.go:171","msg":"trace[1102476019] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"164.152518ms","start":"2026-02-12T16:51:06.001209Z","end":"2026-02-12T16:51:06.165362Z","steps":["trace[1102476019] 'process raft request' (duration: 159.372079ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:06.166168Z","caller":"traceutil/trace.go:171","msg":"trace[1412007548] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"164.947712ms","start":"2026-02-12T16:51:06.001207Z","end":"2026-02-12T16:51:06.166155Z","steps":["trace[1412007548] 'process raft request' (duration: 164.089676ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:06.459169Z","caller":"traceutil/trace.go:171","msg":"trace[1893518369] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"104.12656ms","start":"2026-02-12T16:51:06.35502Z","end":"2026-02-12T16:51:06.459147Z","steps":["trace[1893518369] 'process raft request' (duration: 103.978534ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:06.686431Z","caller":"traceutil/trace.go:171","msg":"trace[504834590] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"143.977226ms","start":"2026-02-12T16:51:06.542425Z","end":"2026-02-12T16:51:06.686402Z","steps":["trace[504834590] 'process raft request' (duration: 143.569679ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:06.686962Z","caller":"traceutil/trace.go:171","msg":"trace[561611612] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"137.059167ms","start":"2026-02-12T16:51:06.549882Z","end":"2026-02-12T16:51:06.686941Z","steps":["trace[561611612] 'process raft request' (duration: 136.459242ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:06.722599Z","caller":"traceutil/trace.go:171","msg":"trace[1296726251] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"164.025963ms","start":"2026-02-12T16:51:06.558556Z","end":"2026-02-12T16:51:06.722582Z","steps":["trace[1296726251] 'process raft request' (duration: 163.904498ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:06.722815Z","caller":"traceutil/trace.go:171","msg":"trace[1948494331] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"162.318962ms","start":"2026-02-12T16:51:06.56049Z","end":"2026-02-12T16:51:06.722809Z","steps":["trace[1948494331] 'process raft request' (duration: 162.006179ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:06.722837Z","caller":"traceutil/trace.go:171","msg":"trace[1619870260] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"162.277951ms","start":"2026-02-12T16:51:06.560555Z","end":"2026-02-12T16:51:06.722833Z","steps":["trace[1619870260] 'process raft request' (duration: 161.972248ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:06.722855Z","caller":"traceutil/trace.go:171","msg":"trace[437554392] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"172.282099ms","start":"2026-02-12T16:51:06.55057Z","end":"2026-02-12T16:51:06.722852Z","steps":["trace[437554392] 'process raft request' (duration: 171.682973ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:06.722884Z","caller":"traceutil/trace.go:171","msg":"trace[1200169661] linearizableReadLoop","detail":"{readStateIndex:517; appliedIndex:513; }","duration":"162.438407ms","start":"2026-02-12T16:51:06.560441Z","end":"2026-02-12T16:51:06.722879Z","steps":["trace[1200169661] 'read index received' (duration: 125.790176ms)","trace[1200169661] 'applied index is now lower than readState.Index' (duration: 36.647601ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:51:06.722988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.53018ms","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-12T16:51:06.724238Z","caller":"traceutil/trace.go:171","msg":"trace[1277575672] range","detail":"{range_begin:/registry/deployments/kube-system/cilium-operator; range_end:; response_count:1; response_revision:508; }","duration":"163.813874ms","start":"2026-02-12T16:51:06.560416Z","end":"2026-02-12T16:51:06.72423Z","steps":["trace[1277575672] 'agreement among raft nodes before linearized reading' (duration: 162.476568ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:06.723007Z","caller":"traceutil/trace.go:171","msg":"trace[319078557] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"162.446998ms","start":"2026-02-12T16:51:06.560554Z","end":"2026-02-12T16:51:06.723001Z","steps":["trace[319078557] 'process raft request' (duration: 161.999379ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:06.845961Z","caller":"traceutil/trace.go:171","msg":"trace[1259270459] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"108.648489ms","start":"2026-02-12T16:51:06.737267Z","end":"2026-02-12T16:51:06.845915Z","steps":["trace[1259270459] 'process raft request' (duration: 62.932269ms)","trace[1259270459] 'compare' (duration: 45.528632ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:51:06.848602Z","caller":"traceutil/trace.go:171","msg":"trace[322052840] linearizableReadLoop","detail":"{readStateIndex:524; appliedIndex:521; }","duration":"104.853971ms","start":"2026-02-12T16:51:06.74373Z","end":"2026-02-12T16:51:06.848584Z","steps":["trace[322052840] 'read index received' (duration: 56.523712ms)","trace[322052840] 'applied index is now lower than readState.Index' (duration: 48.329689ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:51:06.848852Z","caller":"traceutil/trace.go:171","msg":"trace[415478144] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"105.331541ms","start":"2026-02-12T16:51:06.743503Z","end":"2026-02-12T16:51:06.848834Z","steps":["trace[415478144] 'process raft request' (duration: 104.878292ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:06.849209Z","caller":"traceutil/trace.go:171","msg":"trace[643621895] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"105.570561ms","start":"2026-02-12T16:51:06.74363Z","end":"2026-02-12T16:51:06.849201Z","steps":["trace[643621895] 'process raft request' (duration: 104.852271ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:06.849584Z","caller":"traceutil/trace.go:171","msg":"trace[1186960228] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"103.809697ms","start":"2026-02-12T16:51:06.745763Z","end":"2026-02-12T16:51:06.849573Z","steps":["trace[1186960228] 'process raft request' (duration: 102.790125ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:06.849852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.105183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T16:51:06.849947Z","caller":"traceutil/trace.go:171","msg":"trace[1579700593] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:513; }","duration":"106.227079ms","start":"2026-02-12T16:51:06.743711Z","end":"2026-02-12T16:51:06.849938Z","steps":["trace[1579700593] 'agreement among raft nodes before linearized reading' (duration: 106.081772ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:09.069732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.24889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.87\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-12T16:51:09.069792Z","caller":"traceutil/trace.go:171","msg":"trace[543906418] range","detail":"{range_begin:/registry/masterleases/199.19.213.87; range_end:; response_count:1; response_revision:523; }","duration":"392.361925ms","start":"2026-02-12T16:51:08.677416Z","end":"2026-02-12T16:51:09.069778Z","steps":["trace[543906418] 'range keys from in-memory index tree' (duration: 392.164827ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:09.069838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:51:08.677402Z","time spent":"392.428978ms","remote":"127.0.0.1:34494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.87\" "} {"level":"warn","ts":"2026-02-12T16:51:10.344991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.455071ms","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-12T16:51:10.345052Z","caller":"traceutil/trace.go:171","msg":"trace[1346675627] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:524; }","duration":"162.547555ms","start":"2026-02-12T16:51:10.182489Z","end":"2026-02-12T16:51:10.345037Z","steps":["trace[1346675627] 'range keys from in-memory index tree' (duration: 162.339837ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:10.345258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.552182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T16:51:10.345282Z","caller":"traceutil/trace.go:171","msg":"trace[977926721] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:524; }","duration":"606.599414ms","start":"2026-02-12T16:51:09.738676Z","end":"2026-02-12T16:51:10.345276Z","steps":["trace[977926721] 'range keys from in-memory index tree' (duration: 606.513801ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:10.3453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:51:09.738663Z","time spent":"606.631896ms","remote":"127.0.0.1:34456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T16:51:10.34546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.319122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-12T16:51:10.345484Z","caller":"traceutil/trace.go:171","msg":"trace[981773241] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:524; }","duration":"169.383185ms","start":"2026-02-12T16:51:10.176095Z","end":"2026-02-12T16:51:10.345478Z","steps":["trace[981773241] 'range keys from in-memory index tree' (duration: 169.246178ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:10.34557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.549166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-12T16:51:10.345588Z","caller":"traceutil/trace.go:171","msg":"trace[452425726] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:524; }","duration":"167.586848ms","start":"2026-02-12T16:51:10.177995Z","end":"2026-02-12T16:51:10.345582Z","steps":["trace[452425726] 'range keys from in-memory index tree' (duration: 167.503615ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:10.345703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.568791ms","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-12T16:51:10.345725Z","caller":"traceutil/trace.go:171","msg":"trace[1230785089] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:524; }","duration":"164.612303ms","start":"2026-02-12T16:51:10.181107Z","end":"2026-02-12T16:51:10.345719Z","steps":["trace[1230785089] 'range keys from in-memory index tree' (duration: 164.476027ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:10.619006Z","caller":"traceutil/trace.go:171","msg":"trace[859374657] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"266.857307ms","start":"2026-02-12T16:51:10.352125Z","end":"2026-02-12T16:51:10.618983Z","steps":["trace[859374657] 'process raft request' (duration: 266.718562ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:11.120627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.728817ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T16:51:11.12076Z","caller":"traceutil/trace.go:171","msg":"trace[1484628971] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"259.344915ms","start":"2026-02-12T16:51:10.861405Z","end":"2026-02-12T16:51:11.12075Z","steps":["trace[1484628971] 'process raft request' (duration: 259.323454ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:11.12092Z","caller":"traceutil/trace.go:171","msg":"trace[1324234663] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"768.836015ms","start":"2026-02-12T16:51:10.352078Z","end":"2026-02-12T16:51:11.120914Z","steps":["trace[1324234663] 'process raft request' (duration: 514.767724ms)","trace[1324234663] 'compare' (duration: 253.552359ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:51:11.120961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:51:10.352065Z","time spent":"768.879827ms","remote":"127.0.0.1:34742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T16:51:11.121025Z","caller":"traceutil/trace.go:171","msg":"trace[1009872409] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"768.790404ms","start":"2026-02-12T16:51:10.35223Z","end":"2026-02-12T16:51:11.121021Z","steps":["trace[1009872409] 'process raft request' (duration: 768.456689ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:11.121048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:51:10.352222Z","time spent":"768.813784ms","remote":"127.0.0.1:34742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T16:51:11.121103Z","caller":"traceutil/trace.go:171","msg":"trace[2014021143] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"768.763892ms","start":"2026-02-12T16:51:10.352334Z","end":"2026-02-12T16:51:11.121098Z","steps":["trace[2014021143] 'process raft request' (duration: 768.377625ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:11.121124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:51:10.352328Z","time spent":"768.785113ms","remote":"127.0.0.1:34742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T16:51:11.121175Z","caller":"traceutil/trace.go:171","msg":"trace[1824216948] linearizableReadLoop","detail":"{readStateIndex:541; appliedIndex:538; }","duration":"378.639451ms","start":"2026-02-12T16:51:10.742531Z","end":"2026-02-12T16:51:11.121171Z","steps":["trace[1824216948] 'read index received' (duration: 124.388822ms)","trace[1824216948] 'applied index is now lower than readState.Index' (duration: 254.249959ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:51:11.121201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.676132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T16:51:11.121212Z","caller":"traceutil/trace.go:171","msg":"trace[895447232] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:529; }","duration":"378.724434ms","start":"2026-02-12T16:51:10.742484Z","end":"2026-02-12T16:51:11.121208Z","steps":["trace[895447232] 'agreement among raft nodes before linearized reading' (duration: 378.703804ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:11.121224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:51:10.742468Z","time spent":"378.753295ms","remote":"127.0.0.1:34456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-12T16:51:11.983024Z","caller":"traceutil/trace.go:171","msg":"trace[351232766] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"128.399457ms","start":"2026-02-12T16:51:11.8546Z","end":"2026-02-12T16:51:11.982992Z","steps":["trace[351232766] 'process raft request' (duration: 120.565295ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:12.099421Z","caller":"traceutil/trace.go:171","msg":"trace[517030744] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"107.106765ms","start":"2026-02-12T16:51:11.992294Z","end":"2026-02-12T16:51:12.099401Z","steps":["trace[517030744] 'process raft request' (duration: 103.758003ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:12.275594Z","caller":"traceutil/trace.go:171","msg":"trace[215673417] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"137.410667ms","start":"2026-02-12T16:51:12.138168Z","end":"2026-02-12T16:51:12.275578Z","steps":["trace[215673417] 'process raft request' (duration: 137.338043ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:12.357997Z","caller":"traceutil/trace.go:171","msg":"trace[2001022355] linearizableReadLoop","detail":"{readStateIndex:563; appliedIndex:561; }","duration":"153.787673ms","start":"2026-02-12T16:51:12.204186Z","end":"2026-02-12T16:51:12.357974Z","steps":["trace[2001022355] 'read index received' (duration: 71.69043ms)","trace[2001022355] 'applied index is now lower than readState.Index' (duration: 82.096433ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:51:12.358234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.002662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12727"} {"level":"info","ts":"2026-02-12T16:51:12.358273Z","caller":"traceutil/trace.go:171","msg":"trace[292169100] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:550; }","duration":"154.104556ms","start":"2026-02-12T16:51:12.204157Z","end":"2026-02-12T16:51:12.358262Z","steps":["trace[292169100] 'agreement among raft nodes before linearized reading' (duration: 153.911828ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:12.358633Z","caller":"traceutil/trace.go:171","msg":"trace[497169855] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"204.350364ms","start":"2026-02-12T16:51:12.154266Z","end":"2026-02-12T16:51:12.358617Z","steps":["trace[497169855] 'process raft request' (duration: 203.561741ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:12.35885Z","caller":"traceutil/trace.go:171","msg":"trace[359172249] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"184.250519ms","start":"2026-02-12T16:51:12.17459Z","end":"2026-02-12T16:51:12.358841Z","steps":["trace[359172249] 'process raft request' (duration: 183.34393ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:18.243549Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14346107017283998403,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T16:51:18.744479Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14346107017283998403,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T16:51:19.244767Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14346107017283998403,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T16:51:19.318187Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.5939416s","expected-duration":"1s"} {"level":"info","ts":"2026-02-12T16:51:19.318481Z","caller":"traceutil/trace.go:171","msg":"trace[1104242204] linearizableReadLoop","detail":"{readStateIndex:582; appliedIndex:581; }","duration":"1.577163006s","start":"2026-02-12T16:51:17.741302Z","end":"2026-02-12T16:51:19.318465Z","steps":["trace[1104242204] 'read index received' (duration: 1.576991089s)","trace[1104242204] 'applied index is now lower than readState.Index' (duration: 171.267µs)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:51:19.318792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.47395ms","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-12T16:51:19.318821Z","caller":"traceutil/trace.go:171","msg":"trace[1604172238] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:568; }","duration":"660.540283ms","start":"2026-02-12T16:51:18.658274Z","end":"2026-02-12T16:51:19.318814Z","steps":["trace[1604172238] 'agreement among raft nodes before linearized reading' (duration: 660.440199ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:19.318843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:51:18.658261Z","time spent":"660.576864ms","remote":"127.0.0.1:34742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-12T16:51:19.318903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.577426147s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-12T16:51:19.319049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.999168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-12T16:51:19.319075Z","caller":"traceutil/trace.go:171","msg":"trace[1441377145] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:568; }","duration":"104.044039ms","start":"2026-02-12T16:51:19.215025Z","end":"2026-02-12T16:51:19.319069Z","steps":["trace[1441377145] 'agreement among raft nodes before linearized reading' (duration: 103.985727ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:19.319165Z","caller":"traceutil/trace.go:171","msg":"trace[963047003] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:568; }","duration":"1.577783213s","start":"2026-02-12T16:51:17.741247Z","end":"2026-02-12T16:51:19.31903Z","steps":["trace[963047003] 'agreement among raft nodes before linearized reading' (duration: 1.577379205s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:19.319259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.917774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.87\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-12T16:51:19.319282Z","caller":"traceutil/trace.go:171","msg":"trace[363416341] range","detail":"{range_begin:/registry/masterleases/199.19.213.87; range_end:; response_count:1; response_revision:568; }","duration":"640.962376ms","start":"2026-02-12T16:51:18.678314Z","end":"2026-02-12T16:51:19.319276Z","steps":["trace[363416341] 'agreement among raft nodes before linearized reading' (duration: 640.919264ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:19.319302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:51:18.678287Z","time spent":"641.009888ms","remote":"127.0.0.1:34494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.87\" "} {"level":"warn","ts":"2026-02-12T16:51:19.319362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:51:17.741235Z","time spent":"1.578035714s","remote":"127.0.0.1:34470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-12T16:51:19.319565Z","caller":"traceutil/trace.go:171","msg":"trace[29149978] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"1.595418104s","start":"2026-02-12T16:51:17.724139Z","end":"2026-02-12T16:51:19.319557Z","steps":["trace[29149978] 'process raft request' (duration: 1.594222512s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:19.319624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:51:17.724128Z","time spent":"1.595461105s","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T16:51:19.319182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.12247ms","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-12T16:51:19.319758Z","caller":"traceutil/trace.go:171","msg":"trace[1197970230] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:568; }","duration":"115.697285ms","start":"2026-02-12T16:51:19.204055Z","end":"2026-02-12T16:51:19.319752Z","steps":["trace[1197970230] 'agreement among raft nodes before linearized reading' (duration: 115.090209ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:19.319223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.42663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-12T16:51:19.319838Z","caller":"traceutil/trace.go:171","msg":"trace[1054409987] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:568; }","duration":"124.039516ms","start":"2026-02-12T16:51:19.195793Z","end":"2026-02-12T16:51:19.319832Z","steps":["trace[1054409987] 'agreement among raft nodes before linearized reading' (duration: 123.401299ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:19.921085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.684872ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T16:51:19.921204Z","caller":"traceutil/trace.go:171","msg":"trace[1580706920] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"593.241564ms","start":"2026-02-12T16:51:19.327923Z","end":"2026-02-12T16:51:19.921164Z","steps":["trace[1580706920] 'process raft request' (duration: 137.407956ms)","trace[1580706920] 'compare' (duration: 455.577967ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:51:19.921266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:51:19.327908Z","time spent":"593.330389ms","remote":"127.0.0.1:34742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T16:51:21.335855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000138636s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-12T16:51:21.335974Z","caller":"traceutil/trace.go:171","msg":"trace[232740664] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00024817s","start":"2026-02-12T16:51:19.335667Z","end":"2026-02-12T16:51:21.335915Z","steps":["trace[232740664] 'agreement among raft nodes before linearized reading' (duration: 2.000149166s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:21.336029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:51:19.335658Z","time spent":"2.000361396s","remote":"127.0.0.1:34456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T16:51:21.629844Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.164513973s","expected-duration":"1s"} {"level":"info","ts":"2026-02-12T16:51:21.706351Z","caller":"traceutil/trace.go:171","msg":"trace[357384016] linearizableReadLoop","detail":"{readStateIndex:587; appliedIndex:582; }","duration":"2.370646535s","start":"2026-02-12T16:51:19.335685Z","end":"2026-02-12T16:51:21.706332Z","steps":["trace[357384016] 'read index received' (duration: 129.65554ms)","trace[357384016] 'applied index is now lower than readState.Index' (duration: 2.240990105s)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:51:21.706438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:51:19.328158Z","time spent":"2.378278126s","remote":"127.0.0.1:34494","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-12T16:51:21.706705Z","caller":"traceutil/trace.go:171","msg":"trace[810592290] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"2.378249164s","start":"2026-02-12T16:51:19.328442Z","end":"2026-02-12T16:51:21.706691Z","steps":["trace[810592290] 'process raft request' (duration: 2.37769846s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:21.706789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:51:19.328425Z","time spent":"2.378304516s","remote":"127.0.0.1:34742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T16:51:21.706908Z","caller":"traceutil/trace.go:171","msg":"trace[1479744873] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"2.371723142s","start":"2026-02-12T16:51:19.335174Z","end":"2026-02-12T16:51:21.706897Z","steps":["trace[1479744873] 'process raft request' (duration: 2.371093714s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:21.706953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:51:19.335162Z","time spent":"2.371765843s","remote":"127.0.0.1:34742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T16:51:21.707078Z","caller":"traceutil/trace.go:171","msg":"trace[1234517303] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"2.371857137s","start":"2026-02-12T16:51:19.335213Z","end":"2026-02-12T16:51:21.70707Z","steps":["trace[1234517303] 'process raft request' (duration: 2.371087794s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:21.707123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:51:19.33521Z","time spent":"2.371889028s","remote":"127.0.0.1:34742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T16:51:21.724811Z","caller":"traceutil/trace.go:171","msg":"trace[2138698890] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"564.171474ms","start":"2026-02-12T16:51:21.160624Z","end":"2026-02-12T16:51:21.724795Z","steps":["trace[2138698890] 'process raft request' (duration: 564.07962ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:21.724906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:51:21.160606Z","time spent":"564.249848ms","remote":"127.0.0.1:34742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T16:51:21.725205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.595907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T16:51:21.725228Z","caller":"traceutil/trace.go:171","msg":"trace[1518813586] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:573; }","duration":"388.655359ms","start":"2026-02-12T16:51:21.336566Z","end":"2026-02-12T16:51:21.725221Z","steps":["trace[1518813586] 'agreement among raft nodes before linearized reading' (duration: 388.620077ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:21.725245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:51:21.336545Z","time spent":"388.696131ms","remote":"127.0.0.1:34470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T16:51:22.000486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.909765ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T16:51:22.000851Z","caller":"traceutil/trace.go:171","msg":"trace[971627997] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"291.205875ms","start":"2026-02-12T16:51:21.709623Z","end":"2026-02-12T16:51:22.000829Z","steps":["trace[971627997] 'process raft request' (duration: 161.878882ms)","trace[971627997] 'compare' (duration: 128.827581ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:51:22.001027Z","caller":"traceutil/trace.go:171","msg":"trace[1479469882] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"268.492415ms","start":"2026-02-12T16:51:21.732523Z","end":"2026-02-12T16:51:22.001016Z","steps":["trace[1479469882] 'process raft request' (duration: 268.084277ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:22.001148Z","caller":"traceutil/trace.go:171","msg":"trace[1339605656] linearizableReadLoop","detail":"{readStateIndex:590; appliedIndex:588; }","duration":"268.059796ms","start":"2026-02-12T16:51:21.733077Z","end":"2026-02-12T16:51:22.001137Z","steps":["trace[1339605656] 'read index received' (duration: 138.43451ms)","trace[1339605656] 'applied index is now lower than readState.Index' (duration: 129.624026ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:51:22.001369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.284156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T16:51:22.001414Z","caller":"traceutil/trace.go:171","msg":"trace[1400889004] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:575; }","duration":"268.352439ms","start":"2026-02-12T16:51:21.73305Z","end":"2026-02-12T16:51:22.001403Z","steps":["trace[1400889004] 'agreement among raft nodes before linearized reading' (duration: 268.265895ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:22.508575Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14346107017283998419,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T16:51:23.009187Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14346107017283998419,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T16:51:23.510304Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14346107017283998419,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T16:51:23.71781Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.709764125s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-12T16:51:24.740517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000881757s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-12T16:51:24.740576Z","caller":"traceutil/trace.go:171","msg":"trace[483642490] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000982121s","start":"2026-02-12T16:51:22.739581Z","end":"2026-02-12T16:51:24.740563Z","steps":["trace[483642490] 'agreement among raft nodes before linearized reading' (duration: 2.000900137s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:24.740606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:51:22.739568Z","time spent":"2.001033814s","remote":"127.0.0.1:34456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T16:51:25.17032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.452093086s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T16:51:25.17063Z","caller":"traceutil/trace.go:171","msg":"trace[783267974] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"3.162660976s","start":"2026-02-12T16:51:22.007956Z","end":"2026-02-12T16:51:25.170617Z","steps":["trace[783267974] 'process raft request' (duration: 1.710225516s)","trace[783267974] 'compare' (duration: 1.451795732s)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:51:25.170665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:51:22.007936Z","time spent":"3.162713989s","remote":"127.0.0.1:34742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T16:51:25.170802Z","caller":"traceutil/trace.go:171","msg":"trace[1659528842] linearizableReadLoop","detail":"{readStateIndex:591; appliedIndex:590; }","duration":"3.162458337s","start":"2026-02-12T16:51:22.008338Z","end":"2026-02-12T16:51:25.170797Z","steps":["trace[1659528842] 'read index received' (duration: 1.709844639s)","trace[1659528842] 'applied index is now lower than readState.Index' (duration: 1.452613008s)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:51:25.336664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.088002ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-12T16:51:25.336741Z","caller":"traceutil/trace.go:171","msg":"trace[456724371] linearizableReadLoop","detail":"{readStateIndex:592; appliedIndex:591; }","duration":"165.914935ms","start":"2026-02-12T16:51:25.170813Z","end":"2026-02-12T16:51:25.336728Z","steps":["trace[456724371] 'read index received' (duration: 32.341µs)","trace[456724371] 'applied index is now lower than readState.Index' (duration: 165.881214ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:51:25.336848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.624926843s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-12T16:51:25.336861Z","caller":"traceutil/trace.go:171","msg":"trace[341388264] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:576; }","duration":"1.624974085s","start":"2026-02-12T16:51:23.711882Z","end":"2026-02-12T16:51:25.336856Z","steps":["trace[341388264] 'agreement among raft nodes before linearized reading' (duration: 1.624875091s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:25.336885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:51:23.711867Z","time spent":"1.625010767s","remote":"127.0.0.1:34742","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-12T16:51:25.336967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.328629103s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-02-12T16:51:25.336979Z","caller":"traceutil/trace.go:171","msg":"trace[1603352885] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:576; }","duration":"3.328663335s","start":"2026-02-12T16:51:22.008311Z","end":"2026-02-12T16:51:25.336974Z","steps":["trace[1603352885] 'agreement among raft nodes before linearized reading' (duration: 3.162526681s)","trace[1603352885] 'range keys from in-memory index tree' (duration: 166.113134ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:51:25.336991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:51:22.008295Z","time spent":"3.328692436s","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-02-12T16:51:25.337154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.779763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T16:51:25.337166Z","caller":"traceutil/trace.go:171","msg":"trace[1858754170] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:576; }","duration":"586.844876ms","start":"2026-02-12T16:51:24.750317Z","end":"2026-02-12T16:51:25.337162Z","steps":["trace[1858754170] 'agreement among raft nodes before linearized reading' (duration: 586.825305ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:25.337177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:51:24.750289Z","time spent":"586.886107ms","remote":"127.0.0.1:34456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T16:51:25.337352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.953622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T16:51:25.337367Z","caller":"traceutil/trace.go:171","msg":"trace[1115212171] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:576; }","duration":"594.966602ms","start":"2026-02-12T16:51:24.742394Z","end":"2026-02-12T16:51:25.337361Z","steps":["trace[1115212171] 'agreement among raft nodes before linearized reading' (duration: 594.945452ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:25.337378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:51:24.742364Z","time spent":"595.012274ms","remote":"127.0.0.1:34470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T16:51:25.337437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.624815468s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-12T16:51:25.337519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.624384199s","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-12T16:51:25.337534Z","caller":"traceutil/trace.go:171","msg":"trace[485360180] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:576; }","duration":"1.624412091s","start":"2026-02-12T16:51:23.713118Z","end":"2026-02-12T16:51:25.33753Z","steps":["trace[485360180] 'agreement among raft nodes before linearized reading' (duration: 1.624363909s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:25.337547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:51:23.713113Z","time spent":"1.624430612s","remote":"127.0.0.1:34742","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":"info","ts":"2026-02-12T16:51:25.337724Z","caller":"traceutil/trace.go:171","msg":"trace[2005853498] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:576; }","duration":"1.624937824s","start":"2026-02-12T16:51:23.71257Z","end":"2026-02-12T16:51:25.337508Z","steps":["trace[2005853498] 'agreement among raft nodes before linearized reading' (duration: 1.624659392s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:25.337792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:51:23.712564Z","time spent":"1.625216306s","remote":"127.0.0.1:34742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-12T16:51:25.552757Z","caller":"traceutil/trace.go:171","msg":"trace[1252880484] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"202.771655ms","start":"2026-02-12T16:51:25.349968Z","end":"2026-02-12T16:51:25.55274Z","steps":["trace[1252880484] 'process raft request' (duration: 202.66522ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:25.552785Z","caller":"traceutil/trace.go:171","msg":"trace[592623776] linearizableReadLoop","detail":"{readStateIndex:593; appliedIndex:593; }","duration":"201.113883ms","start":"2026-02-12T16:51:25.351645Z","end":"2026-02-12T16:51:25.552759Z","steps":["trace[592623776] 'read index received' (duration: 201.106862ms)","trace[592623776] 'applied index is now lower than readState.Index' (duration: 5.67µs)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:51:25.552975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.308171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-12T16:51:25.553008Z","caller":"traceutil/trace.go:171","msg":"trace[1974833984] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:577; }","duration":"201.368923ms","start":"2026-02-12T16:51:25.351629Z","end":"2026-02-12T16:51:25.552998Z","steps":["trace[1974833984] 'agreement among raft nodes before linearized reading' (duration: 201.255718ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:25.589115Z","caller":"traceutil/trace.go:171","msg":"trace[1687674827] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"231.334701ms","start":"2026-02-12T16:51:25.357757Z","end":"2026-02-12T16:51:25.589092Z","steps":["trace[1687674827] 'process raft request' (duration: 231.150203ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:25.589209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.000396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18938de255dbc15f\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-02-12T16:51:25.58927Z","caller":"traceutil/trace.go:171","msg":"trace[1262729879] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18938de255dbc15f; range_end:; response_count:1; response_revision:579; }","duration":"236.08197ms","start":"2026-02-12T16:51:25.353171Z","end":"2026-02-12T16:51:25.589253Z","steps":["trace[1262729879] 'agreement among raft nodes before linearized reading' (duration: 235.894522ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:25.589472Z","caller":"traceutil/trace.go:171","msg":"trace[439481680] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"226.740398ms","start":"2026-02-12T16:51:25.362725Z","end":"2026-02-12T16:51:25.589466Z","steps":["trace[439481680] 'process raft request' (duration: 226.304299ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:26.852883Z","caller":"traceutil/trace.go:171","msg":"trace[709624892] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"109.867261ms","start":"2026-02-12T16:51:26.743002Z","end":"2026-02-12T16:51:26.852869Z","steps":["trace[709624892] 'process raft request' (duration: 109.778787ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:26.967301Z","caller":"traceutil/trace.go:171","msg":"trace[411815329] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"217.802437ms","start":"2026-02-12T16:51:26.749476Z","end":"2026-02-12T16:51:26.967278Z","steps":["trace[411815329] 'process raft request' (duration: 217.684072ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:29.8243Z","caller":"traceutil/trace.go:171","msg":"trace[1323687579] linearizableReadLoop","detail":"{readStateIndex:618; appliedIndex:617; }","duration":"222.028636ms","start":"2026-02-12T16:51:29.602253Z","end":"2026-02-12T16:51:29.824282Z","steps":["trace[1323687579] 'read index received' (duration: 221.89397ms)","trace[1323687579] 'applied index is now lower than readState.Index' (duration: 134.036µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:51:29.824524Z","caller":"traceutil/trace.go:171","msg":"trace[1512788589] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"626.730803ms","start":"2026-02-12T16:51:29.197785Z","end":"2026-02-12T16:51:29.824515Z","steps":["trace[1512788589] 'process raft request' (duration: 626.407259ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:29.824606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:51:29.197766Z","time spent":"626.779406ms","remote":"127.0.0.1:34742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T16:51:29.824797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.538708ms","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-12T16:51:29.824824Z","caller":"traceutil/trace.go:171","msg":"trace[361819288] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:601; }","duration":"222.588881ms","start":"2026-02-12T16:51:29.602226Z","end":"2026-02-12T16:51:29.824815Z","steps":["trace[361819288] 'agreement among raft nodes before linearized reading' (duration: 222.492836ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:29.824961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.685375ms","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-12T16:51:29.824979Z","caller":"traceutil/trace.go:171","msg":"trace[784593538] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:601; }","duration":"162.735208ms","start":"2026-02-12T16:51:29.662238Z","end":"2026-02-12T16:51:29.824973Z","steps":["trace[784593538] 'agreement among raft nodes before linearized reading' (duration: 162.679245ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:29.825376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.043411ms","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-12T16:51:29.825399Z","caller":"traceutil/trace.go:171","msg":"trace[534654257] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:601; }","duration":"163.110064ms","start":"2026-02-12T16:51:29.662282Z","end":"2026-02-12T16:51:29.825392Z","steps":["trace[534654257] 'agreement among raft nodes before linearized reading' (duration: 163.047561ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:30.740599Z","caller":"traceutil/trace.go:171","msg":"trace[185703582] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"151.002711ms","start":"2026-02-12T16:51:30.589572Z","end":"2026-02-12T16:51:30.740575Z","steps":["trace[185703582] 'process raft request' (duration: 150.865195ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:31.122292Z","caller":"traceutil/trace.go:171","msg":"trace[123929002] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"117.697051ms","start":"2026-02-12T16:51:31.00458Z","end":"2026-02-12T16:51:31.122277Z","steps":["trace[123929002] 'process raft request' (duration: 106.378026ms)","trace[123929002] 'compare' (duration: 10.874455ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:51:32.571933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.72462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-02-12T16:51:32.571986Z","caller":"traceutil/trace.go:171","msg":"trace[2117003214] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:654; }","duration":"181.837765ms","start":"2026-02-12T16:51:32.390135Z","end":"2026-02-12T16:51:32.571972Z","steps":["trace[2117003214] 'range keys from in-memory index tree' (duration: 181.562152ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:34.35638Z","caller":"traceutil/trace.go:171","msg":"trace[1466638141] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"108.229547ms","start":"2026-02-12T16:51:34.248139Z","end":"2026-02-12T16:51:34.356368Z","steps":["trace[1466638141] 'process raft request' (duration: 108.117682ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:34.356985Z","caller":"traceutil/trace.go:171","msg":"trace[50667421] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"106.25779ms","start":"2026-02-12T16:51:34.250722Z","end":"2026-02-12T16:51:34.35698Z","steps":["trace[50667421] 'process raft request' (duration: 105.613291ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:34.358021Z","caller":"traceutil/trace.go:171","msg":"trace[2100000571] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"104.769883ms","start":"2026-02-12T16:51:34.253243Z","end":"2026-02-12T16:51:34.358013Z","steps":["trace[2100000571] 'process raft request' (duration: 103.528177ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:34.6305Z","caller":"traceutil/trace.go:171","msg":"trace[443597023] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"107.389641ms","start":"2026-02-12T16:51:34.523092Z","end":"2026-02-12T16:51:34.630482Z","steps":["trace[443597023] 'process raft request' (duration: 46.746755ms)","trace[443597023] 'compare' (duration: 60.537991ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:51:38.45135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.580447ms","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-12T16:51:38.451395Z","caller":"traceutil/trace.go:171","msg":"trace[1036864200] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:709; }","duration":"127.65667ms","start":"2026-02-12T16:51:38.323729Z","end":"2026-02-12T16:51:38.451386Z","steps":["trace[1036864200] 'range keys from in-memory index tree' (duration: 127.447061ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:40.562757Z","caller":"traceutil/trace.go:171","msg":"trace[1765799186] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"119.775954ms","start":"2026-02-12T16:51:40.442966Z","end":"2026-02-12T16:51:40.562742Z","steps":["trace[1765799186] 'process raft request' (duration: 119.425428ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:42.368496Z","caller":"traceutil/trace.go:171","msg":"trace[1642147378] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"130.130452ms","start":"2026-02-12T16:51:42.238348Z","end":"2026-02-12T16:51:42.368479Z","steps":["trace[1642147378] 'process raft request' (duration: 129.8554ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:42.368831Z","caller":"traceutil/trace.go:171","msg":"trace[1290119109] linearizableReadLoop","detail":"{readStateIndex:770; appliedIndex:767; }","duration":"110.013819ms","start":"2026-02-12T16:51:42.258808Z","end":"2026-02-12T16:51:42.368822Z","steps":["trace[1290119109] 'read index received' (duration: 53.838676ms)","trace[1290119109] 'applied index is now lower than readState.Index' (duration: 56.174493ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:51:42.368878Z","caller":"traceutil/trace.go:171","msg":"trace[1842099689] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"129.665361ms","start":"2026-02-12T16:51:42.239206Z","end":"2026-02-12T16:51:42.368871Z","steps":["trace[1842099689] 'process raft request' (duration: 129.219481ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:42.369235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.412977ms","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-12T16:51:42.369268Z","caller":"traceutil/trace.go:171","msg":"trace[787543407] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:749; }","duration":"110.452668ms","start":"2026-02-12T16:51:42.258805Z","end":"2026-02-12T16:51:42.369258Z","steps":["trace[787543407] 'agreement among raft nodes before linearized reading' (duration: 110.350374ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:42.398603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.948863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-12T16:51:42.398667Z","caller":"traceutil/trace.go:171","msg":"trace[60628803] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:751; }","duration":"139.012966ms","start":"2026-02-12T16:51:42.259626Z","end":"2026-02-12T16:51:42.398639Z","steps":["trace[60628803] 'agreement among raft nodes before linearized reading' (duration: 138.88097ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:43.19826Z","caller":"traceutil/trace.go:171","msg":"trace[1979268494] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"115.597011ms","start":"2026-02-12T16:51:43.082646Z","end":"2026-02-12T16:51:43.198243Z","steps":["trace[1979268494] 'process raft request' (duration: 115.543149ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:43.198565Z","caller":"traceutil/trace.go:171","msg":"trace[1270407256] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"115.962998ms","start":"2026-02-12T16:51:43.082594Z","end":"2026-02-12T16:51:43.198557Z","steps":["trace[1270407256] 'process raft request' (duration: 115.494866ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:43.418756Z","caller":"traceutil/trace.go:171","msg":"trace[1128804432] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"207.307135ms","start":"2026-02-12T16:51:43.21143Z","end":"2026-02-12T16:51:43.418737Z","steps":["trace[1128804432] 'process raft request' (duration: 126.001875ms)","trace[1128804432] 'compare' (duration: 81.226826ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:51:46.056617Z","caller":"traceutil/trace.go:171","msg":"trace[143965597] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"144.213658ms","start":"2026-02-12T16:51:45.912374Z","end":"2026-02-12T16:51:46.056588Z","steps":["trace[143965597] 'process raft request' (duration: 143.435922ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:51:46.184193Z","caller":"traceutil/trace.go:171","msg":"trace[1523886732] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"103.830158ms","start":"2026-02-12T16:51:46.080345Z","end":"2026-02-12T16:51:46.184175Z","steps":["trace[1523886732] 'process raft request' (duration: 103.00637ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:51:46.480317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.477504ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T16:51:46.480811Z","caller":"traceutil/trace.go:171","msg":"trace[1503810582] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"221.494029ms","start":"2026-02-12T16:51:46.259299Z","end":"2026-02-12T16:51:46.480793Z","steps":["trace[1503810582] 'process raft request' (duration: 52.426558ms)","trace[1503810582] 'compare' (duration: 168.340448ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:52:12.972883Z","caller":"traceutil/trace.go:171","msg":"trace[465469927] transaction","detail":"{read_only:false; response_revision:861; number_of_response:1; }","duration":"100.405943ms","start":"2026-02-12T16:52:12.872455Z","end":"2026-02-12T16:52:12.972861Z","steps":["trace[465469927] 'process raft request' (duration: 49.526349ms)","trace[465469927] 'compare' (duration: 50.302457ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:52:13.123549Z","caller":"traceutil/trace.go:171","msg":"trace[260854344] transaction","detail":"{read_only:false; response_revision:864; number_of_response:1; }","duration":"138.361371ms","start":"2026-02-12T16:52:12.985172Z","end":"2026-02-12T16:52:13.123534Z","steps":["trace[260854344] 'process raft request' (duration: 138.33397ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:52:13.123758Z","caller":"traceutil/trace.go:171","msg":"trace[172672707] transaction","detail":"{read_only:false; response_revision:863; number_of_response:1; }","duration":"138.649996ms","start":"2026-02-12T16:52:12.985104Z","end":"2026-02-12T16:52:13.123754Z","steps":["trace[172672707] 'process raft request' (duration: 137.401646ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:52:13.153066Z","caller":"traceutil/trace.go:171","msg":"trace[1528907354] transaction","detail":"{read_only:false; response_revision:865; number_of_response:1; }","duration":"108.785784ms","start":"2026-02-12T16:52:13.044266Z","end":"2026-02-12T16:52:13.153051Z","steps":["trace[1528907354] 'process raft request' (duration: 108.70461ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:52:15.09802Z","caller":"traceutil/trace.go:171","msg":"trace[1781420853] transaction","detail":"{read_only:false; response_revision:867; number_of_response:1; }","duration":"117.621661ms","start":"2026-02-12T16:52:14.980374Z","end":"2026-02-12T16:52:15.097995Z","steps":["trace[1781420853] 'process raft request' (duration: 116.766641ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:52:15.272936Z","caller":"traceutil/trace.go:171","msg":"trace[187288579] linearizableReadLoop","detail":"{readStateIndex:896; appliedIndex:895; }","duration":"145.745054ms","start":"2026-02-12T16:52:15.127171Z","end":"2026-02-12T16:52:15.272916Z","steps":["trace[187288579] 'read index received' (duration: 105.468706ms)","trace[187288579] 'applied index is now lower than readState.Index' (duration: 40.275548ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:52:15.273495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.310982ms","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-12T16:52:15.273799Z","caller":"traceutil/trace.go:171","msg":"trace[467422931] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:868; }","duration":"146.594385ms","start":"2026-02-12T16:52:15.127122Z","end":"2026-02-12T16:52:15.273717Z","steps":["trace[467422931] 'agreement among raft nodes before linearized reading' (duration: 146.161725ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:52:15.274044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.783597ms","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-12T16:52:15.274435Z","caller":"traceutil/trace.go:171","msg":"trace[1048440569] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:868; }","duration":"138.176506ms","start":"2026-02-12T16:52:15.136252Z","end":"2026-02-12T16:52:15.274428Z","steps":["trace[1048440569] 'agreement among raft nodes before linearized reading' (duration: 137.327726ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:52:15.274325Z","caller":"traceutil/trace.go:171","msg":"trace[608722784] transaction","detail":"{read_only:false; response_revision:868; number_of_response:1; }","duration":"165.787725ms","start":"2026-02-12T16:52:15.108527Z","end":"2026-02-12T16:52:15.274315Z","steps":["trace[608722784] 'process raft request' (duration: 124.169193ms)","trace[608722784] 'compare' (duration: 40.140732ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:52:17.460657Z","caller":"traceutil/trace.go:171","msg":"trace[152490555] linearizableReadLoop","detail":"{readStateIndex:902; appliedIndex:901; }","duration":"100.341323ms","start":"2026-02-12T16:52:17.360301Z","end":"2026-02-12T16:52:17.460643Z","steps":["trace[152490555] 'read index received' (duration: 69.503369ms)","trace[152490555] 'applied index is now lower than readState.Index' (duration: 30.837464ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:52:17.46079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.470899ms","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-12T16:52:17.460811Z","caller":"traceutil/trace.go:171","msg":"trace[1582758542] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:874; }","duration":"100.516791ms","start":"2026-02-12T16:52:17.360288Z","end":"2026-02-12T16:52:17.460805Z","steps":["trace[1582758542] 'agreement among raft nodes before linearized reading' (duration: 100.410106ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:52:17.461006Z","caller":"traceutil/trace.go:171","msg":"trace[740795661] transaction","detail":"{read_only:false; response_revision:874; number_of_response:1; }","duration":"109.366311ms","start":"2026-02-12T16:52:17.351634Z","end":"2026-02-12T16:52:17.461Z","steps":["trace[740795661] 'process raft request' (duration: 78.220542ms)","trace[740795661] 'compare' (duration: 30.726319ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:52:20.938431Z","caller":"traceutil/trace.go:171","msg":"trace[691732879] linearizableReadLoop","detail":"{readStateIndex:912; appliedIndex:911; }","duration":"197.650256ms","start":"2026-02-12T16:52:20.740763Z","end":"2026-02-12T16:52:20.938413Z","steps":["trace[691732879] 'read index received' (duration: 197.559962ms)","trace[691732879] 'applied index is now lower than readState.Index' (duration: 89.614µs)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:52:20.938509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.730871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T16:52:20.938525Z","caller":"traceutil/trace.go:171","msg":"trace[304678130] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:883; }","duration":"197.793953ms","start":"2026-02-12T16:52:20.740726Z","end":"2026-02-12T16:52:20.93852Z","steps":["trace[304678130] 'agreement among raft nodes before linearized reading' (duration: 197.748071ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:52:20.938699Z","caller":"traceutil/trace.go:171","msg":"trace[27611277] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"305.607684ms","start":"2026-02-12T16:52:20.633087Z","end":"2026-02-12T16:52:20.938694Z","steps":["trace[27611277] 'process raft request' (duration: 305.220436ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:52:20.93875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:52:20.633075Z","time spent":"305.637546ms","remote":"127.0.0.1:34742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T16:52:25.832015Z","caller":"traceutil/trace.go:171","msg":"trace[293079784] linearizableReadLoop","detail":"{readStateIndex:928; appliedIndex:927; }","duration":"115.09504ms","start":"2026-02-12T16:52:25.716896Z","end":"2026-02-12T16:52:25.831991Z","steps":["trace[293079784] 'read index received' (duration: 35.962798ms)","trace[293079784] 'applied index is now lower than readState.Index' (duration: 79.131082ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:52:25.832141Z","caller":"traceutil/trace.go:171","msg":"trace[158693027] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"121.658764ms","start":"2026-02-12T16:52:25.710471Z","end":"2026-02-12T16:52:25.832129Z","steps":["trace[158693027] 'process raft request' (duration: 42.447948ms)","trace[158693027] 'compare' (duration: 78.768175ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:52:25.832506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.591254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4911"} {"level":"info","ts":"2026-02-12T16:52:25.832616Z","caller":"traceutil/trace.go:171","msg":"trace[1023981900] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:898; }","duration":"115.71052ms","start":"2026-02-12T16:52:25.716876Z","end":"2026-02-12T16:52:25.832586Z","steps":["trace[1023981900] 'agreement among raft nodes before linearized reading' (duration: 115.529571ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:52:28.915714Z","caller":"traceutil/trace.go:171","msg":"trace[1895114817] linearizableReadLoop","detail":"{readStateIndex:936; appliedIndex:935; }","duration":"131.088589ms","start":"2026-02-12T16:52:28.784604Z","end":"2026-02-12T16:52:28.915693Z","steps":["trace[1895114817] 'read index received' (duration: 125.573095ms)","trace[1895114817] 'applied index is now lower than readState.Index' (duration: 5.514554ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:52:28.915746Z","caller":"traceutil/trace.go:171","msg":"trace[632850782] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"131.613895ms","start":"2026-02-12T16:52:28.784097Z","end":"2026-02-12T16:52:28.915711Z","steps":["trace[632850782] 'process raft request' (duration: 126.519011ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:52:28.916255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.610584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T16:52:28.916345Z","caller":"traceutil/trace.go:171","msg":"trace[455900139] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:905; }","duration":"131.749201ms","start":"2026-02-12T16:52:28.784576Z","end":"2026-02-12T16:52:28.916325Z","steps":["trace[455900139] 'agreement among raft nodes before linearized reading' (duration: 131.30771ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:52:28.916587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.502489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12727"} {"level":"info","ts":"2026-02-12T16:52:28.916908Z","caller":"traceutil/trace.go:171","msg":"trace[463202572] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:905; }","duration":"117.839825ms","start":"2026-02-12T16:52:28.79905Z","end":"2026-02-12T16:52:28.91689Z","steps":["trace[463202572] 'agreement among raft nodes before linearized reading' (duration: 117.412575ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:52:29.301773Z","caller":"traceutil/trace.go:171","msg":"trace[1441450365] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"292.010599ms","start":"2026-02-12T16:52:29.009745Z","end":"2026-02-12T16:52:29.301756Z","steps":["trace[1441450365] 'process raft request' (duration: 222.938938ms)","trace[1441450365] 'compare' (duration: 68.815129ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:52:29.855601Z","caller":"traceutil/trace.go:171","msg":"trace[1571045316] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"112.66217ms","start":"2026-02-12T16:52:29.742923Z","end":"2026-02-12T16:52:29.855585Z","steps":["trace[1571045316] 'process raft request' (duration: 112.577986ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:52:30.004747Z","caller":"traceutil/trace.go:171","msg":"trace[1734455680] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"137.638629ms","start":"2026-02-12T16:52:29.867092Z","end":"2026-02-12T16:52:30.004731Z","steps":["trace[1734455680] 'process raft request' (duration: 56.645396ms)","trace[1734455680] 'compare' (duration: 80.752791ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:52:32.186885Z","caller":"traceutil/trace.go:171","msg":"trace[2093055920] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"176.147313ms","start":"2026-02-12T16:52:32.010713Z","end":"2026-02-12T16:52:32.18686Z","steps":["trace[2093055920] 'process raft request' (duration: 175.987185ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:52:38.267067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.298462ms","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-12T16:52:38.267237Z","caller":"traceutil/trace.go:171","msg":"trace[1902478177] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:944; }","duration":"341.507492ms","start":"2026-02-12T16:52:37.925705Z","end":"2026-02-12T16:52:38.267213Z","steps":["trace[1902478177] 'range keys from in-memory index tree' (duration: 341.130754ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:52:38.267298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:52:37.925693Z","time spent":"341.586616ms","remote":"127.0.0.1:34742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-12T16:52:38.268877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.424354ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T16:52:38.268953Z","caller":"traceutil/trace.go:171","msg":"trace[1525600731] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"336.966733ms","start":"2026-02-12T16:52:37.931972Z","end":"2026-02-12T16:52:38.268939Z","steps":["trace[1525600731] 'process raft request' (duration: 207.414024ms)","trace[1525600731] 'compare' (duration: 127.044318ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:52:38.269396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:52:37.931948Z","time spent":"337.030706ms","remote":"127.0.0.1:34742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T16:52:38.511241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.193935ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-12T16:52:38.511665Z","caller":"traceutil/trace.go:171","msg":"trace[1504895520] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"237.238304ms","start":"2026-02-12T16:52:38.274407Z","end":"2026-02-12T16:52:38.511645Z","steps":["trace[1504895520] 'process raft request' (duration: 237.15284ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:52:38.512095Z","caller":"traceutil/trace.go:171","msg":"trace[993784196] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"496.027939ms","start":"2026-02-12T16:52:38.016045Z","end":"2026-02-12T16:52:38.512073Z","steps":["trace[993784196] 'process raft request' (duration: 384.915439ms)","trace[993784196] 'compare' (duration: 109.807257ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:52:38.512297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:52:38.016013Z","time spent":"496.122373ms","remote":"127.0.0.1:34536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":852,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-12T16:52:38.512601Z","caller":"traceutil/trace.go:171","msg":"trace[558252470] linearizableReadLoop","detail":"{readStateIndex:978; appliedIndex:976; }","duration":"467.891441ms","start":"2026-02-12T16:52:38.044695Z","end":"2026-02-12T16:52:38.512586Z","steps":["trace[558252470] 'read index received' (duration: 94.699938ms)","trace[558252470] 'applied index is now lower than readState.Index' (duration: 373.190103ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:52:38.513089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.386995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-753cc3e3-f59c-40d6-a038-a449674ad2a4\" ","response":"range_response_count:1 size:3534"} {"level":"info","ts":"2026-02-12T16:52:38.5135Z","caller":"traceutil/trace.go:171","msg":"trace[1099458530] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-753cc3e3-f59c-40d6-a038-a449674ad2a4; range_end:; response_count:1; response_revision:947; }","duration":"468.479899ms","start":"2026-02-12T16:52:38.04466Z","end":"2026-02-12T16:52:38.51314Z","steps":["trace[1099458530] 'agreement among raft nodes before linearized reading' (duration: 468.318912ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:52:38.513676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:52:38.044647Z","time spent":"468.921071ms","remote":"127.0.0.1:34640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3558,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-753cc3e3-f59c-40d6-a038-a449674ad2a4\" "} {"level":"warn","ts":"2026-02-12T16:52:38.514305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.163371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-12T16:52:38.514358Z","caller":"traceutil/trace.go:171","msg":"trace[1363227571] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:947; }","duration":"396.263975ms","start":"2026-02-12T16:52:38.11808Z","end":"2026-02-12T16:52:38.514344Z","steps":["trace[1363227571] 'agreement among raft nodes before linearized reading' (duration: 395.94387ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:52:38.514426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:52:38.118063Z","time spent":"396.320068ms","remote":"127.0.0.1:34742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":7,"response size":31,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-02-12T16:52:38.515185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.980154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-12T16:52:38.516241Z","caller":"traceutil/trace.go:171","msg":"trace[1343231413] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:947; }","duration":"197.047805ms","start":"2026-02-12T16:52:38.319181Z","end":"2026-02-12T16:52:38.516229Z","steps":["trace[1343231413] 'agreement among raft nodes before linearized reading' (duration: 195.90936ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:52:38.517879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.530582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-12T16:52:38.518027Z","caller":"traceutil/trace.go:171","msg":"trace[1101625680] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:947; }","duration":"116.885279ms","start":"2026-02-12T16:52:38.401131Z","end":"2026-02-12T16:52:38.518016Z","steps":["trace[1101625680] 'agreement among raft nodes before linearized reading' (duration: 116.531402ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:52:38.515405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.862062ms","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-12T16:52:38.519959Z","caller":"traceutil/trace.go:171","msg":"trace[1562232796] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:947; }","duration":"260.450194ms","start":"2026-02-12T16:52:38.259493Z","end":"2026-02-12T16:52:38.519943Z","steps":["trace[1562232796] 'agreement among raft nodes before linearized reading' (duration: 255.696944ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:52:40.743735Z","caller":"traceutil/trace.go:171","msg":"trace[1200988199] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"131.105602ms","start":"2026-02-12T16:52:40.612607Z","end":"2026-02-12T16:52:40.743713Z","steps":["trace[1200988199] 'process raft request' (duration: 131.006728ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:52:40.766154Z","caller":"traceutil/trace.go:171","msg":"trace[1655572907] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"152.300456ms","start":"2026-02-12T16:52:40.613831Z","end":"2026-02-12T16:52:40.766132Z","steps":["trace[1655572907] 'process raft request' (duration: 152.215832ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:52:45.833234Z","caller":"traceutil/trace.go:171","msg":"trace[1714392923] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"202.641198ms","start":"2026-02-12T16:52:45.630573Z","end":"2026-02-12T16:52:45.833214Z","steps":["trace[1714392923] 'process raft request' (duration: 202.471369ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:52:46.718109Z","caller":"traceutil/trace.go:171","msg":"trace[1576526012] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"109.805263ms","start":"2026-02-12T16:52:46.608278Z","end":"2026-02-12T16:52:46.718083Z","steps":["trace[1576526012] 'process raft request' (duration: 109.658476ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:52:47.213303Z","caller":"traceutil/trace.go:171","msg":"trace[920976752] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"274.266121ms","start":"2026-02-12T16:52:46.939014Z","end":"2026-02-12T16:52:47.21328Z","steps":["trace[920976752] 'process raft request' (duration: 274.124884ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:52:48.920902Z","caller":"traceutil/trace.go:171","msg":"trace[360639808] linearizableReadLoop","detail":"{readStateIndex:1030; appliedIndex:1029; }","duration":"117.04672ms","start":"2026-02-12T16:52:48.803824Z","end":"2026-02-12T16:52:48.920871Z","steps":["trace[360639808] 'read index received' (duration: 29.06994ms)","trace[360639808] 'applied index is now lower than readState.Index' (duration: 87.9758ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:52:48.921311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.469851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-02-12T16:52:48.92144Z","caller":"traceutil/trace.go:171","msg":"trace[647421822] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:994; }","duration":"117.643009ms","start":"2026-02-12T16:52:48.803782Z","end":"2026-02-12T16:52:48.921425Z","steps":["trace[647421822] 'agreement among raft nodes before linearized reading' (duration: 117.371586ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:52:48.921955Z","caller":"traceutil/trace.go:171","msg":"trace[37280833] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"174.482618ms","start":"2026-02-12T16:52:48.747457Z","end":"2026-02-12T16:52:48.92194Z","steps":["trace[37280833] 'process raft request' (duration: 85.580223ms)","trace[37280833] 'compare' (duration: 87.709507ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:52:51.035577Z","caller":"traceutil/trace.go:171","msg":"trace[3465566] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"102.815276ms","start":"2026-02-12T16:52:50.932737Z","end":"2026-02-12T16:52:51.035552Z","steps":["trace[3465566] 'process raft request' (duration: 102.646598ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:52:52.319498Z","caller":"traceutil/trace.go:171","msg":"trace[1432314358] linearizableReadLoop","detail":"{readStateIndex:1042; appliedIndex:1041; }","duration":"129.856096ms","start":"2026-02-12T16:52:52.189621Z","end":"2026-02-12T16:52:52.319477Z","steps":["trace[1432314358] 'read index received' (duration: 129.694228ms)","trace[1432314358] 'applied index is now lower than readState.Index' (duration: 161.018µs)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:52:52.319923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.284526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1465"} {"level":"info","ts":"2026-02-12T16:52:52.320313Z","caller":"traceutil/trace.go:171","msg":"trace[1585995067] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:1006; }","duration":"130.704667ms","start":"2026-02-12T16:52:52.189584Z","end":"2026-02-12T16:52:52.320288Z","steps":["trace[1585995067] 'agreement among raft nodes before linearized reading' (duration: 130.073857ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:52:52.320329Z","caller":"traceutil/trace.go:171","msg":"trace[768686787] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"199.189388ms","start":"2026-02-12T16:52:52.121126Z","end":"2026-02-12T16:52:52.320315Z","steps":["trace[768686787] 'process raft request' (duration: 198.244672ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:52:52.3202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.035095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-12T16:52:52.321306Z","caller":"traceutil/trace.go:171","msg":"trace[2007191580] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1006; }","duration":"116.17242ms","start":"2026-02-12T16:52:52.205121Z","end":"2026-02-12T16:52:52.321293Z","steps":["trace[2007191580] 'agreement among raft nodes before linearized reading' (duration: 114.848076ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:52:55.251852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.099029ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-12T16:52:55.251933Z","caller":"traceutil/trace.go:171","msg":"trace[1394751022] linearizableReadLoop","detail":"{readStateIndex:1052; appliedIndex:1051; }","duration":"173.301251ms","start":"2026-02-12T16:52:55.078621Z","end":"2026-02-12T16:52:55.251922Z","steps":["trace[1394751022] 'read index received' (duration: 60.012463ms)","trace[1394751022] 'applied index is now lower than readState.Index' (duration: 113.288008ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:52:55.25209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.463409ms","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-12T16:52:55.252114Z","caller":"traceutil/trace.go:171","msg":"trace[91273203] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1015; }","duration":"173.513162ms","start":"2026-02-12T16:52:55.078595Z","end":"2026-02-12T16:52:55.252108Z","steps":["trace[91273203] 'agreement among raft nodes before linearized reading' (duration: 173.374415ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:52:55.252129Z","caller":"traceutil/trace.go:171","msg":"trace[1160406564] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"191.604652ms","start":"2026-02-12T16:52:55.060498Z","end":"2026-02-12T16:52:55.252102Z","steps":["trace[1160406564] 'process raft request' (duration: 78.205819ms)","trace[1160406564] 'compare' (duration: 112.971673ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:52:56.107453Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14346107017283999468,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T16:52:56.607854Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14346107017283999468,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T16:52:56.955878Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.59851497s","expected-duration":"1s"} {"level":"info","ts":"2026-02-12T16:52:56.9566Z","caller":"traceutil/trace.go:171","msg":"trace[1284670042] linearizableReadLoop","detail":"{readStateIndex:1054; appliedIndex:1053; }","duration":"1.350082231s","start":"2026-02-12T16:52:55.606495Z","end":"2026-02-12T16:52:56.956577Z","steps":["trace[1284670042] 'read index received' (duration: 1.349834039s)","trace[1284670042] 'applied index is now lower than readState.Index' (duration: 247.322µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:52:56.956855Z","caller":"traceutil/trace.go:171","msg":"trace[742110247] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"1.599561691s","start":"2026-02-12T16:52:55.35728Z","end":"2026-02-12T16:52:56.956842Z","steps":["trace[742110247] 'process raft request' (duration: 1.599104808s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:52:56.957286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:52:55.357264Z","time spent":"1.599798572s","remote":"127.0.0.1:34742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T16:52:56.957662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.351161063s","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-12T16:52:56.957819Z","caller":"traceutil/trace.go:171","msg":"trace[1814953899] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1017; }","duration":"1.351333741s","start":"2026-02-12T16:52:55.606468Z","end":"2026-02-12T16:52:56.957801Z","steps":["trace[1814953899] 'agreement among raft nodes before linearized reading' (duration: 1.35109711s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:52:56.959251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:52:55.606454Z","time spent":"1.352767252s","remote":"127.0.0.1:34742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-12T16:52:56.959605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.219986874s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T16:52:56.959771Z","caller":"traceutil/trace.go:171","msg":"trace[741343821] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1017; }","duration":"1.22012617s","start":"2026-02-12T16:52:55.739607Z","end":"2026-02-12T16:52:56.959733Z","steps":["trace[741343821] 'agreement among raft nodes before linearized reading' (duration: 1.219962952s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:52:56.959887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:52:55.739562Z","time spent":"1.220313359s","remote":"127.0.0.1:34470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T16:52:56.960215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.691094ms","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-12T16:52:56.960656Z","caller":"traceutil/trace.go:171","msg":"trace[570360564] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1017; }","duration":"394.149407ms","start":"2026-02-12T16:52:56.566494Z","end":"2026-02-12T16:52:56.960643Z","steps":["trace[570360564] 'agreement among raft nodes before linearized reading' (duration: 393.670533ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:52:56.961088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:52:56.566478Z","time spent":"394.426119ms","remote":"127.0.0.1:34592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"warn","ts":"2026-02-12T16:52:56.962515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"912.15705ms","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-12T16:52:56.963952Z","caller":"traceutil/trace.go:171","msg":"trace[2012945391] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1017; }","duration":"913.922796ms","start":"2026-02-12T16:52:56.050005Z","end":"2026-02-12T16:52:56.963928Z","steps":["trace[2012945391] 'agreement among raft nodes before linearized reading' (duration: 911.869586ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:52:56.962711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.326468ms","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-12T16:52:56.96578Z","caller":"traceutil/trace.go:171","msg":"trace[21033584] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1017; }","duration":"584.46461ms","start":"2026-02-12T16:52:56.381304Z","end":"2026-02-12T16:52:56.965768Z","steps":["trace[21033584] 'agreement among raft nodes before linearized reading' (duration: 581.276595ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:52:56.965821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:52:56.381284Z","time spent":"584.519193ms","remote":"127.0.0.1:34742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-12T16:52:56.965898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:52:56.04999Z","time spent":"915.903871ms","remote":"127.0.0.1:34742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-12T16:52:57.175035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.436487ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T16:52:57.17533Z","caller":"traceutil/trace.go:171","msg":"trace[1998742738] linearizableReadLoop","detail":"{readStateIndex:1056; appliedIndex:1054; }","duration":"206.111237ms","start":"2026-02-12T16:52:56.9692Z","end":"2026-02-12T16:52:57.175311Z","steps":["trace[1998742738] 'read index received' (duration: 81.320973ms)","trace[1998742738] 'applied index is now lower than readState.Index' (duration: 124.789434ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:52:57.17554Z","caller":"traceutil/trace.go:171","msg":"trace[817657033] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"210.337714ms","start":"2026-02-12T16:52:56.965168Z","end":"2026-02-12T16:52:57.175506Z","steps":["trace[817657033] 'process raft request' (duration: 85.34137ms)","trace[817657033] 'compare' (duration: 124.278439ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:52:57.175651Z","caller":"traceutil/trace.go:171","msg":"trace[499021506] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"207.1863ms","start":"2026-02-12T16:52:56.968388Z","end":"2026-02-12T16:52:57.175574Z","steps":["trace[499021506] 'process raft request' (duration: 206.816802ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:52:57.176053Z","caller":"traceutil/trace.go:171","msg":"trace[674963566] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"206.468804ms","start":"2026-02-12T16:52:56.969568Z","end":"2026-02-12T16:52:57.176037Z","steps":["trace[674963566] 'process raft request' (duration: 205.693467ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:52:57.176139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.917756ms","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-12T16:52:57.17619Z","caller":"traceutil/trace.go:171","msg":"trace[8280312] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1020; }","duration":"206.99299ms","start":"2026-02-12T16:52:56.969185Z","end":"2026-02-12T16:52:57.176178Z","steps":["trace[8280312] 'agreement among raft nodes before linearized reading' (duration: 206.903436ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:52:57.179953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.590146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T16:52:57.180004Z","caller":"traceutil/trace.go:171","msg":"trace[1967927134] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1020; }","duration":"210.645418ms","start":"2026-02-12T16:52:56.969346Z","end":"2026-02-12T16:52:57.179991Z","steps":["trace[1967927134] 'agreement among raft nodes before linearized reading' (duration: 207.050333ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:52:57.538761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.52229ms","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-12T16:52:57.538837Z","caller":"traceutil/trace.go:171","msg":"trace[1978425816] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1020; }","duration":"181.646106ms","start":"2026-02-12T16:52:57.357178Z","end":"2026-02-12T16:52:57.538824Z","steps":["trace[1978425816] 'range keys from in-memory index tree' (duration: 181.382603ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:52:59.18284Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14346107017283999478,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T16:52:59.319369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"860.371804ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T16:52:59.31982Z","caller":"traceutil/trace.go:171","msg":"trace[1800014201] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1021; }","duration":"860.516361ms","start":"2026-02-12T16:52:58.458964Z","end":"2026-02-12T16:52:59.31948Z","steps":["trace[1800014201] 'range keys from in-memory index tree' (duration: 860.353563ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:52:59.423579Z","caller":"traceutil/trace.go:171","msg":"trace[402128246] linearizableReadLoop","detail":"{readStateIndex:1059; appliedIndex:1058; }","duration":"740.757802ms","start":"2026-02-12T16:52:58.682796Z","end":"2026-02-12T16:52:59.423553Z","steps":["trace[402128246] 'read index received' (duration: 740.533251ms)","trace[402128246] 'applied index is now lower than readState.Index' (duration: 223.641µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:52:59.423931Z","caller":"traceutil/trace.go:171","msg":"trace[86422145] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"869.434258ms","start":"2026-02-12T16:52:58.55449Z","end":"2026-02-12T16:52:59.423924Z","steps":["trace[86422145] 'process raft request' (duration: 868.904642ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:52:59.424012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:52:58.554459Z","time spent":"869.49088ms","remote":"127.0.0.1:34742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T16:52:59.424193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.396983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.87\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-12T16:52:59.424209Z","caller":"traceutil/trace.go:171","msg":"trace[1066131289] range","detail":"{range_begin:/registry/masterleases/199.19.213.87; range_end:; response_count:1; response_revision:1022; }","duration":"741.451406ms","start":"2026-02-12T16:52:58.682753Z","end":"2026-02-12T16:52:59.424204Z","steps":["trace[1066131289] 'agreement among raft nodes before linearized reading' (duration: 741.373742ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:52:59.424223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:52:58.682733Z","time spent":"741.487327ms","remote":"127.0.0.1:34494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.87\" "} {"level":"warn","ts":"2026-02-12T16:52:59.424315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.47259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T16:52:59.424326Z","caller":"traceutil/trace.go:171","msg":"trace[2050041556] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1022; }","duration":"680.535653ms","start":"2026-02-12T16:52:58.743787Z","end":"2026-02-12T16:52:59.424323Z","steps":["trace[2050041556] 'agreement among raft nodes before linearized reading' (duration: 680.514882ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:52:59.424339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:52:58.743736Z","time spent":"680.598576ms","remote":"127.0.0.1:34456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T16:52:59.424476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.703419ms","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-12T16:52:59.424494Z","caller":"traceutil/trace.go:171","msg":"trace[432092928] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1022; }","duration":"238.732281ms","start":"2026-02-12T16:52:59.185757Z","end":"2026-02-12T16:52:59.42449Z","steps":["trace[432092928] 'agreement among raft nodes before linearized reading' (duration: 238.673438ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:52:59.424581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.026744ms","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-12T16:52:59.424597Z","caller":"traceutil/trace.go:171","msg":"trace[396855564] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1022; }","duration":"240.077546ms","start":"2026-02-12T16:52:59.184515Z","end":"2026-02-12T16:52:59.424593Z","steps":["trace[396855564] 'agreement among raft nodes before linearized reading' (duration: 240.031933ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:52:59.424671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.526146ms","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-12T16:52:59.424687Z","caller":"traceutil/trace.go:171","msg":"trace[871377643] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1022; }","duration":"241.575699ms","start":"2026-02-12T16:52:59.183106Z","end":"2026-02-12T16:52:59.424682Z","steps":["trace[871377643] 'agreement among raft nodes before linearized reading' (duration: 241.537587ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:52:59.42476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.863685ms","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-12T16:52:59.424775Z","caller":"traceutil/trace.go:171","msg":"trace[322800117] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1022; }","duration":"455.922567ms","start":"2026-02-12T16:52:58.968849Z","end":"2026-02-12T16:52:59.424771Z","steps":["trace[322800117] 'agreement among raft nodes before linearized reading' (duration: 455.885425ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:52:59.424788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:52:58.96883Z","time spent":"455.955219ms","remote":"127.0.0.1:34742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-12T16:52:59.985906Z","caller":"traceutil/trace.go:171","msg":"trace[1787198995] linearizableReadLoop","detail":"{readStateIndex:1061; appliedIndex:1060; }","duration":"556.379444ms","start":"2026-02-12T16:52:59.429509Z","end":"2026-02-12T16:52:59.985888Z","steps":["trace[1787198995] 'read index received' (duration: 65.015012ms)","trace[1787198995] 'applied index is now lower than readState.Index' (duration: 491.363802ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:52:59.985984Z","caller":"traceutil/trace.go:171","msg":"trace[46877831] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"552.898174ms","start":"2026-02-12T16:52:59.433057Z","end":"2026-02-12T16:52:59.985955Z","steps":["trace[46877831] 'process raft request' (duration: 552.746727ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:52:59.98604Z","caller":"traceutil/trace.go:171","msg":"trace[861701712] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"552.551287ms","start":"2026-02-12T16:52:59.433479Z","end":"2026-02-12T16:52:59.98603Z","steps":["trace[861701712] 'process raft request' (duration: 552.353967ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:52:59.986096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:52:59.433038Z","time spent":"552.997939ms","remote":"127.0.0.1:34742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T16:52:59.986122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:52:59.433473Z","time spent":"552.589598ms","remote":"127.0.0.1:34742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T16:52:59.986333Z","caller":"traceutil/trace.go:171","msg":"trace[1602952029] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"556.874348ms","start":"2026-02-12T16:52:59.42945Z","end":"2026-02-12T16:52:59.986325Z","steps":["trace[1602952029] 'process raft request' (duration: 556.161503ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:52:59.986368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:52:59.429441Z","time spent":"556.907449ms","remote":"127.0.0.1:34742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T16:52:59.986374Z","caller":"traceutil/trace.go:171","msg":"trace[882200441] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"552.022981ms","start":"2026-02-12T16:52:59.434339Z","end":"2026-02-12T16:52:59.986362Z","steps":["trace[882200441] 'process raft request' (duration: 551.526107ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:52:59.986414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:52:59.434333Z","time spent":"552.058743ms","remote":"127.0.0.1:34742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T16:52:59.98648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.970442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T16:52:59.9865Z","caller":"traceutil/trace.go:171","msg":"trace[2138801394] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1026; }","duration":"556.998373ms","start":"2026-02-12T16:52:59.429495Z","end":"2026-02-12T16:52:59.986494Z","steps":["trace[2138801394] 'agreement among raft nodes before linearized reading' (duration: 556.966812ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:52:59.986517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:52:59.42949Z","time spent":"557.023565ms","remote":"127.0.0.1:34470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T16:52:59.988546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.024313ms","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-12T16:52:59.988573Z","caller":"traceutil/trace.go:171","msg":"trace[1896314678] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1027; }","duration":"409.087926ms","start":"2026-02-12T16:52:59.579477Z","end":"2026-02-12T16:52:59.988565Z","steps":["trace[1896314678] 'agreement among raft nodes before linearized reading' (duration: 408.97755ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:52:59.988616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:52:59.579456Z","time spent":"409.144408ms","remote":"127.0.0.1:34742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-12T16:52:59.988644Z","caller":"traceutil/trace.go:171","msg":"trace[526573051] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"493.495046ms","start":"2026-02-12T16:52:59.495139Z","end":"2026-02-12T16:52:59.988634Z","steps":["trace[526573051] 'process raft request' (duration: 493.255944ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:52:59.988686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:52:59.495116Z","time spent":"493.547508ms","remote":"127.0.0.1:34494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T16:53:01.467732Z","caller":"traceutil/trace.go:171","msg":"trace[935978527] linearizableReadLoop","detail":"{readStateIndex:1069; appliedIndex:1068; }","duration":"200.730825ms","start":"2026-02-12T16:53:01.266957Z","end":"2026-02-12T16:53:01.467688Z","steps":["trace[935978527] 'read index received' (duration: 200.535705ms)","trace[935978527] 'applied index is now lower than readState.Index' (duration: 194.25µs)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:53:01.468214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.234229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329944"} {"level":"info","ts":"2026-02-12T16:53:01.468265Z","caller":"traceutil/trace.go:171","msg":"trace[1363456007] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:1031; }","duration":"201.326863ms","start":"2026-02-12T16:53:01.266925Z","end":"2026-02-12T16:53:01.468252Z","steps":["trace[1363456007] 'agreement among raft nodes before linearized reading' (duration: 200.872061ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:53:01.468857Z","caller":"traceutil/trace.go:171","msg":"trace[23095139] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"299.588933ms","start":"2026-02-12T16:53:01.169253Z","end":"2026-02-12T16:53:01.468842Z","steps":["trace[23095139] 'process raft request' (duration: 298.29878ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:53:02.17633Z","caller":"traceutil/trace.go:171","msg":"trace[1434177515] linearizableReadLoop","detail":"{readStateIndex:1075; appliedIndex:1072; }","duration":"107.859971ms","start":"2026-02-12T16:53:02.068453Z","end":"2026-02-12T16:53:02.176313Z","steps":["trace[1434177515] 'read index received' (duration: 107.645551ms)","trace[1434177515] 'applied index is now lower than readState.Index' (duration: 213.6µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:53:02.176568Z","caller":"traceutil/trace.go:171","msg":"trace[646587150] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"179.26868ms","start":"2026-02-12T16:53:01.997258Z","end":"2026-02-12T16:53:02.176526Z","steps":["trace[646587150] 'process raft request' (duration: 178.85451ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:53:02.176766Z","caller":"traceutil/trace.go:171","msg":"trace[2142620811] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"177.912694ms","start":"2026-02-12T16:53:01.998841Z","end":"2026-02-12T16:53:02.176753Z","steps":["trace[2142620811] 'process raft request' (duration: 177.397379ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:53:02.177512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.24934ms","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-12T16:53:02.177562Z","caller":"traceutil/trace.go:171","msg":"trace[584983315] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1036; }","duration":"108.306493ms","start":"2026-02-12T16:53:02.069246Z","end":"2026-02-12T16:53:02.177552Z","steps":["trace[584983315] 'agreement among raft nodes before linearized reading' (duration: 108.180787ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:53:02.177316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.839899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T16:53:02.177384Z","caller":"traceutil/trace.go:171","msg":"trace[472375068] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"178.483462ms","start":"2026-02-12T16:53:01.998883Z","end":"2026-02-12T16:53:02.177367Z","steps":["trace[472375068] 'process raft request' (duration: 177.395739ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:53:02.178884Z","caller":"traceutil/trace.go:171","msg":"trace[1862978266] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1036; }","duration":"110.431636ms","start":"2026-02-12T16:53:02.068436Z","end":"2026-02-12T16:53:02.178868Z","steps":["trace[1862978266] 'agreement among raft nodes before linearized reading' (duration: 108.827968ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:53:04.262508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.887557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/kubeadm:get-nodes\" ","response":"range_response_count:1 size:305"} {"level":"info","ts":"2026-02-12T16:53:04.262626Z","caller":"traceutil/trace.go:171","msg":"trace[1882720193] range","detail":"{range_begin:/registry/clusterroles/kubeadm:get-nodes; range_end:; response_count:1; response_revision:1038; }","duration":"184.043326ms","start":"2026-02-12T16:53:04.07856Z","end":"2026-02-12T16:53:04.262603Z","steps":["trace[1882720193] 'range keys from in-memory index tree' (duration: 183.804118ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:53:04.267473Z","caller":"traceutil/trace.go:171","msg":"trace[705317322] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"149.198104ms","start":"2026-02-12T16:53:04.118255Z","end":"2026-02-12T16:53:04.267453Z","steps":["trace[705317322] 'process raft request' (duration: 148.966596ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:53:04.267852Z","caller":"traceutil/trace.go:171","msg":"trace[934536937] linearizableReadLoop","detail":"{readStateIndex:1078; appliedIndex:1078; }","duration":"131.817478ms","start":"2026-02-12T16:53:04.136018Z","end":"2026-02-12T16:53:04.267835Z","steps":["trace[934536937] 'read index received' (duration: 131.811958ms)","trace[934536937] 'applied index is now lower than readState.Index' (duration: 4.59µs)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:53:04.268232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.209604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/local-path-provisioner\" ","response":"range_response_count:1 size:1106"} {"level":"info","ts":"2026-02-12T16:53:04.268451Z","caller":"traceutil/trace.go:171","msg":"trace[336708776] range","detail":"{range_begin:/registry/clusterroles/local-path-provisioner; range_end:; response_count:1; response_revision:1039; }","duration":"132.473282ms","start":"2026-02-12T16:53:04.135966Z","end":"2026-02-12T16:53:04.268439Z","steps":["trace[336708776] 'agreement among raft nodes before linearized reading' (duration: 132.046766ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:53:10.679741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.068434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:root-ca-cert-publisher\" ","response":"range_response_count:1 size:672"} {"level":"info","ts":"2026-02-12T16:53:10.679804Z","caller":"traceutil/trace.go:171","msg":"trace[494432310] range","detail":"{range_begin:/registry/clusterroles/system:controller:root-ca-cert-publisher; range_end:; response_count:1; response_revision:1060; }","duration":"101.160653ms","start":"2026-02-12T16:53:10.578626Z","end":"2026-02-12T16:53:10.679787Z","steps":["trace[494432310] 'range keys from in-memory index tree' (duration: 100.984674ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:53:16.610262Z","caller":"traceutil/trace.go:171","msg":"trace[705678428] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"139.164321ms","start":"2026-02-12T16:53:16.47108Z","end":"2026-02-12T16:53:16.610245Z","steps":["trace[705678428] 'process raft request' (duration: 119.026575ms)","trace[705678428] 'compare' (duration: 19.876007ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:53:16.811086Z","caller":"traceutil/trace.go:171","msg":"trace[1086103872] linearizableReadLoop","detail":"{readStateIndex:1119; appliedIndex:1118; }","duration":"182.1653ms","start":"2026-02-12T16:53:16.628903Z","end":"2026-02-12T16:53:16.811068Z","steps":["trace[1086103872] 'read index received' (duration: 56.136928ms)","trace[1086103872] 'applied index is now lower than readState.Index' (duration: 126.027092ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:53:16.811436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.983481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-node-lease/\" range_end:\"/registry/daemonsets/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T16:53:16.811506Z","caller":"traceutil/trace.go:171","msg":"trace[360432757] range","detail":"{range_begin:/registry/daemonsets/kube-node-lease/; range_end:/registry/daemonsets/kube-node-lease0; response_count:0; response_revision:1077; }","duration":"169.086161ms","start":"2026-02-12T16:53:16.642402Z","end":"2026-02-12T16:53:16.811488Z","steps":["trace[360432757] 'agreement among raft nodes before linearized reading' (duration: 168.965831ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:53:16.811761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.849646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-node-lease/kube-root-ca.crt\" ","response":"range_response_count:1 size:1754"} {"level":"info","ts":"2026-02-12T16:53:16.811817Z","caller":"traceutil/trace.go:171","msg":"trace[1231678252] range","detail":"{range_begin:/registry/configmaps/kube-node-lease/kube-root-ca.crt; range_end:; response_count:1; response_revision:1077; }","duration":"182.925126ms","start":"2026-02-12T16:53:16.628876Z","end":"2026-02-12T16:53:16.811801Z","steps":["trace[1231678252] 'agreement among raft nodes before linearized reading' (duration: 182.838557ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:53:16.812205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.047233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/default/\" range_end:\"/registry/ingress/default0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T16:53:16.812262Z","caller":"traceutil/trace.go:171","msg":"trace[1823420762] range","detail":"{range_begin:/registry/ingress/default/; range_end:/registry/ingress/default0; response_count:0; response_revision:1077; }","duration":"100.107063ms","start":"2026-02-12T16:53:16.712142Z","end":"2026-02-12T16:53:16.812249Z","steps":["trace[1823420762] 'agreement among raft nodes before linearized reading' (duration: 100.027543ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:53:16.812224Z","caller":"traceutil/trace.go:171","msg":"trace[1991775171] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"197.31027ms","start":"2026-02-12T16:53:16.613983Z","end":"2026-02-12T16:53:16.811293Z","steps":["trace[1991775171] 'process raft request' (duration: 159.043208ms)","trace[1991775171] 'compare' (duration: 37.915893ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:53:16.812479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.146222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-02-12T16:53:16.812528Z","caller":"traceutil/trace.go:171","msg":"trace[1974716890] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1077; }","duration":"113.227931ms","start":"2026-02-12T16:53:16.699288Z","end":"2026-02-12T16:53:16.812516Z","steps":["trace[1974716890] 'agreement among raft nodes before linearized reading' (duration: 113.145841ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:53:18.451964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.654176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-public/\" range_end:\"/registry/daemonsets/kube-public0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T16:53:18.452124Z","caller":"traceutil/trace.go:171","msg":"trace[289790545] range","detail":"{range_begin:/registry/daemonsets/kube-public/; range_end:/registry/daemonsets/kube-public0; response_count:0; response_revision:1078; }","duration":"123.871225ms","start":"2026-02-12T16:53:18.328235Z","end":"2026-02-12T16:53:18.452106Z","steps":["trace[289790545] 'range keys from in-memory index tree' (duration: 123.588086ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:53:18.909947Z","caller":"traceutil/trace.go:171","msg":"trace[243154496] linearizableReadLoop","detail":"{readStateIndex:1125; appliedIndex:1124; }","duration":"151.285129ms","start":"2026-02-12T16:53:18.758639Z","end":"2026-02-12T16:53:18.909924Z","steps":["trace[243154496] 'read index received' (duration: 121.113166ms)","trace[243154496] 'applied index is now lower than readState.Index' (duration: 30.170583ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:53:18.910039Z","caller":"traceutil/trace.go:171","msg":"trace[31292768] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"168.266642ms","start":"2026-02-12T16:53:18.741749Z","end":"2026-02-12T16:53:18.910016Z","steps":["trace[31292768] 'process raft request' (duration: 138.06607ms)","trace[31292768] 'compare' (duration: 30.007123ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:53:18.910682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.018866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T16:53:18.910827Z","caller":"traceutil/trace.go:171","msg":"trace[34369023] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1082; }","duration":"152.164025ms","start":"2026-02-12T16:53:18.758618Z","end":"2026-02-12T16:53:18.910782Z","steps":["trace[34369023] 'agreement among raft nodes before linearized reading' (duration: 151.862447ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:53:18.911303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.881481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/\" range_end:\"/registry/configmaps/kube-system0\" limit:500 ","response":"range_response_count:8 size:16534"} {"level":"info","ts":"2026-02-12T16:53:18.913547Z","caller":"traceutil/trace.go:171","msg":"trace[1601878388] range","detail":"{range_begin:/registry/configmaps/kube-system/; range_end:/registry/configmaps/kube-system0; response_count:8; response_revision:1082; }","duration":"153.131652ms","start":"2026-02-12T16:53:18.760391Z","end":"2026-02-12T16:53:18.913522Z","steps":["trace[1601878388] 'agreement among raft nodes before linearized reading' (duration: 150.807781ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:53:18.913935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.540946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/kube-system/\" range_end:\"/registry/cronjobs/kube-system0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T16:53:18.914414Z","caller":"traceutil/trace.go:171","msg":"trace[913301775] range","detail":"{range_begin:/registry/cronjobs/kube-system/; range_end:/registry/cronjobs/kube-system0; response_count:0; response_revision:1082; }","duration":"127.044654ms","start":"2026-02-12T16:53:18.787351Z","end":"2026-02-12T16:53:18.914396Z","steps":["trace[913301775] 'agreement among raft nodes before linearized reading' (duration: 126.532956ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:53:20.76781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.081455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-public/\" range_end:\"/registry/secrets/kube-public0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T16:53:20.768Z","caller":"traceutil/trace.go:171","msg":"trace[192023651] range","detail":"{range_begin:/registry/secrets/kube-public/; range_end:/registry/secrets/kube-public0; response_count:0; response_revision:1086; }","duration":"118.302384ms","start":"2026-02-12T16:53:20.649688Z","end":"2026-02-12T16:53:20.76799Z","steps":["trace[192023651] 'range keys from in-memory index tree' (duration: 118.021545ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:53:20.768174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.827529ms","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-12T16:53:20.76824Z","caller":"traceutil/trace.go:171","msg":"trace[183099131] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1086; }","duration":"110.915289ms","start":"2026-02-12T16:53:20.657318Z","end":"2026-02-12T16:53:20.768234Z","steps":["trace[183099131] 'range keys from in-memory index tree' (duration: 110.755319ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:53:30.144859Z","caller":"traceutil/trace.go:171","msg":"trace[1991371902] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"112.090079ms","start":"2026-02-12T16:53:30.032746Z","end":"2026-02-12T16:53:30.144836Z","steps":["trace[1991371902] 'process raft request' (duration: 111.648689ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:53:32.479375Z","caller":"traceutil/trace.go:171","msg":"trace[2040254824] transaction","detail":"{read_only:false; response_revision:1118; number_of_response:1; }","duration":"109.080761ms","start":"2026-02-12T16:53:32.370269Z","end":"2026-02-12T16:53:32.47935Z","steps":["trace[2040254824] 'process raft request' (duration: 108.603961ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:53:32.94683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.318955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-h4swj\" ","response":"range_response_count:1 size:18058"} {"level":"info","ts":"2026-02-12T16:53:32.946895Z","caller":"traceutil/trace.go:171","msg":"trace[941151350] range","detail":"{range_begin:/registry/pods/kube-system/cilium-h4swj; range_end:; response_count:1; response_revision:1119; }","duration":"121.391955ms","start":"2026-02-12T16:53:32.825488Z","end":"2026-02-12T16:53:32.94688Z","steps":["trace[941151350] 'range keys from in-memory index tree' (duration: 121.226075ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:53:33.92425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.543893ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-12T16:53:33.924346Z","caller":"traceutil/trace.go:171","msg":"trace[2002457928] linearizableReadLoop","detail":"{readStateIndex:1168; appliedIndex:1167; }","duration":"101.945138ms","start":"2026-02-12T16:53:33.822384Z","end":"2026-02-12T16:53:33.924329Z","steps":["trace[2002457928] 'read index received' (duration: 19.04µs)","trace[2002457928] 'applied index is now lower than readState.Index' (duration: 101.924848ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T16:53:33.924538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.141238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-qmxng\" ","response":"range_response_count:1 size:4624"} {"level":"info","ts":"2026-02-12T16:53:33.924608Z","caller":"traceutil/trace.go:171","msg":"trace[1311302466] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-qmxng; range_end:; response_count:1; response_revision:1122; }","duration":"102.234838ms","start":"2026-02-12T16:53:33.82236Z","end":"2026-02-12T16:53:33.924595Z","steps":["trace[1311302466] 'agreement among raft nodes before linearized reading' (duration: 102.014618ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T16:53:34.79502Z","caller":"traceutil/trace.go:171","msg":"trace[1853565787] linearizableReadLoop","detail":"{readStateIndex:1169; appliedIndex:1168; }","duration":"271.972497ms","start":"2026-02-12T16:53:34.523025Z","end":"2026-02-12T16:53:34.794998Z","steps":["trace[1853565787] 'read index received' (duration: 271.710287ms)","trace[1853565787] 'applied index is now lower than readState.Index' (duration: 261.35µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T16:53:34.795401Z","caller":"traceutil/trace.go:171","msg":"trace[1345336332] transaction","detail":"{read_only:false; response_revision:1123; number_of_response:1; }","duration":"310.635443ms","start":"2026-02-12T16:53:34.484748Z","end":"2026-02-12T16:53:34.795383Z","steps":["trace[1345336332] 'process raft request' (duration: 310.057412ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T16:53:34.795573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T16:53:34.484731Z","time spent":"310.747663ms","remote":"127.0.0.1:34742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T16:53:34.79584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.808509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-cgc7k\" ","response":"range_response_count:1 size:4844"} {"level":"info","ts":"2026-02-12T16:53:34.79588Z","caller":"traceutil/trace.go:171","msg":"trace[1442566408] range","detail":"{range_begin:/registry/pods/kube-system/coredns-77cccfdc44-cgc7k; range_end:; response_count:1; response_revision:1123; }","duration":"272.876209ms","start":"2026-02-12T16:53:34.522993Z","end":"2026-02-12T16:53:34.79587Z","steps":["trace[1442566408] 'agreement among raft nodes before linearized reading' (duration: 272.722498ms)"],"step_count":1}