{"level":"warn","ts":"2026-07-21T05:30:30.301008Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-21T05:30:30.301143Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.203: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.203:2380","--initial-cluster=instance=https://199.19.213.203:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.203:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.203: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-07-21T05:30:30.301232Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-21T05:30:30.301243Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.203:2380"]} {"level":"info","ts":"2026-07-21T05:30:30.301263Z","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-07-21T05:30:30.302083Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.203:2379"]} {"level":"info","ts":"2026-07-21T05:30:30.302178Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.203:2380"],"listen-peer-urls":["https://199.19.213.203:2380"],"advertise-client-urls":["https://199.19.213.203:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.203:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.203: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-07-21T05:30:30.306196Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.750959ms"} {"level":"info","ts":"2026-07-21T05:30:30.317527Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b5a1dacc8ba86a4f","cluster-id":"a3ec0b489c26f222"} {"level":"info","ts":"2026-07-21T05:30:30.317608Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b5a1dacc8ba86a4f switched to configuration voters=()"} {"level":"info","ts":"2026-07-21T05:30:30.317641Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b5a1dacc8ba86a4f became follower at term 0"} {"level":"info","ts":"2026-07-21T05:30:30.31771Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b5a1dacc8ba86a4f [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-21T05:30:30.317718Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b5a1dacc8ba86a4f became follower at term 1"} {"level":"info","ts":"2026-07-21T05:30:30.317756Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b5a1dacc8ba86a4f switched to configuration voters=(13087982564166625871)"} {"level":"warn","ts":"2026-07-21T05:30:30.325763Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-21T05:30:30.327207Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-21T05:30:30.328636Z","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-07-21T05:30:30.333744Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b5a1dacc8ba86a4f","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-21T05:30:30.335781Z","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-07-21T05:30:30.335853Z","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-07-21T05:30:30.335863Z","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-07-21T05:30:30.335811Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b5a1dacc8ba86a4f","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-21T05:30:30.340951Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b5a1dacc8ba86a4f switched to configuration voters=(13087982564166625871)"} {"level":"info","ts":"2026-07-21T05:30:30.341084Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"a3ec0b489c26f222","local-member-id":"b5a1dacc8ba86a4f","added-peer-id":"b5a1dacc8ba86a4f","added-peer-peer-urls":["https://199.19.213.203:2380"]} {"level":"info","ts":"2026-07-21T05:30:30.341302Z","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-07-21T05:30:30.341396Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.203:2380"} {"level":"info","ts":"2026-07-21T05:30:30.341458Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.203:2380"} {"level":"info","ts":"2026-07-21T05:30:30.341622Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b5a1dacc8ba86a4f","initial-advertise-peer-urls":["https://199.19.213.203:2380"],"listen-peer-urls":["https://199.19.213.203:2380"],"advertise-client-urls":["https://199.19.213.203:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.203:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-21T05:30:30.341692Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-21T05:30:31.117934Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b5a1dacc8ba86a4f is starting a new election at term 1"} {"level":"info","ts":"2026-07-21T05:30:31.118035Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b5a1dacc8ba86a4f became pre-candidate at term 1"} {"level":"info","ts":"2026-07-21T05:30:31.11806Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b5a1dacc8ba86a4f received MsgPreVoteResp from b5a1dacc8ba86a4f at term 1"} {"level":"info","ts":"2026-07-21T05:30:31.11808Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b5a1dacc8ba86a4f became candidate at term 2"} {"level":"info","ts":"2026-07-21T05:30:31.118097Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b5a1dacc8ba86a4f received MsgVoteResp from b5a1dacc8ba86a4f at term 2"} {"level":"info","ts":"2026-07-21T05:30:31.118119Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b5a1dacc8ba86a4f became leader at term 2"} {"level":"info","ts":"2026-07-21T05:30:31.118138Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b5a1dacc8ba86a4f elected leader b5a1dacc8ba86a4f at term 2"} {"level":"info","ts":"2026-07-21T05:30:31.119308Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T05:30:31.120019Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-21T05:30:31.120014Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b5a1dacc8ba86a4f","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.203:2379]}","request-path":"/0/members/b5a1dacc8ba86a4f/attributes","cluster-id":"a3ec0b489c26f222","publish-timeout":"7s"} {"level":"info","ts":"2026-07-21T05:30:31.120189Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-21T05:30:31.120408Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-21T05:30:31.120541Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-21T05:30:31.120787Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"a3ec0b489c26f222","local-member-id":"b5a1dacc8ba86a4f","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T05:30:31.120906Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T05:30:31.120945Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T05:30:31.122643Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-21T05:30:31.123731Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.203:2379"} {"level":"warn","ts":"2026-07-21T05:30:33.863799Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7660516876832353812,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-21T05:30:33.909196Z","caller":"traceutil/trace.go:171","msg":"trace[1570670289] linearizableReadLoop","detail":"{readStateIndex:117; appliedIndex:116; }","duration":"545.601492ms","start":"2026-07-21T05:30:33.363567Z","end":"2026-07-21T05:30:33.909168Z","steps":["trace[1570670289] 'read index received' (duration: 545.439488ms)","trace[1570670289] 'applied index is now lower than readState.Index' (duration: 160.844µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:30:33.90933Z","caller":"traceutil/trace.go:171","msg":"trace[2042316773] transaction","detail":"{read_only:false; response_revision:112; number_of_response:1; }","duration":"547.327513ms","start":"2026-07-21T05:30:33.361992Z","end":"2026-07-21T05:30:33.90932Z","steps":["trace[2042316773] 'process raft request' (duration: 547.022234ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:30:33.909622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.090345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-21T05:30:33.909633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.033004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-21T05:30:33.90983Z","caller":"traceutil/trace.go:171","msg":"trace[1390663999] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:112; }","duration":"441.296011ms","start":"2026-07-21T05:30:33.468446Z","end":"2026-07-21T05:30:33.909742Z","steps":["trace[1390663999] 'agreement among raft nodes before linearized reading' (duration: 440.987722ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:30:33.9099Z","caller":"traceutil/trace.go:171","msg":"trace[750215222] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-edit; range_end:; response_count:0; response_revision:112; }","duration":"546.339873ms","start":"2026-07-21T05:30:33.363533Z","end":"2026-07-21T05:30:33.909873Z","steps":["trace[750215222] 'agreement among raft nodes before linearized reading' (duration: 546.001713ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:30:33.90997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:30:33.363516Z","time spent":"546.436696ms","remote":"127.0.0.1:44524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":0,"response size":28,"request content":"key:\"/registry/clusterroles/system:aggregate-to-edit\" "} {"level":"warn","ts":"2026-07-21T05:30:33.909954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:30:33.46843Z","time spent":"441.508176ms","remote":"127.0.0.1:44170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-21T05:30:33.910081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:30:33.361976Z","time spent":"547.383564ms","remote":"127.0.0.1:44540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T05:30:34.187835Z","caller":"traceutil/trace.go:171","msg":"trace[1931702840] linearizableReadLoop","detail":"{readStateIndex:118; appliedIndex:117; }","duration":"272.2774ms","start":"2026-07-21T05:30:33.915522Z","end":"2026-07-21T05:30:34.187799Z","steps":["trace[1931702840] 'read index received' (duration: 272.099246ms)","trace[1931702840] 'applied index is now lower than readState.Index' (duration: 177.274µs)"],"step_count":2} {"level":"warn","ts":"2026-07-21T05:30:34.188061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.485996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-view\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-21T05:30:34.188122Z","caller":"traceutil/trace.go:171","msg":"trace[1932021437] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-view; range_end:; response_count:0; response_revision:113; }","duration":"272.63902ms","start":"2026-07-21T05:30:33.915468Z","end":"2026-07-21T05:30:34.188107Z","steps":["trace[1932021437] 'agreement among raft nodes before linearized reading' (duration: 272.436514ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:30:34.188209Z","caller":"traceutil/trace.go:171","msg":"trace[1816428755] transaction","detail":"{read_only:false; response_revision:113; number_of_response:1; }","duration":"273.155656ms","start":"2026-07-21T05:30:33.915025Z","end":"2026-07-21T05:30:34.188181Z","steps":["trace[1816428755] 'process raft request' (duration: 272.652091ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:30:34.650786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.973403ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-07-21T05:30:34.651524Z","caller":"traceutil/trace.go:171","msg":"trace[154649645] transaction","detail":"{read_only:false; response_revision:114; number_of_response:1; }","duration":"450.190257ms","start":"2026-07-21T05:30:34.201305Z","end":"2026-07-21T05:30:34.651495Z","steps":["trace[154649645] 'process raft request' (duration: 336.899046ms)","trace[154649645] 'compare' (duration: 111.223302ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T05:30:34.651631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:30:34.201286Z","time spent":"450.287561ms","remote":"127.0.0.1:44524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T05:31:12.047725Z","caller":"traceutil/trace.go:171","msg":"trace[958587513] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"492.441098ms","start":"2026-07-21T05:31:11.555246Z","end":"2026-07-21T05:31:12.047687Z","steps":["trace[958587513] 'process raft request' (duration: 403.950703ms)","trace[958587513] 'compare' (duration: 88.166846ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T05:31:12.047949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:31:11.555213Z","time spent":"492.614952ms","remote":"127.0.0.1:44252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T05:31:12.0494Z","caller":"traceutil/trace.go:171","msg":"trace[399442756] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"468.469735ms","start":"2026-07-21T05:31:11.580889Z","end":"2026-07-21T05:31:12.049358Z","steps":["trace[399442756] 'process raft request' (duration: 468.373392ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:31:12.04964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:31:11.58086Z","time spent":"468.650861ms","remote":"127.0.0.1:44252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T05:31:12.049667Z","caller":"traceutil/trace.go:171","msg":"trace[1626196351] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"491.087238ms","start":"2026-07-21T05:31:11.558565Z","end":"2026-07-21T05:31:12.049652Z","steps":["trace[1626196351] 'process raft request' (duration: 488.973166ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:31:12.049893Z","caller":"traceutil/trace.go:171","msg":"trace[1112340904] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"486.66861ms","start":"2026-07-21T05:31:11.563211Z","end":"2026-07-21T05:31:12.049879Z","steps":["trace[1112340904] 'process raft request' (duration: 484.9065ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:31:12.051271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:31:11.558526Z","time spent":"492.639822ms","remote":"127.0.0.1:44690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T05:31:12.051618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:31:11.563184Z","time spent":"488.084781ms","remote":"127.0.0.1:44252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9125,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T05:31:12.124527Z","caller":"traceutil/trace.go:171","msg":"trace[683388026] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"498.283317ms","start":"2026-07-21T05:31:11.626209Z","end":"2026-07-21T05:31:12.124492Z","steps":["trace[683388026] 'process raft request' (duration: 497.963777ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:31:12.124709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:31:11.62618Z","time spent":"498.43756ms","remote":"127.0.0.1:44690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T05:31:12.124716Z","caller":"traceutil/trace.go:171","msg":"trace[1023174079] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"493.675073ms","start":"2026-07-21T05:31:11.63098Z","end":"2026-07-21T05:31:12.124655Z","steps":["trace[1023174079] 'process raft request' (duration: 493.433595ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:31:12.125072Z","caller":"traceutil/trace.go:171","msg":"trace[927636864] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"490.773469ms","start":"2026-07-21T05:31:11.634289Z","end":"2026-07-21T05:31:12.125062Z","steps":["trace[927636864] 'process raft request' (duration: 490.175612ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:31:12.125139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:31:11.634274Z","time spent":"490.820622ms","remote":"127.0.0.1:44252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T05:31:12.125173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:31:11.630962Z","time spent":"493.988352ms","remote":"127.0.0.1:44690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T05:31:12.12522Z","caller":"traceutil/trace.go:171","msg":"trace[179734671] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"488.324479ms","start":"2026-07-21T05:31:11.636826Z","end":"2026-07-21T05:31:12.12515Z","steps":["trace[179734671] 'process raft request' (duration: 487.728412ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:31:12.1253Z","caller":"traceutil/trace.go:171","msg":"trace[1254585779] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"488.405371ms","start":"2026-07-21T05:31:11.636878Z","end":"2026-07-21T05:31:12.125283Z","steps":["trace[1254585779] 'process raft request' (duration: 487.880316ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:31:12.125552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:31:11.636868Z","time spent":"488.528014ms","remote":"127.0.0.1:44690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T05:31:12.125539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:31:11.636812Z","time spent":"488.486123ms","remote":"127.0.0.1:44252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6888,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T05:31:12.125778Z","caller":"traceutil/trace.go:171","msg":"trace[30805837] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"488.644898ms","start":"2026-07-21T05:31:11.637118Z","end":"2026-07-21T05:31:12.125763Z","steps":["trace[30805837] 'process raft request' (duration: 487.7115ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:31:12.125855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:31:11.637087Z","time spent":"488.727571ms","remote":"127.0.0.1:44252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T05:31:12.126137Z","caller":"traceutil/trace.go:171","msg":"trace[723732848] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"485.511707ms","start":"2026-07-21T05:31:11.640583Z","end":"2026-07-21T05:31:12.126095Z","steps":["trace[723732848] 'process raft request' (duration: 485.137536ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:31:12.126179Z","caller":"traceutil/trace.go:171","msg":"trace[663494055] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"485.427855ms","start":"2026-07-21T05:31:11.640736Z","end":"2026-07-21T05:31:12.126164Z","steps":["trace[663494055] 'process raft request' (duration: 485.24877ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:31:12.125222Z","caller":"traceutil/trace.go:171","msg":"trace[863667253] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"498.659478ms","start":"2026-07-21T05:31:11.626335Z","end":"2026-07-21T05:31:12.124995Z","steps":["trace[863667253] 'process raft request' (duration: 498.029419ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:31:12.126382Z","caller":"traceutil/trace.go:171","msg":"trace[253131634] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"484.876358ms","start":"2026-07-21T05:31:11.641487Z","end":"2026-07-21T05:31:12.126363Z","steps":["trace[253131634] 'process raft request' (duration: 484.610431ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:31:12.126432Z","caller":"traceutil/trace.go:171","msg":"trace[395803871] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"370.573939ms","start":"2026-07-21T05:31:11.755845Z","end":"2026-07-21T05:31:12.126419Z","steps":["trace[395803871] 'process raft request' (duration: 370.448026ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:31:12.126428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:31:11.640705Z","time spent":"485.594059ms","remote":"127.0.0.1:44252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T05:31:12.126461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:31:11.641478Z","time spent":"484.9298ms","remote":"127.0.0.1:44252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T05:31:12.126483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:31:11.755812Z","time spent":"370.65069ms","remote":"127.0.0.1:44252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T05:31:12.126598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:31:11.626327Z","time spent":"499.995225ms","remote":"127.0.0.1:44690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T05:31:12.126398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:31:11.640569Z","time spent":"485.667921ms","remote":"127.0.0.1:44252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T05:31:12.273527Z","caller":"traceutil/trace.go:171","msg":"trace[181512648] linearizableReadLoop","detail":"{readStateIndex:580; appliedIndex:570; }","duration":"209.637253ms","start":"2026-07-21T05:31:12.063858Z","end":"2026-07-21T05:31:12.273495Z","steps":["trace[181512648] 'read index received' (duration: 60.61046ms)","trace[181512648] 'applied index is now lower than readState.Index' (duration: 149.025453ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:31:12.274045Z","caller":"traceutil/trace.go:171","msg":"trace[1817142898] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"214.024719ms","start":"2026-07-21T05:31:12.059998Z","end":"2026-07-21T05:31:12.274023Z","steps":["trace[1817142898] 'process raft request' (duration: 149.856217ms)","trace[1817142898] 'compare' (duration: 63.39716ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:31:12.274104Z","caller":"traceutil/trace.go:171","msg":"trace[196827226] transaction","detail":"{read_only:false; number_of_response:1; response_revision:559; }","duration":"213.780172ms","start":"2026-07-21T05:31:12.060258Z","end":"2026-07-21T05:31:12.274038Z","steps":["trace[196827226] 'process raft request' (duration: 213.180454ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:31:12.274318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.417135ms","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-07-21T05:31:12.274436Z","caller":"traceutil/trace.go:171","msg":"trace[51786893] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:559; }","duration":"210.58405ms","start":"2026-07-21T05:31:12.063827Z","end":"2026-07-21T05:31:12.274411Z","steps":["trace[51786893] 'agreement among raft nodes before linearized reading' (duration: 210.284261ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:31:12.345476Z","caller":"traceutil/trace.go:171","msg":"trace[1355635930] transaction","detail":"{read_only:false; number_of_response:0; response_revision:559; }","duration":"210.190249ms","start":"2026-07-21T05:31:12.135247Z","end":"2026-07-21T05:31:12.345437Z","steps":["trace[1355635930] 'process raft request' (duration: 209.934681ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:31:12.34562Z","caller":"traceutil/trace.go:171","msg":"trace[2126425962] transaction","detail":"{read_only:false; number_of_response:0; response_revision:559; }","duration":"208.813229ms","start":"2026-07-21T05:31:12.136778Z","end":"2026-07-21T05:31:12.345591Z","steps":["trace[2126425962] 'process raft request' (duration: 208.588282ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:31:12.345735Z","caller":"traceutil/trace.go:171","msg":"trace[2085493770] transaction","detail":"{read_only:false; number_of_response:0; response_revision:559; }","duration":"208.578401ms","start":"2026-07-21T05:31:12.13715Z","end":"2026-07-21T05:31:12.345728Z","steps":["trace[2085493770] 'process raft request' (duration: 208.259271ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:31:12.345931Z","caller":"traceutil/trace.go:171","msg":"trace[1886001317] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"184.844976ms","start":"2026-07-21T05:31:12.16107Z","end":"2026-07-21T05:31:12.345915Z","steps":["trace[1886001317] 'process raft request' (duration: 184.392413ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:31:12.346132Z","caller":"traceutil/trace.go:171","msg":"trace[934663420] transaction","detail":"{read_only:false; number_of_response:0; response_revision:559; }","duration":"208.778748ms","start":"2026-07-21T05:31:12.13667Z","end":"2026-07-21T05:31:12.345448Z","steps":["trace[934663420] 'process raft request' (duration: 208.666225ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:31:12.348649Z","caller":"traceutil/trace.go:171","msg":"trace[900156083] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"187.38803ms","start":"2026-07-21T05:31:12.161222Z","end":"2026-07-21T05:31:12.34861Z","steps":["trace[900156083] 'process raft request' (duration: 184.482756ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:31:12.351566Z","caller":"traceutil/trace.go:171","msg":"trace[960253736] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"160.114002ms","start":"2026-07-21T05:31:12.191438Z","end":"2026-07-21T05:31:12.351552Z","steps":["trace[960253736] 'process raft request' (duration: 159.721041ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:31:12.353754Z","caller":"traceutil/trace.go:171","msg":"trace[1742433247] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"180.570503ms","start":"2026-07-21T05:31:12.17315Z","end":"2026-07-21T05:31:12.35372Z","steps":["trace[1742433247] 'process raft request' (duration: 174.979851ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:31:12.781673Z","caller":"traceutil/trace.go:171","msg":"trace[323307623] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"561.274565ms","start":"2026-07-21T05:31:12.220366Z","end":"2026-07-21T05:31:12.78164Z","steps":["trace[323307623] 'process raft request' (duration: 559.056711ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:31:12.782146Z","caller":"traceutil/trace.go:171","msg":"trace[1127870128] linearizableReadLoop","detail":"{readStateIndex:589; appliedIndex:583; }","duration":"508.48229ms","start":"2026-07-21T05:31:12.273613Z","end":"2026-07-21T05:31:12.782095Z","steps":["trace[1127870128] 'read index received' (duration: 71.780232ms)","trace[1127870128] 'applied index is now lower than readState.Index' (duration: 436.700948ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:31:12.782129Z","caller":"traceutil/trace.go:171","msg":"trace[49178425] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"501.680334ms","start":"2026-07-21T05:31:12.280406Z","end":"2026-07-21T05:31:12.782086Z","steps":["trace[49178425] 'process raft request' (duration: 501.169059ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:31:12.782238Z","caller":"traceutil/trace.go:171","msg":"trace[579511147] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"494.313172ms","start":"2026-07-21T05:31:12.287916Z","end":"2026-07-21T05:31:12.782229Z","steps":["trace[579511147] 'process raft request' (duration: 494.065965ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:31:12.782332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:31:12.287879Z","time spent":"494.388224ms","remote":"127.0.0.1:44252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7684,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T05:31:12.782378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:31:12.280376Z","time spent":"501.852659ms","remote":"127.0.0.1:44460","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-07-21T05:31:12.78256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:31:12.220302Z","time spent":"561.46608ms","remote":"127.0.0.1:44252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T05:31:12.782839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.972775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T05:31:12.782992Z","caller":"traceutil/trace.go:171","msg":"trace[1529897221] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:566; }","duration":"598.267392ms","start":"2026-07-21T05:31:12.184693Z","end":"2026-07-21T05:31:12.78296Z","steps":["trace[1529897221] 'agreement among raft nodes before linearized reading' (duration: 597.654995ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:31:12.783098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:31:12.184665Z","time spent":"598.417867ms","remote":"127.0.0.1:44170","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-07-21T05:31:12.78882Z","caller":"traceutil/trace.go:171","msg":"trace[69578093] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"420.040477ms","start":"2026-07-21T05:31:12.368763Z","end":"2026-07-21T05:31:12.788803Z","steps":["trace[69578093] 'process raft request' (duration: 419.518472ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:31:12.788891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:31:12.368733Z","time spent":"420.13052ms","remote":"127.0.0.1:44690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":986,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T05:31:12.789242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.394116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha3.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:1013"} {"level":"info","ts":"2026-07-21T05:31:12.789281Z","caller":"traceutil/trace.go:171","msg":"trace[720141566] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1alpha3.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:568; }","duration":"438.458849ms","start":"2026-07-21T05:31:12.350812Z","end":"2026-07-21T05:31:12.78927Z","steps":["trace[720141566] 'agreement among raft nodes before linearized reading' (duration: 438.245333ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:31:12.789303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:31:12.350791Z","time spent":"438.50818ms","remote":"127.0.0.1:44690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1037,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha3.gateway.networking.k8s.io\" "} {"level":"info","ts":"2026-07-21T05:31:12.7894Z","caller":"traceutil/trace.go:171","msg":"trace[2102308976] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"353.762054ms","start":"2026-07-21T05:31:12.435629Z","end":"2026-07-21T05:31:12.789391Z","steps":["trace[2102308976] 'process raft request' (duration: 353.165726ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:31:12.789441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:31:12.435531Z","time spent":"353.889928ms","remote":"127.0.0.1:44252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":97665,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T05:31:12.789492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.460168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1beta1.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:1010"} {"level":"warn","ts":"2026-07-21T05:31:12.789602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.712438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha2.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:1013"} {"level":"info","ts":"2026-07-21T05:31:12.789642Z","caller":"traceutil/trace.go:171","msg":"trace[267314436] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1alpha2.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:568; }","duration":"437.751359ms","start":"2026-07-21T05:31:12.351873Z","end":"2026-07-21T05:31:12.789624Z","steps":["trace[267314436] 'agreement among raft nodes before linearized reading' (duration: 437.689747ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:31:12.789674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:31:12.351863Z","time spent":"437.798689ms","remote":"127.0.0.1:44690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1037,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha2.gateway.networking.k8s.io\" "} {"level":"info","ts":"2026-07-21T05:31:12.789712Z","caller":"traceutil/trace.go:171","msg":"trace[808345361] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1beta1.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:568; }","duration":"439.749456ms","start":"2026-07-21T05:31:12.349844Z","end":"2026-07-21T05:31:12.789593Z","steps":["trace[808345361] 'agreement among raft nodes before linearized reading' (duration: 439.215811ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:31:12.789837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:31:12.349789Z","time spent":"440.037505ms","remote":"127.0.0.1:44690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":1034,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/v1beta1.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-07-21T05:31:12.789547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.325155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:995"} {"level":"info","ts":"2026-07-21T05:31:12.789953Z","caller":"traceutil/trace.go:171","msg":"trace[1842555263] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:568; }","duration":"437.725848ms","start":"2026-07-21T05:31:12.352207Z","end":"2026-07-21T05:31:12.789933Z","steps":["trace[1842555263] 'agreement among raft nodes before linearized reading' (duration: 437.286975ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:31:12.789994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:31:12.3522Z","time spent":"437.78351ms","remote":"127.0.0.1:44690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":1019,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/v1.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-07-21T05:31:19.537415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.847083ms","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-07-21T05:31:19.537552Z","caller":"traceutil/trace.go:171","msg":"trace[341613279] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:647; }","duration":"109.062679ms","start":"2026-07-21T05:31:19.428463Z","end":"2026-07-21T05:31:19.537526Z","steps":["trace[341613279] 'range keys from in-memory index tree' (duration: 108.606306ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:31:20.087293Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7660516876832354922,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-21T05:31:20.417428Z","caller":"traceutil/trace.go:171","msg":"trace[846119503] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"874.331163ms","start":"2026-07-21T05:31:19.543069Z","end":"2026-07-21T05:31:20.4174Z","steps":["trace[846119503] 'process raft request' (duration: 874.085825ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:31:20.4176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:31:19.543037Z","time spent":"874.470897ms","remote":"127.0.0.1:44460","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-07-21T05:31:20.500739Z","caller":"traceutil/trace.go:171","msg":"trace[1051022536] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"941.726239ms","start":"2026-07-21T05:31:19.55897Z","end":"2026-07-21T05:31:20.500696Z","steps":["trace[1051022536] 'process raft request' (duration: 939.176664ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:31:20.500955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:31:19.558937Z","time spent":"941.906595ms","remote":"127.0.0.1:44292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T05:31:20.533072Z","caller":"traceutil/trace.go:171","msg":"trace[1784206701] linearizableReadLoop","detail":"{readStateIndex:675; appliedIndex:674; }","duration":"946.791926ms","start":"2026-07-21T05:31:19.586254Z","end":"2026-07-21T05:31:20.533046Z","steps":["trace[1784206701] 'read index received' (duration: 831.558169ms)","trace[1784206701] 'applied index is now lower than readState.Index' (duration: 115.232337ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T05:31:20.536253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"949.979177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-bjm59\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-07-21T05:31:20.536336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.925457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T05:31:20.536385Z","caller":"traceutil/trace.go:171","msg":"trace[1621692828] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:649; }","duration":"355.027579ms","start":"2026-07-21T05:31:20.181339Z","end":"2026-07-21T05:31:20.536366Z","steps":["trace[1621692828] 'agreement among raft nodes before linearized reading' (duration: 354.950197ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:31:20.536434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:31:20.181313Z","time spent":"355.110342ms","remote":"127.0.0.1:44170","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-07-21T05:31:20.536634Z","caller":"traceutil/trace.go:171","msg":"trace[1370331098] range","detail":"{range_begin:/registry/pods/kube-system/cilium-bjm59; range_end:; response_count:1; response_revision:649; }","duration":"950.101131ms","start":"2026-07-21T05:31:19.5862Z","end":"2026-07-21T05:31:20.536302Z","steps":["trace[1370331098] 'agreement among raft nodes before linearized reading' (duration: 949.811262ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:31:20.536712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:31:19.586085Z","time spent":"950.619385ms","remote":"127.0.0.1:44380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-bjm59\" "} {"level":"warn","ts":"2026-07-21T05:31:21.32301Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7660516876832354928,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T05:31:21.751554Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.194741364s","expected-duration":"1s"} {"level":"info","ts":"2026-07-21T05:31:21.752821Z","caller":"traceutil/trace.go:171","msg":"trace[96775895] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"1.196092062s","start":"2026-07-21T05:31:20.55669Z","end":"2026-07-21T05:31:21.752782Z","steps":["trace[96775895] 'process raft request' (duration: 1.195852905s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:31:21.753049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:31:20.556669Z","time spent":"1.196260427s","remote":"127.0.0.1:44380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T05:31:21.8437Z","caller":"traceutil/trace.go:171","msg":"trace[877245493] linearizableReadLoop","detail":"{readStateIndex:678; appliedIndex:676; }","duration":"1.020725589s","start":"2026-07-21T05:31:20.822934Z","end":"2026-07-21T05:31:21.84366Z","steps":["trace[877245493] 'read index received' (duration: 929.522716ms)","trace[877245493] 'applied index is now lower than readState.Index' (duration: 91.201343ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:31:21.843741Z","caller":"traceutil/trace.go:171","msg":"trace[1267192386] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"1.259578225s","start":"2026-07-21T05:31:20.584136Z","end":"2026-07-21T05:31:21.843714Z","steps":["trace[1267192386] 'process raft request' (duration: 1.247913777s)","trace[1267192386] 'compare' (duration: 11.363619ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T05:31:21.844048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:31:20.584115Z","time spent":"1.259849263s","remote":"127.0.0.1:44438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T05:31:21.844075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.021112831s","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-07-21T05:31:21.844187Z","caller":"traceutil/trace.go:171","msg":"trace[2102243378] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:651; }","duration":"1.021274465s","start":"2026-07-21T05:31:20.822889Z","end":"2026-07-21T05:31:21.844164Z","steps":["trace[2102243378] 'agreement among raft nodes before linearized reading' (duration: 1.020902834s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:31:21.844235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:31:20.822866Z","time spent":"1.021362037s","remote":"127.0.0.1:44460","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-07-21T05:31:21.849649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.003289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T05:31:21.849689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"884.557739ms","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-07-21T05:31:21.84974Z","caller":"traceutil/trace.go:171","msg":"trace[578992086] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:651; }","duration":"665.187184ms","start":"2026-07-21T05:31:21.184533Z","end":"2026-07-21T05:31:21.849721Z","steps":["trace[578992086] 'agreement among raft nodes before linearized reading' (duration: 665.003089ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:31:21.849752Z","caller":"traceutil/trace.go:171","msg":"trace[325967335] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:651; }","duration":"884.662012ms","start":"2026-07-21T05:31:20.965066Z","end":"2026-07-21T05:31:21.849728Z","steps":["trace[325967335] 'agreement among raft nodes before linearized reading' (duration: 884.463536ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:31:21.849791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:31:20.965043Z","time spent":"884.742473ms","remote":"127.0.0.1:44460","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-07-21T05:31:21.849791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:31:21.184504Z","time spent":"665.277057ms","remote":"127.0.0.1:44170","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-07-21T05:31:21.849918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"858.551527ms","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-07-21T05:31:21.849948Z","caller":"traceutil/trace.go:171","msg":"trace[1876613790] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:651; }","duration":"858.6299ms","start":"2026-07-21T05:31:20.99131Z","end":"2026-07-21T05:31:21.84994Z","steps":["trace[1876613790] 'agreement among raft nodes before linearized reading' (duration: 858.552017ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:31:21.849967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:31:20.991281Z","time spent":"858.681681ms","remote":"127.0.0.1:44460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-21T05:31:24.104352Z","caller":"traceutil/trace.go:171","msg":"trace[769209272] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"384.624549ms","start":"2026-07-21T05:31:23.719664Z","end":"2026-07-21T05:31:24.104288Z","steps":["trace[769209272] 'process raft request' (duration: 374.098717ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:31:24.1045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:31:23.719638Z","time spent":"384.796535ms","remote":"127.0.0.1:44276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":722,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T05:31:24.106607Z","caller":"traceutil/trace.go:171","msg":"trace[1351875582] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"267.116508ms","start":"2026-07-21T05:31:23.839471Z","end":"2026-07-21T05:31:24.106588Z","steps":["trace[1351875582] 'process raft request' (duration: 266.748447ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:31:24.106727Z","caller":"traceutil/trace.go:171","msg":"trace[1698455266] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"257.885711ms","start":"2026-07-21T05:31:23.848819Z","end":"2026-07-21T05:31:24.106705Z","steps":["trace[1698455266] 'process raft request' (duration: 257.542281ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:31:24.106977Z","caller":"traceutil/trace.go:171","msg":"trace[1465520863] linearizableReadLoop","detail":"{readStateIndex:714; appliedIndex:711; }","duration":"232.748984ms","start":"2026-07-21T05:31:23.874216Z","end":"2026-07-21T05:31:24.106965Z","steps":["trace[1465520863] 'read index received' (duration: 219.567027ms)","trace[1465520863] 'applied index is now lower than readState.Index' (duration: 13.180917ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T05:31:24.107279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.049433ms","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-07-21T05:31:24.107312Z","caller":"traceutil/trace.go:171","msg":"trace[1837043375] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:688; }","duration":"233.125385ms","start":"2026-07-21T05:31:23.874179Z","end":"2026-07-21T05:31:24.107304Z","steps":["trace[1837043375] 'agreement among raft nodes before linearized reading' (duration: 232.965871ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:31:24.107508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.66103ms","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-07-21T05:31:24.107532Z","caller":"traceutil/trace.go:171","msg":"trace[782246517] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:688; }","duration":"226.69865ms","start":"2026-07-21T05:31:23.880827Z","end":"2026-07-21T05:31:24.107526Z","steps":["trace[782246517] 'agreement among raft nodes before linearized reading' (duration: 226.640759ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:31:24.108941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.256606ms","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-07-21T05:31:24.109274Z","caller":"traceutil/trace.go:171","msg":"trace[1550775778] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:688; }","duration":"228.621665ms","start":"2026-07-21T05:31:23.88061Z","end":"2026-07-21T05:31:24.109231Z","steps":["trace[1550775778] 'agreement among raft nodes before linearized reading' (duration: 226.568086ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:31:37.395711Z","caller":"traceutil/trace.go:171","msg":"trace[249275160] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"194.668495ms","start":"2026-07-21T05:31:37.201017Z","end":"2026-07-21T05:31:37.395685Z","steps":["trace[249275160] 'process raft request' (duration: 194.533611ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:31:38.044281Z","caller":"traceutil/trace.go:171","msg":"trace[1454079047] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"315.627968ms","start":"2026-07-21T05:31:37.72863Z","end":"2026-07-21T05:31:38.044258Z","steps":["trace[1454079047] 'process raft request' (duration: 247.162123ms)","trace[1454079047] 'compare' (duration: 68.326361ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T05:31:38.044449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:31:37.728598Z","time spent":"315.787423ms","remote":"127.0.0.1:44438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T05:31:38.827952Z","caller":"traceutil/trace.go:171","msg":"trace[1499297038] linearizableReadLoop","detail":"{readStateIndex:848; appliedIndex:847; }","duration":"124.43184ms","start":"2026-07-21T05:31:38.703491Z","end":"2026-07-21T05:31:38.827923Z","steps":["trace[1499297038] 'read index received' (duration: 124.192824ms)","trace[1499297038] 'applied index is now lower than readState.Index' (duration: 238.126µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:31:38.828049Z","caller":"traceutil/trace.go:171","msg":"trace[897811299] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"130.224186ms","start":"2026-07-21T05:31:38.6978Z","end":"2026-07-21T05:31:38.828025Z","steps":["trace[897811299] 'process raft request' (duration: 129.98151ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:31:38.828269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.75581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-642nj\" ","response":"range_response_count:1 size:4037"} {"level":"info","ts":"2026-07-21T05:31:38.828349Z","caller":"traceutil/trace.go:171","msg":"trace[1831393989] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-642nj; range_end:; response_count:1; response_revision:816; }","duration":"124.837323ms","start":"2026-07-21T05:31:38.703458Z","end":"2026-07-21T05:31:38.828295Z","steps":["trace[1831393989] 'agreement among raft nodes before linearized reading' (duration: 124.626017ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:31:41.911806Z","caller":"traceutil/trace.go:171","msg":"trace[928013971] transaction","detail":"{read_only:false; response_revision:831; number_of_response:1; }","duration":"127.19693ms","start":"2026-07-21T05:31:41.784588Z","end":"2026-07-21T05:31:41.911785Z","steps":["trace[928013971] 'process raft request' (duration: 127.051676ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:31:41.917503Z","caller":"traceutil/trace.go:171","msg":"trace[683820135] transaction","detail":"{read_only:false; response_revision:832; number_of_response:1; }","duration":"107.434942ms","start":"2026-07-21T05:31:41.810047Z","end":"2026-07-21T05:31:41.917482Z","steps":["trace[683820135] 'process raft request' (duration: 107.301809ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:32:13.969235Z","caller":"traceutil/trace.go:171","msg":"trace[865241334] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"244.616519ms","start":"2026-07-21T05:32:13.724598Z","end":"2026-07-21T05:32:13.969215Z","steps":["trace[865241334] 'process raft request' (duration: 244.464205ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:32:14.23433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.75229ms","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-07-21T05:32:14.234402Z","caller":"traceutil/trace.go:171","msg":"trace[626181857] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1037; }","duration":"116.864405ms","start":"2026-07-21T05:32:14.117522Z","end":"2026-07-21T05:32:14.234386Z","steps":["trace[626181857] 'range keys from in-memory index tree' (duration: 116.511083ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:32:14.450825Z","caller":"traceutil/trace.go:171","msg":"trace[1534371262] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"212.577791ms","start":"2026-07-21T05:32:14.238216Z","end":"2026-07-21T05:32:14.450793Z","steps":["trace[1534371262] 'process raft request' (duration: 212.380855ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:32:40.872908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.32439ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-21T05:32:40.873058Z","caller":"traceutil/trace.go:171","msg":"trace[1465811538] linearizableReadLoop","detail":"{readStateIndex:1171; appliedIndex:1170; }","duration":"279.518217ms","start":"2026-07-21T05:32:40.593483Z","end":"2026-07-21T05:32:40.873002Z","steps":["trace[1465811538] 'read index received' (duration: 7.020353ms)","trace[1465811538] 'applied index is now lower than readState.Index' (duration: 272.496404ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T05:32:40.873233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.736164ms","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-07-21T05:32:40.873312Z","caller":"traceutil/trace.go:171","msg":"trace[1083148642] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1124; }","duration":"279.860788ms","start":"2026-07-21T05:32:40.593435Z","end":"2026-07-21T05:32:40.873296Z","steps":["trace[1083148642] 'agreement among raft nodes before linearized reading' (duration: 279.662542ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:32:40.8734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.201971ms","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-07-21T05:32:40.873399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.344206ms","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-07-21T05:32:40.873427Z","caller":"traceutil/trace.go:171","msg":"trace[827426946] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1124; }","duration":"141.261453ms","start":"2026-07-21T05:32:40.732158Z","end":"2026-07-21T05:32:40.87342Z","steps":["trace[827426946] 'agreement among raft nodes before linearized reading' (duration: 141.18808ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:32:40.873462Z","caller":"traceutil/trace.go:171","msg":"trace[1329619031] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1124; }","duration":"131.437958ms","start":"2026-07-21T05:32:40.742008Z","end":"2026-07-21T05:32:40.873446Z","steps":["trace[1329619031] 'agreement among raft nodes before linearized reading' (duration: 131.286944ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:32:40.873491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.996972ms","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-07-21T05:32:40.873539Z","caller":"traceutil/trace.go:171","msg":"trace[403595763] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1124; }","duration":"162.096534ms","start":"2026-07-21T05:32:40.711429Z","end":"2026-07-21T05:32:40.873525Z","steps":["trace[403595763] 'agreement among raft nodes before linearized reading' (duration: 161.755154ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:32:58.683017Z","caller":"traceutil/trace.go:171","msg":"trace[146453785] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"446.944756ms","start":"2026-07-21T05:32:58.236046Z","end":"2026-07-21T05:32:58.682991Z","steps":["trace[146453785] 'process raft request' (duration: 446.73353ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:32:58.683221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:32:58.23597Z","time spent":"447.147592ms","remote":"127.0.0.1:44460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T05:32:59.205598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.6397ms","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-07-21T05:32:59.205693Z","caller":"traceutil/trace.go:171","msg":"trace[204063549] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1235; }","duration":"189.794215ms","start":"2026-07-21T05:32:59.01588Z","end":"2026-07-21T05:32:59.205674Z","steps":["trace[204063549] 'range keys from in-memory index tree' (duration: 189.391653ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:32:59.205826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.181037ms","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-07-21T05:32:59.205942Z","caller":"traceutil/trace.go:171","msg":"trace[1742444179] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1235; }","duration":"180.348802ms","start":"2026-07-21T05:32:59.025568Z","end":"2026-07-21T05:32:59.205917Z","steps":["trace[1742444179] 'range keys from in-memory index tree' (duration: 179.986921ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:33:16.289672Z","caller":"traceutil/trace.go:171","msg":"trace[1115599994] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"202.642956ms","start":"2026-07-21T05:33:16.087005Z","end":"2026-07-21T05:33:16.289648Z","steps":["trace[1115599994] 'process raft request' (duration: 202.4355ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:33:16.735702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.340934ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T05:33:16.735828Z","caller":"traceutil/trace.go:171","msg":"trace[629916852] linearizableReadLoop","detail":"{readStateIndex:1367; appliedIndex:1365; }","duration":"553.023901ms","start":"2026-07-21T05:33:16.182787Z","end":"2026-07-21T05:33:16.735811Z","steps":["trace[629916852] 'read index received' (duration: 106.723845ms)","trace[629916852] 'applied index is now lower than readState.Index' (duration: 446.298486ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T05:33:16.735911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.115084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T05:33:16.735955Z","caller":"traceutil/trace.go:171","msg":"trace[502768705] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1311; }","duration":"553.188996ms","start":"2026-07-21T05:33:16.182752Z","end":"2026-07-21T05:33:16.735941Z","steps":["trace[502768705] 'agreement among raft nodes before linearized reading' (duration: 553.106523ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:33:16.735997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:33:16.182723Z","time spent":"553.263858ms","remote":"127.0.0.1:44170","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-07-21T05:33:16.736207Z","caller":"traceutil/trace.go:171","msg":"trace[676616862] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"570.881568ms","start":"2026-07-21T05:33:16.165304Z","end":"2026-07-21T05:33:16.736186Z","steps":["trace[676616862] 'process raft request' (duration: 310.955006ms)","trace[676616862] 'compare' (duration: 259.18862ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T05:33:16.736297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:33:16.165288Z","time spent":"570.96035ms","remote":"127.0.0.1:44460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T05:33:16.829314Z","caller":"traceutil/trace.go:171","msg":"trace[1750509359] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"529.217954ms","start":"2026-07-21T05:33:16.300063Z","end":"2026-07-21T05:33:16.829281Z","steps":["trace[1750509359] 'process raft request' (duration: 528.914436ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:33:16.829499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:33:16.300042Z","time spent":"529.361098ms","remote":"127.0.0.1:44474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1508,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T05:33:16.830073Z","caller":"traceutil/trace.go:171","msg":"trace[510742104] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"529.911944ms","start":"2026-07-21T05:33:16.300141Z","end":"2026-07-21T05:33:16.830053Z","steps":["trace[510742104] 'process raft request' (duration: 529.059169ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:33:16.830179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:33:16.300119Z","time spent":"530.013096ms","remote":"127.0.0.1:44372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":915,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T05:33:16.83019Z","caller":"traceutil/trace.go:171","msg":"trace[373029824] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"525.485416ms","start":"2026-07-21T05:33:16.304689Z","end":"2026-07-21T05:33:16.830174Z","steps":["trace[373029824] 'process raft request' (duration: 525.210718ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:33:16.830272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:33:16.30466Z","time spent":"525.568357ms","remote":"127.0.0.1:44638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9375,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T05:33:17.03224Z","caller":"traceutil/trace.go:171","msg":"trace[127665403] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"274.782641ms","start":"2026-07-21T05:33:16.757437Z","end":"2026-07-21T05:33:17.03222Z","steps":["trace[127665403] 'process raft request' (duration: 274.639686ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:33:17.57973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.899832ms","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-07-21T05:33:17.579824Z","caller":"traceutil/trace.go:171","msg":"trace[1572811897] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1315; }","duration":"204.081957ms","start":"2026-07-21T05:33:17.375724Z","end":"2026-07-21T05:33:17.579806Z","steps":["trace[1572811897] 'range keys from in-memory index tree' (duration: 203.666745ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:33:17.579889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.036ms","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-07-21T05:33:17.579978Z","caller":"traceutil/trace.go:171","msg":"trace[47215144] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1315; }","duration":"237.184545ms","start":"2026-07-21T05:33:17.342774Z","end":"2026-07-21T05:33:17.579959Z","steps":["trace[47215144] 'range keys from in-memory index tree' (duration: 236.784082ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:33:17.580138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.171719ms","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-07-21T05:33:17.580181Z","caller":"traceutil/trace.go:171","msg":"trace[366576226] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1315; }","duration":"213.237312ms","start":"2026-07-21T05:33:17.366927Z","end":"2026-07-21T05:33:17.580164Z","steps":["trace[366576226] 'range keys from in-memory index tree' (duration: 213.075007ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:33:17.580373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.624326ms","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-07-21T05:33:17.58044Z","caller":"traceutil/trace.go:171","msg":"trace[1200101588] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1315; }","duration":"201.704249ms","start":"2026-07-21T05:33:17.37872Z","end":"2026-07-21T05:33:17.580424Z","steps":["trace[1200101588] 'range keys from in-memory index tree' (duration: 201.417691ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:33:17.580432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.586593ms","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-07-21T05:33:17.5805Z","caller":"traceutil/trace.go:171","msg":"trace[510700182] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1315; }","duration":"203.670676ms","start":"2026-07-21T05:33:17.376812Z","end":"2026-07-21T05:33:17.580482Z","steps":["trace[510700182] 'range keys from in-memory index tree' (duration: 203.423438ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:33:17.87899Z","caller":"traceutil/trace.go:171","msg":"trace[123606256] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"292.44185ms","start":"2026-07-21T05:33:17.586527Z","end":"2026-07-21T05:33:17.878969Z","steps":["trace[123606256] 'process raft request' (duration: 292.283876ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:33:18.39253Z","caller":"traceutil/trace.go:171","msg":"trace[1532195765] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"805.90309ms","start":"2026-07-21T05:33:17.586596Z","end":"2026-07-21T05:33:18.392499Z","steps":["trace[1532195765] 'process raft request' (duration: 774.738828ms)","trace[1532195765] 'compare' (duration: 30.614456ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T05:33:18.392671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:33:17.586562Z","time spent":"806.042074ms","remote":"127.0.0.1:44460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T05:33:18.392752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.532434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T05:33:18.392826Z","caller":"traceutil/trace.go:171","msg":"trace[775461538] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1320; }","duration":"210.655149ms","start":"2026-07-21T05:33:18.182155Z","end":"2026-07-21T05:33:18.39281Z","steps":["trace[775461538] 'agreement among raft nodes before linearized reading' (duration: 210.547206ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:33:18.392931Z","caller":"traceutil/trace.go:171","msg":"trace[1096960536] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"806.062974ms","start":"2026-07-21T05:33:17.586798Z","end":"2026-07-21T05:33:18.392861Z","steps":["trace[1096960536] 'process raft request' (duration: 805.691523ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:33:18.393009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:33:17.586762Z","time spent":"806.210038ms","remote":"127.0.0.1:44460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T05:33:18.393133Z","caller":"traceutil/trace.go:171","msg":"trace[124857240] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"806.339181ms","start":"2026-07-21T05:33:17.586781Z","end":"2026-07-21T05:33:18.39312Z","steps":["trace[124857240] 'process raft request' (duration: 805.631151ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:33:18.393144Z","caller":"traceutil/trace.go:171","msg":"trace[146337137] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"806.279649ms","start":"2026-07-21T05:33:17.586847Z","end":"2026-07-21T05:33:18.393127Z","steps":["trace[146337137] 'process raft request' (duration: 805.687082ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:33:18.393206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:33:17.586774Z","time spent":"806.396923ms","remote":"127.0.0.1:44460","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-07-21T05:33:18.392611Z","caller":"traceutil/trace.go:171","msg":"trace[1575724891] linearizableReadLoop","detail":"{readStateIndex:1376; appliedIndex:1372; }","duration":"210.376179ms","start":"2026-07-21T05:33:18.182208Z","end":"2026-07-21T05:33:18.392584Z","steps":["trace[1575724891] 'read index received' (duration: 179.034314ms)","trace[1575724891] 'applied index is now lower than readState.Index' (duration: 31.340625ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T05:33:18.39327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:33:17.586831Z","time spent":"806.362871ms","remote":"127.0.0.1:44460","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-07-21T05:33:19.21312Z","caller":"traceutil/trace.go:171","msg":"trace[2123268012] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"175.258784ms","start":"2026-07-21T05:33:19.037837Z","end":"2026-07-21T05:33:19.213096Z","steps":["trace[2123268012] 'process raft request' (duration: 174.550404ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:33:20.281109Z","caller":"traceutil/trace.go:171","msg":"trace[377431844] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"175.909093ms","start":"2026-07-21T05:33:20.10517Z","end":"2026-07-21T05:33:20.281079Z","steps":["trace[377431844] 'process raft request' (duration: 102.4409ms)","trace[377431844] 'compare' (duration: 73.204236ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:33:20.281367Z","caller":"traceutil/trace.go:171","msg":"trace[2108590153] transaction","detail":"{read_only:false; response_revision:1329; number_of_response:1; }","duration":"175.067889ms","start":"2026-07-21T05:33:20.106264Z","end":"2026-07-21T05:33:20.281331Z","steps":["trace[2108590153] 'process raft request' (duration: 174.773641ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:33:20.808216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.522244ms","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-07-21T05:33:20.808282Z","caller":"traceutil/trace.go:171","msg":"trace[1534941138] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1329; }","duration":"409.659179ms","start":"2026-07-21T05:33:20.398611Z","end":"2026-07-21T05:33:20.80827Z","steps":["trace[1534941138] 'range keys from in-memory index tree' (duration: 409.35463ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:33:20.808326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:33:20.398579Z","time spent":"409.724991ms","remote":"127.0.0.1:44460","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-07-21T05:33:20.808357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.630767ms","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-07-21T05:33:20.808438Z","caller":"traceutil/trace.go:171","msg":"trace[2117279021] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1329; }","duration":"409.797944ms","start":"2026-07-21T05:33:20.398621Z","end":"2026-07-21T05:33:20.808419Z","steps":["trace[2117279021] 'range keys from in-memory index tree' (duration: 409.447712ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:33:20.808489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:33:20.398596Z","time spent":"409.878315ms","remote":"127.0.0.1:44460","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-07-21T05:33:20.80852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.70672ms","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-07-21T05:33:20.808603Z","caller":"traceutil/trace.go:171","msg":"trace[637281687] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1329; }","duration":"409.853005ms","start":"2026-07-21T05:33:20.398732Z","end":"2026-07-21T05:33:20.808585Z","steps":["trace[637281687] 'range keys from in-memory index tree' (duration: 409.441923ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:33:20.80852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.667709ms","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-07-21T05:33:20.808665Z","caller":"traceutil/trace.go:171","msg":"trace[190975446] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1329; }","duration":"409.851925ms","start":"2026-07-21T05:33:20.398797Z","end":"2026-07-21T05:33:20.808649Z","steps":["trace[190975446] 'range keys from in-memory index tree' (duration: 409.441042ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:33:20.808692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:33:20.398789Z","time spent":"409.894496ms","remote":"127.0.0.1:44460","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-07-21T05:33:20.808736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:33:20.39871Z","time spent":"409.984718ms","remote":"127.0.0.1:44460","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":"warn","ts":"2026-07-21T05:33:20.953315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.005413ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-21T05:33:20.957874Z","caller":"traceutil/trace.go:171","msg":"trace[755214168] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"144.735893ms","start":"2026-07-21T05:33:20.813112Z","end":"2026-07-21T05:33:20.957848Z","steps":["trace[755214168] 'process raft request' (duration: 144.566499ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:33:20.984379Z","caller":"traceutil/trace.go:171","msg":"trace[219408379] transaction","detail":"{read_only:false; response_revision:1331; number_of_response:1; }","duration":"171.209048ms","start":"2026-07-21T05:33:20.813142Z","end":"2026-07-21T05:33:20.984351Z","steps":["trace[219408379] 'process raft request' (duration: 170.838958ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:33:20.984403Z","caller":"traceutil/trace.go:171","msg":"trace[581661890] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"170.862048ms","start":"2026-07-21T05:33:20.813437Z","end":"2026-07-21T05:33:20.984299Z","steps":["trace[581661890] 'process raft request' (duration: 170.778705ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:33:20.984909Z","caller":"traceutil/trace.go:171","msg":"trace[1443061713] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"170.318911ms","start":"2026-07-21T05:33:20.814549Z","end":"2026-07-21T05:33:20.984868Z","steps":["trace[1443061713] 'process raft request' (duration: 169.710683ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:33:33.025932Z","caller":"traceutil/trace.go:171","msg":"trace[1299950707] transaction","detail":"{read_only:false; response_revision:1410; number_of_response:1; }","duration":"392.947626ms","start":"2026-07-21T05:33:32.63295Z","end":"2026-07-21T05:33:33.025898Z","steps":["trace[1299950707] 'process raft request' (duration: 392.74065ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:33:33.026138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:33:32.632906Z","time spent":"393.142851ms","remote":"127.0.0.1:44460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T05:33:33.547387Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7660516876832356645,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T05:33:33.736382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.748028ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T05:33:33.736585Z","caller":"traceutil/trace.go:171","msg":"trace[2059568908] transaction","detail":"{read_only:false; response_revision:1411; number_of_response:1; }","duration":"700.007311ms","start":"2026-07-21T05:33:33.036554Z","end":"2026-07-21T05:33:33.736562Z","steps":["trace[2059568908] 'process raft request' (duration: 546.954146ms)","trace[2059568908] 'compare' (duration: 152.473878ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:33:33.736726Z","caller":"traceutil/trace.go:171","msg":"trace[2056209320] linearizableReadLoop","detail":"{readStateIndex:1471; appliedIndex:1469; }","duration":"689.822517ms","start":"2026-07-21T05:33:33.046887Z","end":"2026-07-21T05:33:33.736709Z","steps":["trace[2056209320] 'read index received' (duration: 536.637868ms)","trace[2056209320] 'applied index is now lower than readState.Index' (duration: 153.183469ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T05:33:33.736855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:33:33.03653Z","time spent":"700.095144ms","remote":"127.0.0.1:44460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T05:33:33.737036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"679.754916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-07-21T05:33:33.737053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.809762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-21T05:33:33.737075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.687252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-07-21T05:33:33.73709Z","caller":"traceutil/trace.go:171","msg":"trace[1158549238] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1412; }","duration":"679.849608ms","start":"2026-07-21T05:33:33.057223Z","end":"2026-07-21T05:33:33.737072Z","steps":["trace[1158549238] 'agreement among raft nodes before linearized reading' (duration: 679.669494ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:33:33.737118Z","caller":"traceutil/trace.go:171","msg":"trace[278258605] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1412; }","duration":"621.920915ms","start":"2026-07-21T05:33:33.115182Z","end":"2026-07-21T05:33:33.737103Z","steps":["trace[278258605] 'agreement among raft nodes before linearized reading' (duration: 621.76846ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:33:33.737133Z","caller":"traceutil/trace.go:171","msg":"trace[82119161] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1412; }","duration":"599.747734ms","start":"2026-07-21T05:33:33.137369Z","end":"2026-07-21T05:33:33.737117Z","steps":["trace[82119161] 'agreement among raft nodes before linearized reading' (duration: 599.572599ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:33:33.737135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:33:33.057203Z","time spent":"679.922591ms","remote":"127.0.0.1:44460","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-07-21T05:33:33.737173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:33:33.115142Z","time spent":"622.018738ms","remote":"127.0.0.1:44460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-21T05:33:33.737173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:33:33.1373Z","time spent":"599.863128ms","remote":"127.0.0.1:44460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-07-21T05:33:33.737279Z","caller":"traceutil/trace.go:171","msg":"trace[1968755562] transaction","detail":"{read_only:false; response_revision:1412; number_of_response:1; }","duration":"452.982613ms","start":"2026-07-21T05:33:33.284269Z","end":"2026-07-21T05:33:33.737251Z","steps":["trace[1968755562] 'process raft request' (duration: 452.235551ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:33:33.737465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:33:33.284247Z","time spent":"453.169569ms","remote":"127.0.0.1:44460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T05:33:33.73739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"690.426065ms","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-07-21T05:33:33.737626Z","caller":"traceutil/trace.go:171","msg":"trace[1442676980] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1412; }","duration":"690.749955ms","start":"2026-07-21T05:33:33.046846Z","end":"2026-07-21T05:33:33.737596Z","steps":["trace[1442676980] 'agreement among raft nodes before linearized reading' (duration: 690.001423ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:33:33.73796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:33:33.046827Z","time spent":"691.118456ms","remote":"127.0.0.1:44460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-21T05:35:14.598329Z","caller":"traceutil/trace.go:171","msg":"trace[157573232] linearizableReadLoop","detail":"{readStateIndex:1954; appliedIndex:1953; }","duration":"111.642389ms","start":"2026-07-21T05:35:14.486657Z","end":"2026-07-21T05:35:14.5983Z","steps":["trace[157573232] 'read index received' (duration: 87.693316ms)","trace[157573232] 'applied index is now lower than readState.Index' (duration: 23.947793ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:35:14.598393Z","caller":"traceutil/trace.go:171","msg":"trace[1154381623] transaction","detail":"{read_only:false; response_revision:1875; number_of_response:1; }","duration":"132.477601ms","start":"2026-07-21T05:35:14.465879Z","end":"2026-07-21T05:35:14.598356Z","steps":["trace[1154381623] 'process raft request' (duration: 108.561709ms)","trace[1154381623] 'compare' (duration: 23.589643ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T05:35:14.598544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.811733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T05:35:14.598732Z","caller":"traceutil/trace.go:171","msg":"trace[679436955] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1875; }","duration":"112.087422ms","start":"2026-07-21T05:35:14.486563Z","end":"2026-07-21T05:35:14.59865Z","steps":["trace[679436955] 'agreement among raft nodes before linearized reading' (duration: 111.849465ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:35:16.367326Z","caller":"traceutil/trace.go:171","msg":"trace[426256080] transaction","detail":"{read_only:false; response_revision:1882; number_of_response:1; }","duration":"101.00826ms","start":"2026-07-21T05:35:16.266286Z","end":"2026-07-21T05:35:16.367294Z","steps":["trace[426256080] 'process raft request' (duration: 100.868555ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:35:16.850924Z","caller":"traceutil/trace.go:171","msg":"trace[273857789] linearizableReadLoop","detail":"{readStateIndex:1967; appliedIndex:1965; }","duration":"167.465612ms","start":"2026-07-21T05:35:16.683426Z","end":"2026-07-21T05:35:16.850892Z","steps":["trace[273857789] 'read index received' (duration: 14.774337ms)","trace[273857789] 'applied index is now lower than readState.Index' (duration: 152.689915ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:35:16.851026Z","caller":"traceutil/trace.go:171","msg":"trace[1334619836] transaction","detail":"{read_only:false; response_revision:1887; number_of_response:1; }","duration":"172.529169ms","start":"2026-07-21T05:35:16.678458Z","end":"2026-07-21T05:35:16.850987Z","steps":["trace[1334619836] 'process raft request' (duration: 119.920747ms)","trace[1334619836] 'compare' (duration: 52.364765ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T05:35:16.851316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.703059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-21T05:35:16.851365Z","caller":"traceutil/trace.go:171","msg":"trace[847840578] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1887; }","duration":"167.947746ms","start":"2026-07-21T05:35:16.683403Z","end":"2026-07-21T05:35:16.851351Z","steps":["trace[847840578] 'agreement among raft nodes before linearized reading' (duration: 167.660379ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:35:18.730967Z","caller":"traceutil/trace.go:171","msg":"trace[1830996924] transaction","detail":"{read_only:false; response_revision:1892; number_of_response:1; }","duration":"162.55548ms","start":"2026-07-21T05:35:18.568377Z","end":"2026-07-21T05:35:18.730933Z","steps":["trace[1830996924] 'process raft request' (duration: 162.327923ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:35:18.741719Z","caller":"traceutil/trace.go:171","msg":"trace[1865692604] linearizableReadLoop","detail":"{readStateIndex:1973; appliedIndex:1971; }","duration":"123.293015ms","start":"2026-07-21T05:35:18.618399Z","end":"2026-07-21T05:35:18.741692Z","steps":["trace[1865692604] 'read index received' (duration: 111.763772ms)","trace[1865692604] 'applied index is now lower than readState.Index' (duration: 11.528273ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:35:18.741825Z","caller":"traceutil/trace.go:171","msg":"trace[1252954759] transaction","detail":"{read_only:false; response_revision:1893; number_of_response:1; }","duration":"173.204848ms","start":"2026-07-21T05:35:18.568574Z","end":"2026-07-21T05:35:18.741779Z","steps":["trace[1252954759] 'process raft request' (duration: 172.988431ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:35:18.741944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.531701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-21T05:35:18.741977Z","caller":"traceutil/trace.go:171","msg":"trace[69624306] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1893; }","duration":"123.638914ms","start":"2026-07-21T05:35:18.61833Z","end":"2026-07-21T05:35:18.741969Z","steps":["trace[69624306] 'agreement among raft nodes before linearized reading' (duration: 123.49468ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:35:18.99349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.786943ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T05:35:18.993877Z","caller":"traceutil/trace.go:171","msg":"trace[1428133644] linearizableReadLoop","detail":"{readStateIndex:1975; appliedIndex:1973; }","duration":"137.240068ms","start":"2026-07-21T05:35:18.856611Z","end":"2026-07-21T05:35:18.993851Z","steps":["trace[1428133644] 'read index received' (duration: 39.78µs)","trace[1428133644] 'applied index is now lower than readState.Index' (duration: 137.197708ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:35:18.994067Z","caller":"traceutil/trace.go:171","msg":"trace[646689591] transaction","detail":"{read_only:false; response_revision:1895; number_of_response:1; }","duration":"248.137465ms","start":"2026-07-21T05:35:18.745914Z","end":"2026-07-21T05:35:18.994051Z","steps":["trace[646689591] 'process raft request' (duration: 247.779585ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:35:18.994328Z","caller":"traceutil/trace.go:171","msg":"trace[326502830] transaction","detail":"{read_only:false; response_revision:1894; number_of_response:1; }","duration":"248.222918ms","start":"2026-07-21T05:35:18.745886Z","end":"2026-07-21T05:35:18.994108Z","steps":["trace[326502830] 'process raft request' (duration: 24.706684ms)","trace[326502830] 'compare' (duration: 222.637579ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T05:35:18.994513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.879527ms","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-07-21T05:35:18.994589Z","caller":"traceutil/trace.go:171","msg":"trace[980818585] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1895; }","duration":"138.01224ms","start":"2026-07-21T05:35:18.85656Z","end":"2026-07-21T05:35:18.994572Z","steps":["trace[980818585] 'agreement among raft nodes before linearized reading' (duration: 137.804824ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:35:19.258527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.723174ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T05:35:19.258894Z","caller":"traceutil/trace.go:171","msg":"trace[2122841161] transaction","detail":"{read_only:false; response_revision:1896; number_of_response:1; }","duration":"260.008297ms","start":"2026-07-21T05:35:18.998846Z","end":"2026-07-21T05:35:19.258855Z","steps":["trace[2122841161] 'process raft request' (duration: 123.83179ms)","trace[2122841161] 'compare' (duration: 135.244369ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:35:19.259175Z","caller":"traceutil/trace.go:171","msg":"trace[150228777] transaction","detail":"{read_only:false; response_revision:1897; number_of_response:1; }","duration":"259.728669ms","start":"2026-07-21T05:35:18.999406Z","end":"2026-07-21T05:35:19.259134Z","steps":["trace[150228777] 'process raft request' (duration: 259.356808ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:35:19.531142Z","caller":"traceutil/trace.go:171","msg":"trace[1400863738] transaction","detail":"{read_only:false; response_revision:1898; number_of_response:1; }","duration":"188.662564ms","start":"2026-07-21T05:35:19.342451Z","end":"2026-07-21T05:35:19.531114Z","steps":["trace[1400863738] 'process raft request' (duration: 188.51319ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:35:19.801847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.520701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-21T05:35:19.801998Z","caller":"traceutil/trace.go:171","msg":"trace[1795724601] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1898; }","duration":"113.674097ms","start":"2026-07-21T05:35:19.68824Z","end":"2026-07-21T05:35:19.801914Z","steps":["trace[1795724601] 'count revisions from in-memory index tree' (duration: 113.364666ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:35:20.184707Z","caller":"traceutil/trace.go:171","msg":"trace[118086319] transaction","detail":"{read_only:false; response_revision:1899; number_of_response:1; }","duration":"379.733929ms","start":"2026-07-21T05:35:19.804932Z","end":"2026-07-21T05:35:20.184666Z","steps":["trace[118086319] 'process raft request' (duration: 379.499522ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:35:20.184955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:35:19.804914Z","time spent":"379.909554ms","remote":"127.0.0.1:44460","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-07-21T05:35:20.986702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.884784ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-21T05:35:20.986866Z","caller":"traceutil/trace.go:171","msg":"trace[881006912] linearizableReadLoop","detail":"{readStateIndex:1981; appliedIndex:1980; }","duration":"250.203594ms","start":"2026-07-21T05:35:20.736616Z","end":"2026-07-21T05:35:20.98682Z","steps":["trace[881006912] 'read index received' (duration: 36.198167ms)","trace[881006912] 'applied index is now lower than readState.Index' (duration: 214.002077ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T05:35:20.987158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.727849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-21T05:35:20.987159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.373997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-07-21T05:35:20.987202Z","caller":"traceutil/trace.go:171","msg":"trace[482966976] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1900; }","duration":"240.817023ms","start":"2026-07-21T05:35:20.746372Z","end":"2026-07-21T05:35:20.987189Z","steps":["trace[482966976] 'agreement among raft nodes before linearized reading' (duration: 240.686619ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:35:20.987224Z","caller":"traceutil/trace.go:171","msg":"trace[1825663665] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1900; }","duration":"243.46873ms","start":"2026-07-21T05:35:20.743735Z","end":"2026-07-21T05:35:20.987204Z","steps":["trace[1825663665] 'agreement among raft nodes before linearized reading' (duration: 243.267104ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:35:20.987212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.555644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-21T05:35:20.987321Z","caller":"traceutil/trace.go:171","msg":"trace[1541861622] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1900; }","duration":"250.73919ms","start":"2026-07-21T05:35:20.736561Z","end":"2026-07-21T05:35:20.9873Z","steps":["trace[1541861622] 'agreement among raft nodes before linearized reading' (duration: 250.39122ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:35:21.350831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.506266ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T05:35:21.351016Z","caller":"traceutil/trace.go:171","msg":"trace[606974342] transaction","detail":"{read_only:false; response_revision:1902; number_of_response:1; }","duration":"359.220368ms","start":"2026-07-21T05:35:20.991772Z","end":"2026-07-21T05:35:21.350992Z","steps":["trace[606974342] 'process raft request' (duration: 111.462223ms)","trace[606974342] 'compare' (duration: 247.376953ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T05:35:21.351126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:35:20.991748Z","time spent":"359.33951ms","remote":"127.0.0.1:44460","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-07-21T05:35:21.351183Z","caller":"traceutil/trace.go:171","msg":"trace[517803742] linearizableReadLoop","detail":"{readStateIndex:1984; appliedIndex:1981; }","duration":"352.920785ms","start":"2026-07-21T05:35:20.998236Z","end":"2026-07-21T05:35:21.351157Z","steps":["trace[517803742] 'read index received' (duration: 14.84692ms)","trace[517803742] 'applied index is now lower than readState.Index' (duration: 338.070995ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:35:21.351283Z","caller":"traceutil/trace.go:171","msg":"trace[1424592715] transaction","detail":"{read_only:false; response_revision:1903; number_of_response:1; }","duration":"359.148535ms","start":"2026-07-21T05:35:20.992103Z","end":"2026-07-21T05:35:21.351251Z","steps":["trace[1424592715] 'process raft request' (duration: 358.828835ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:35:21.351426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.179773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-21T05:35:21.351445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:35:20.992095Z","time spent":"359.264008ms","remote":"127.0.0.1:44460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T05:35:21.351493Z","caller":"traceutil/trace.go:171","msg":"trace[1241491340] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1903; }","duration":"353.322696ms","start":"2026-07-21T05:35:20.998153Z","end":"2026-07-21T05:35:21.351476Z","steps":["trace[1241491340] 'agreement among raft nodes before linearized reading' (duration: 353.12315ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:35:21.35154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:35:20.998139Z","time spent":"353.391219ms","remote":"127.0.0.1:44460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-21T05:35:21.351431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.118211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"warn","ts":"2026-07-21T05:35:21.351575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.060537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T05:35:21.35167Z","caller":"traceutil/trace.go:171","msg":"trace[166715862] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1903; }","duration":"353.400799ms","start":"2026-07-21T05:35:20.99826Z","end":"2026-07-21T05:35:21.35166Z","steps":["trace[166715862] 'agreement among raft nodes before linearized reading' (duration: 353.049389ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:35:21.351702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T05:35:20.998244Z","time spent":"353.44898ms","remote":"127.0.0.1:44460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-07-21T05:35:21.351711Z","caller":"traceutil/trace.go:171","msg":"trace[1393290395] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1903; }","duration":"170.264133ms","start":"2026-07-21T05:35:21.181426Z","end":"2026-07-21T05:35:21.35169Z","steps":["trace[1393290395] 'agreement among raft nodes before linearized reading' (duration: 170.056607ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:35:23.481628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.291354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-21T05:35:23.481752Z","caller":"traceutil/trace.go:171","msg":"trace[2012983679] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1911; }","duration":"124.462699ms","start":"2026-07-21T05:35:23.357262Z","end":"2026-07-21T05:35:23.481724Z","steps":["trace[2012983679] 'range keys from in-memory index tree' (duration: 124.027307ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:35:23.481772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.76902ms","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-07-21T05:35:23.481861Z","caller":"traceutil/trace.go:171","msg":"trace[1136772649] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1911; }","duration":"113.915284ms","start":"2026-07-21T05:35:23.367923Z","end":"2026-07-21T05:35:23.481839Z","steps":["trace[1136772649] 'range keys from in-memory index tree' (duration: 113.521652ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:35:23.672671Z","caller":"traceutil/trace.go:171","msg":"trace[952541281] transaction","detail":"{read_only:false; response_revision:1914; number_of_response:1; }","duration":"185.261876ms","start":"2026-07-21T05:35:23.487374Z","end":"2026-07-21T05:35:23.672636Z","steps":["trace[952541281] 'process raft request' (duration: 185.129232ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:35:23.672672Z","caller":"traceutil/trace.go:171","msg":"trace[841612904] transaction","detail":"{read_only:false; response_revision:1913; number_of_response:1; }","duration":"185.984477ms","start":"2026-07-21T05:35:23.486655Z","end":"2026-07-21T05:35:23.672639Z","steps":["trace[841612904] 'process raft request' (duration: 161.085087ms)","trace[841612904] 'compare' (duration: 24.55921ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:35:23.673256Z","caller":"traceutil/trace.go:171","msg":"trace[359078664] transaction","detail":"{read_only:false; response_revision:1915; number_of_response:1; }","duration":"185.72353ms","start":"2026-07-21T05:35:23.487516Z","end":"2026-07-21T05:35:23.673239Z","steps":["trace[359078664] 'process raft request' (duration: 185.062911ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:35:23.673582Z","caller":"traceutil/trace.go:171","msg":"trace[1380919592] transaction","detail":"{read_only:false; response_revision:1917; number_of_response:1; }","duration":"151.536001ms","start":"2026-07-21T05:35:23.522033Z","end":"2026-07-21T05:35:23.673569Z","steps":["trace[1380919592] 'process raft request' (duration: 151.408368ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:35:23.673654Z","caller":"traceutil/trace.go:171","msg":"trace[241426367] transaction","detail":"{read_only:false; response_revision:1916; number_of_response:1; }","duration":"186.035709ms","start":"2026-07-21T05:35:23.48754Z","end":"2026-07-21T05:35:23.673576Z","steps":["trace[241426367] 'process raft request' (duration: 185.600736ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:35:23.674243Z","caller":"traceutil/trace.go:171","msg":"trace[1170553538] linearizableReadLoop","detail":"{readStateIndex:1998; appliedIndex:1992; }","duration":"131.483862ms","start":"2026-07-21T05:35:23.542701Z","end":"2026-07-21T05:35:23.674185Z","steps":["trace[1170553538] 'read index received' (duration: 3.242293ms)","trace[1170553538] 'applied index is now lower than readState.Index' (duration: 128.233918ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T05:35:23.674526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.793431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T05:35:23.67512Z","caller":"traceutil/trace.go:171","msg":"trace[2064972053] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1917; }","duration":"132.47441ms","start":"2026-07-21T05:35:23.542619Z","end":"2026-07-21T05:35:23.675094Z","steps":["trace[2064972053] 'agreement among raft nodes before linearized reading' (duration: 131.796511ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:35:25.65456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.052649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-21T05:35:25.654709Z","caller":"traceutil/trace.go:171","msg":"trace[1983205784] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1921; }","duration":"104.246703ms","start":"2026-07-21T05:35:25.550434Z","end":"2026-07-21T05:35:25.65468Z","steps":["trace[1983205784] 'range keys from in-memory index tree' (duration: 103.845863ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:35:26.527261Z","caller":"traceutil/trace.go:171","msg":"trace[169043731] transaction","detail":"{read_only:false; response_revision:1927; number_of_response:1; }","duration":"167.293428ms","start":"2026-07-21T05:35:26.359938Z","end":"2026-07-21T05:35:26.527232Z","steps":["trace[169043731] 'process raft request' (duration: 167.124532ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:35:27.848958Z","caller":"traceutil/trace.go:171","msg":"trace[12920240] transaction","detail":"{read_only:false; response_revision:1933; number_of_response:1; }","duration":"115.506639ms","start":"2026-07-21T05:35:27.733417Z","end":"2026-07-21T05:35:27.848923Z","steps":["trace[12920240] 'process raft request' (duration: 92.960078ms)","trace[12920240] 'compare' (duration: 22.295535ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:35:27.856504Z","caller":"traceutil/trace.go:171","msg":"trace[1630162224] transaction","detail":"{read_only:false; response_revision:1935; number_of_response:1; }","duration":"122.414969ms","start":"2026-07-21T05:35:27.734059Z","end":"2026-07-21T05:35:27.856474Z","steps":["trace[1630162224] 'process raft request' (duration: 122.364588ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:35:27.856574Z","caller":"traceutil/trace.go:171","msg":"trace[1256465997] transaction","detail":"{read_only:false; response_revision:1934; number_of_response:1; }","duration":"123.079079ms","start":"2026-07-21T05:35:27.733441Z","end":"2026-07-21T05:35:27.85652Z","steps":["trace[1256465997] 'process raft request' (duration: 122.809801ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:35:29.229577Z","caller":"traceutil/trace.go:171","msg":"trace[1918692017] transaction","detail":"{read_only:false; response_revision:1938; number_of_response:1; }","duration":"112.410471ms","start":"2026-07-21T05:35:29.117125Z","end":"2026-07-21T05:35:29.229535Z","steps":["trace[1918692017] 'process raft request' (duration: 112.141503ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:35:29.968927Z","caller":"traceutil/trace.go:171","msg":"trace[1050605168] linearizableReadLoop","detail":"{readStateIndex:2024; appliedIndex:2023; }","duration":"108.874758ms","start":"2026-07-21T05:35:29.86001Z","end":"2026-07-21T05:35:29.968884Z","steps":["trace[1050605168] 'read index received' (duration: 62.649662ms)","trace[1050605168] 'applied index is now lower than readState.Index' (duration: 46.223366ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T05:35:29.969322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.248699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-21T05:35:29.969479Z","caller":"traceutil/trace.go:171","msg":"trace[266294912] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1942; }","duration":"109.410414ms","start":"2026-07-21T05:35:29.860013Z","end":"2026-07-21T05:35:29.969423Z","steps":["trace[266294912] 'agreement among raft nodes before linearized reading' (duration: 109.152967ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:35:29.96934Z","caller":"traceutil/trace.go:171","msg":"trace[1652587970] transaction","detail":"{read_only:false; response_revision:1942; number_of_response:1; }","duration":"112.117502ms","start":"2026-07-21T05:35:29.857202Z","end":"2026-07-21T05:35:29.96932Z","steps":["trace[1652587970] 'process raft request' (duration: 65.592106ms)","trace[1652587970] 'compare' (duration: 45.919848ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T05:35:29.969716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.678291ms","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-07-21T05:35:29.969774Z","caller":"traceutil/trace.go:171","msg":"trace[2084028712] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1942; }","duration":"109.786664ms","start":"2026-07-21T05:35:29.859979Z","end":"2026-07-21T05:35:29.969766Z","steps":["trace[2084028712] 'agreement among raft nodes before linearized reading' (duration: 109.460974ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:35:30.142781Z","caller":"traceutil/trace.go:171","msg":"trace[115406880] transaction","detail":"{read_only:false; response_revision:1944; number_of_response:1; }","duration":"168.144602ms","start":"2026-07-21T05:35:29.974606Z","end":"2026-07-21T05:35:30.142751Z","steps":["trace[115406880] 'process raft request' (duration: 126.489568ms)","trace[115406880] 'compare' (duration: 41.52111ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:35:31.360725Z","caller":"traceutil/trace.go:171","msg":"trace[43652333] transaction","detail":"{read_only:false; response_revision:1948; number_of_response:1; }","duration":"121.960326ms","start":"2026-07-21T05:35:31.238733Z","end":"2026-07-21T05:35:31.360693Z","steps":["trace[43652333] 'process raft request' (duration: 61.141858ms)","trace[43652333] 'compare' (duration: 60.680764ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:35:31.951595Z","caller":"traceutil/trace.go:171","msg":"trace[1658970464] linearizableReadLoop","detail":"{readStateIndex:2033; appliedIndex:2032; }","duration":"116.276662ms","start":"2026-07-21T05:35:31.835286Z","end":"2026-07-21T05:35:31.951563Z","steps":["trace[1658970464] 'read index received' (duration: 115.997114ms)","trace[1658970464] 'applied index is now lower than readState.Index' (duration: 277.918µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:35:31.951655Z","caller":"traceutil/trace.go:171","msg":"trace[1020278851] transaction","detail":"{read_only:false; response_revision:1950; number_of_response:1; }","duration":"170.238292ms","start":"2026-07-21T05:35:31.781348Z","end":"2026-07-21T05:35:31.951586Z","steps":["trace[1020278851] 'process raft request' (duration: 169.981615ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:35:31.95188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.570402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-21T05:35:31.951955Z","caller":"traceutil/trace.go:171","msg":"trace[5277601] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1950; }","duration":"116.704864ms","start":"2026-07-21T05:35:31.835235Z","end":"2026-07-21T05:35:31.951939Z","steps":["trace[5277601] 'agreement among raft nodes before linearized reading' (duration: 116.460927ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:35:32.774866Z","caller":"traceutil/trace.go:171","msg":"trace[869495632] transaction","detail":"{read_only:false; response_revision:1956; number_of_response:1; }","duration":"123.915643ms","start":"2026-07-21T05:35:32.650915Z","end":"2026-07-21T05:35:32.77483Z","steps":["trace[869495632] 'process raft request' (duration: 123.722837ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T05:35:44.39114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.670836ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T05:35:44.39126Z","caller":"traceutil/trace.go:171","msg":"trace[1910027519] linearizableReadLoop","detail":"{readStateIndex:2093; appliedIndex:2091; }","duration":"157.526347ms","start":"2026-07-21T05:35:44.233716Z","end":"2026-07-21T05:35:44.391242Z","steps":["trace[1910027519] 'read index received' (duration: 31.272268ms)","trace[1910027519] 'applied index is now lower than readState.Index' (duration: 126.252829ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T05:35:44.39148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.775735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-07-21T05:35:44.391519Z","caller":"traceutil/trace.go:171","msg":"trace[567836156] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2008; }","duration":"157.872537ms","start":"2026-07-21T05:35:44.233637Z","end":"2026-07-21T05:35:44.39151Z","steps":["trace[567836156] 'agreement among raft nodes before linearized reading' (duration: 157.688562ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T05:35:44.391309Z","caller":"traceutil/trace.go:171","msg":"trace[417266460] transaction","detail":"{read_only:false; response_revision:2008; number_of_response:1; }","duration":"167.310919ms","start":"2026-07-21T05:35:44.223974Z","end":"2026-07-21T05:35:44.391285Z","steps":["trace[417266460] 'process raft request' (duration: 60.411716ms)","trace[417266460] 'compare' (duration: 106.532623ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T05:35:50.497574Z","caller":"traceutil/trace.go:171","msg":"trace[405555710] transaction","detail":"{read_only:false; response_revision:2035; number_of_response:1; }","duration":"117.707693ms","start":"2026-07-21T05:35:50.379825Z","end":"2026-07-21T05:35:50.497533Z","steps":["trace[405555710] 'process raft request' (duration: 113.679668ms)"],"step_count":1}