{"level":"warn","ts":"2026-07-02T23:03:21.104335Z","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-02T23:03:21.10444Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.129: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.204.45.129:2380","--initial-cluster=instance=https://199.204.45.129:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.129:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.129: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-02T23:03:21.104535Z","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-02T23:03:21.104553Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.129:2380"]} {"level":"info","ts":"2026-07-02T23:03:21.104578Z","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-02T23:03:21.105496Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"]} {"level":"info","ts":"2026-07-02T23:03:21.105606Z","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.204.45.129:2380"],"listen-peer-urls":["https://199.204.45.129:2380"],"advertise-client-urls":["https://199.204.45.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.129: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-02T23:03:21.111375Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.446538ms"} {"level":"info","ts":"2026-07-02T23:03:21.122562Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3cec112083e8d3ff","cluster-id":"3f68255a3b440659"} {"level":"info","ts":"2026-07-02T23:03:21.122662Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=()"} {"level":"info","ts":"2026-07-02T23:03:21.122685Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became follower at term 0"} {"level":"info","ts":"2026-07-02T23:03:21.122703Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3cec112083e8d3ff [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-02T23:03:21.122715Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became follower at term 1"} {"level":"info","ts":"2026-07-02T23:03:21.122754Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=(4389902568129090559)"} {"level":"warn","ts":"2026-07-02T23:03:21.131651Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-02T23:03:21.13433Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-02T23:03:21.137108Z","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-02T23:03:21.143089Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3cec112083e8d3ff","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-02T23:03:21.14445Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3cec112083e8d3ff","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-02T23:03:21.144574Z","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-02T23:03:21.144607Z","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-02T23:03:21.144617Z","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-02T23:03:21.144937Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=(4389902568129090559)"} {"level":"info","ts":"2026-07-02T23:03:21.145033Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3f68255a3b440659","local-member-id":"3cec112083e8d3ff","added-peer-id":"3cec112083e8d3ff","added-peer-peer-urls":["https://199.204.45.129:2380"]} {"level":"info","ts":"2026-07-02T23:03:21.153488Z","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-02T23:03:21.153585Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.129:2380"} {"level":"info","ts":"2026-07-02T23:03:21.153635Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.129:2380"} {"level":"info","ts":"2026-07-02T23:03:21.153917Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3cec112083e8d3ff","initial-advertise-peer-urls":["https://199.204.45.129:2380"],"listen-peer-urls":["https://199.204.45.129:2380"],"advertise-client-urls":["https://199.204.45.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-02T23:03:21.153963Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-02T23:03:22.123516Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff is starting a new election at term 1"} {"level":"info","ts":"2026-07-02T23:03:22.123602Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became pre-candidate at term 1"} {"level":"info","ts":"2026-07-02T23:03:22.123626Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff received MsgPreVoteResp from 3cec112083e8d3ff at term 1"} {"level":"info","ts":"2026-07-02T23:03:22.123646Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became candidate at term 2"} {"level":"info","ts":"2026-07-02T23:03:22.123657Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff received MsgVoteResp from 3cec112083e8d3ff at term 2"} {"level":"info","ts":"2026-07-02T23:03:22.123673Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became leader at term 2"} {"level":"info","ts":"2026-07-02T23:03:22.12369Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3cec112083e8d3ff elected leader 3cec112083e8d3ff at term 2"} {"level":"info","ts":"2026-07-02T23:03:22.124628Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3cec112083e8d3ff","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.129:2379]}","request-path":"/0/members/3cec112083e8d3ff/attributes","cluster-id":"3f68255a3b440659","publish-timeout":"7s"} {"level":"info","ts":"2026-07-02T23:03:22.124663Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-02T23:03:22.124714Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T23:03:22.124827Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-02T23:03:22.125008Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-02T23:03:22.125108Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-02T23:03:22.125828Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3f68255a3b440659","local-member-id":"3cec112083e8d3ff","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T23:03:22.12599Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T23:03:22.126037Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T23:03:22.129124Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-02T23:03:22.129575Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.129:2379"} {"level":"info","ts":"2026-07-02T23:03:30.220189Z","caller":"traceutil/trace.go:171","msg":"trace[806097754] linearizableReadLoop","detail":"{readStateIndex:347; appliedIndex:346; }","duration":"129.327154ms","start":"2026-07-02T23:03:30.090833Z","end":"2026-07-02T23:03:30.22016Z","steps":["trace[806097754] 'read index received' (duration: 129.195748ms)","trace[806097754] 'applied index is now lower than readState.Index' (duration: 130.186µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T23:03:30.220304Z","caller":"traceutil/trace.go:171","msg":"trace[1040234662] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"162.309029ms","start":"2026-07-02T23:03:30.05796Z","end":"2026-07-02T23:03:30.220269Z","steps":["trace[1040234662] 'process raft request' (duration: 162.004675ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T23:03:30.220392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.526923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T23:03:30.220549Z","caller":"traceutil/trace.go:171","msg":"trace[735429918] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:0; response_revision:335; }","duration":"129.740223ms","start":"2026-07-02T23:03:30.090773Z","end":"2026-07-02T23:03:30.220513Z","steps":["trace[735429918] 'agreement among raft nodes before linearized reading' (duration: 129.515543ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T23:03:30.616565Z","caller":"traceutil/trace.go:171","msg":"trace[1777091804] transaction","detail":"{read_only:false; response_revision:339; number_of_response:1; }","duration":"100.258623ms","start":"2026-07-02T23:03:30.516262Z","end":"2026-07-02T23:03:30.616521Z","steps":["trace[1777091804] 'process raft request' (duration: 93.113706ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T23:03:31.461653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.758381ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-02T23:03:31.461788Z","caller":"traceutil/trace.go:171","msg":"trace[1256469455] linearizableReadLoop","detail":"{readStateIndex:358; appliedIndex:357; }","duration":"420.02066ms","start":"2026-07-02T23:03:31.041746Z","end":"2026-07-02T23:03:31.461766Z","steps":["trace[1256469455] 'read index received' (duration: 271.271916ms)","trace[1256469455] 'applied index is now lower than readState.Index' (duration: 148.747124ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T23:03:31.461833Z","caller":"traceutil/trace.go:171","msg":"trace[144515841] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"420.574514ms","start":"2026-07-02T23:03:31.041203Z","end":"2026-07-02T23:03:31.461777Z","steps":["trace[144515841] 'process raft request' (duration: 271.835411ms)","trace[144515841] 'compare' (duration: 147.601523ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T23:03:31.462038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.264351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-07-02T23:03:31.462073Z","caller":"traceutil/trace.go:171","msg":"trace[296323671] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:346; }","duration":"420.362045ms","start":"2026-07-02T23:03:31.041702Z","end":"2026-07-02T23:03:31.462064Z","steps":["trace[296323671] 'agreement among raft nodes before linearized reading' (duration: 420.211578ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T23:03:31.462119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T23:03:31.04169Z","time spent":"420.421508ms","remote":"127.0.0.1:56332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":229,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"warn","ts":"2026-07-02T23:03:31.462145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T23:03:31.041178Z","time spent":"420.875298ms","remote":"127.0.0.1:56312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3378,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-02T23:03:31.462258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.37481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-07-02T23:03:31.462312Z","caller":"traceutil/trace.go:171","msg":"trace[251147114] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:346; }","duration":"370.479244ms","start":"2026-07-02T23:03:31.091818Z","end":"2026-07-02T23:03:31.462297Z","steps":["trace[251147114] 'agreement among raft nodes before linearized reading' (duration: 370.352269ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T23:03:31.462356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T23:03:31.091794Z","time spent":"370.551197ms","remote":"127.0.0.1:56332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":1,"response size":240,"request content":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" "} {"level":"warn","ts":"2026-07-02T23:03:31.462399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.686477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-07-02T23:03:31.462423Z","caller":"traceutil/trace.go:171","msg":"trace[1639985170] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:346; }","duration":"170.74888ms","start":"2026-07-02T23:03:31.291666Z","end":"2026-07-02T23:03:31.462415Z","steps":["trace[1639985170] 'agreement among raft nodes before linearized reading' (duration: 170.706799ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T23:03:31.462592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.592596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-07-02T23:03:31.462651Z","caller":"traceutil/trace.go:171","msg":"trace[107222346] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:346; }","duration":"270.692601ms","start":"2026-07-02T23:03:31.19194Z","end":"2026-07-02T23:03:31.462632Z","steps":["trace[107222346] 'agreement among raft nodes before linearized reading' (duration: 270.572146ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T23:03:31.462665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.286579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-07-02T23:03:31.462708Z","caller":"traceutil/trace.go:171","msg":"trace[2082404768] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:346; }","duration":"320.367763ms","start":"2026-07-02T23:03:31.142328Z","end":"2026-07-02T23:03:31.462696Z","steps":["trace[2082404768] 'agreement among raft nodes before linearized reading' (duration: 320.292089ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T23:03:31.462747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T23:03:31.142239Z","time spent":"320.498909ms","remote":"127.0.0.1:56332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":219,"request content":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" "} {"level":"warn","ts":"2026-07-02T23:03:31.462781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.650097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-07-02T23:03:31.46282Z","caller":"traceutil/trace.go:171","msg":"trace[279183863] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:346; }","duration":"221.72606ms","start":"2026-07-02T23:03:31.241081Z","end":"2026-07-02T23:03:31.462807Z","steps":["trace[279183863] 'agreement among raft nodes before linearized reading' (duration: 221.655837ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T23:03:31.708493Z","caller":"traceutil/trace.go:171","msg":"trace[510470524] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"238.371471ms","start":"2026-07-02T23:03:31.470018Z","end":"2026-07-02T23:03:31.70839Z","steps":["trace[510470524] 'process raft request' (duration: 238.058958ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T23:03:31.708689Z","caller":"traceutil/trace.go:171","msg":"trace[1232753412] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"238.465856ms","start":"2026-07-02T23:03:31.470203Z","end":"2026-07-02T23:03:31.708668Z","steps":["trace[1232753412] 'process raft request' (duration: 238.190624ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T23:03:31.708687Z","caller":"traceutil/trace.go:171","msg":"trace[652596458] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"238.543808ms","start":"2026-07-02T23:03:31.470092Z","end":"2026-07-02T23:03:31.708636Z","steps":["trace[652596458] 'process raft request' (duration: 238.166042ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T23:03:31.708575Z","caller":"traceutil/trace.go:171","msg":"trace[508462719] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"238.331829ms","start":"2026-07-02T23:03:31.470191Z","end":"2026-07-02T23:03:31.708522Z","steps":["trace[508462719] 'process raft request' (duration: 238.12927ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T23:03:31.709128Z","caller":"traceutil/trace.go:171","msg":"trace[800450441] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"237.618578ms","start":"2026-07-02T23:03:31.471482Z","end":"2026-07-02T23:03:31.709101Z","steps":["trace[800450441] 'process raft request' (duration: 237.062193ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T23:03:31.709667Z","caller":"traceutil/trace.go:171","msg":"trace[1836847390] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"233.352911ms","start":"2026-07-02T23:03:31.476297Z","end":"2026-07-02T23:03:31.70965Z","steps":["trace[1836847390] 'process raft request' (duration: 233.140531ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T23:03:31.709268Z","caller":"traceutil/trace.go:171","msg":"trace[1162109274] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"238.040396ms","start":"2026-07-02T23:03:31.471113Z","end":"2026-07-02T23:03:31.709153Z","steps":["trace[1162109274] 'process raft request' (duration: 237.330675ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T23:03:31.709335Z","caller":"traceutil/trace.go:171","msg":"trace[1508138057] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"237.726312ms","start":"2026-07-02T23:03:31.471595Z","end":"2026-07-02T23:03:31.709322Z","steps":["trace[1508138057] 'process raft request' (duration: 237.014551ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T23:03:31.709322Z","caller":"traceutil/trace.go:171","msg":"trace[1165982332] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"238.027745ms","start":"2026-07-02T23:03:31.471273Z","end":"2026-07-02T23:03:31.7093Z","steps":["trace[1165982332] 'process raft request' (duration: 237.23108ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T23:03:31.709492Z","caller":"traceutil/trace.go:171","msg":"trace[1139439221] linearizableReadLoop","detail":"{readStateIndex:370; appliedIndex:358; }","duration":"228.11256ms","start":"2026-07-02T23:03:31.481363Z","end":"2026-07-02T23:03:31.709476Z","steps":["trace[1139439221] 'read index received' (duration: 26.316176ms)","trace[1139439221] 'applied index is now lower than readState.Index' (duration: 201.795464ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T23:03:31.709526Z","caller":"traceutil/trace.go:171","msg":"trace[1526412509] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"235.584018ms","start":"2026-07-02T23:03:31.473925Z","end":"2026-07-02T23:03:31.709509Z","steps":["trace[1526412509] 'process raft request' (duration: 235.024714ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T23:03:31.709583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.194733ms","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-02T23:03:31.711191Z","caller":"traceutil/trace.go:171","msg":"trace[1942529226] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:358; }","duration":"229.834146ms","start":"2026-07-02T23:03:31.481336Z","end":"2026-07-02T23:03:31.71117Z","steps":["trace[1942529226] 'agreement among raft nodes before linearized reading' (duration: 228.187113ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T23:03:31.709662Z","caller":"traceutil/trace.go:171","msg":"trace[262711192] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"235.363609ms","start":"2026-07-02T23:03:31.474286Z","end":"2026-07-02T23:03:31.709649Z","steps":["trace[262711192] 'process raft request' (duration: 235.099837ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T23:03:31.85539Z","caller":"traceutil/trace.go:171","msg":"trace[873491447] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"140.537424ms","start":"2026-07-02T23:03:31.714827Z","end":"2026-07-02T23:03:31.855364Z","steps":["trace[873491447] 'process raft request' (duration: 60.919856ms)","trace[873491447] 'compare' (duration: 79.494852ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T23:03:31.861886Z","caller":"traceutil/trace.go:171","msg":"trace[395637693] linearizableReadLoop","detail":"{readStateIndex:372; appliedIndex:370; }","duration":"146.296647ms","start":"2026-07-02T23:03:31.715577Z","end":"2026-07-02T23:03:31.861874Z","steps":["trace[395637693] 'read index received' (duration: 60.256217ms)","trace[395637693] 'applied index is now lower than readState.Index' (duration: 86.03974ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T23:03:31.861884Z","caller":"traceutil/trace.go:171","msg":"trace[1619326532] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"144.051928ms","start":"2026-07-02T23:03:31.717803Z","end":"2026-07-02T23:03:31.861855Z","steps":["trace[1619326532] 'process raft request' (duration: 143.885951ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T23:03:31.862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.402191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-07-02T23:03:31.862033Z","caller":"traceutil/trace.go:171","msg":"trace[874981550] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:366; }","duration":"146.481764ms","start":"2026-07-02T23:03:31.715542Z","end":"2026-07-02T23:03:31.862024Z","steps":["trace[874981550] 'agreement among raft nodes before linearized reading' (duration: 146.401891ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T23:03:31.861901Z","caller":"traceutil/trace.go:171","msg":"trace[2061911867] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"145.166107ms","start":"2026-07-02T23:03:31.716697Z","end":"2026-07-02T23:03:31.861863Z","steps":["trace[2061911867] 'process raft request' (duration: 144.951188ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T23:03:31.862161Z","caller":"traceutil/trace.go:171","msg":"trace[770564015] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"146.877952ms","start":"2026-07-02T23:03:31.715232Z","end":"2026-07-02T23:03:31.86211Z","steps":["trace[770564015] 'process raft request' (duration: 146.305737ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T23:03:31.862223Z","caller":"traceutil/trace.go:171","msg":"trace[958950605] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"138.669602ms","start":"2026-07-02T23:03:31.723543Z","end":"2026-07-02T23:03:31.862212Z","steps":["trace[958950605] 'process raft request' (duration: 138.222512ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T23:03:31.862235Z","caller":"traceutil/trace.go:171","msg":"trace[1125173190] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"141.919834ms","start":"2026-07-02T23:03:31.720283Z","end":"2026-07-02T23:03:31.862203Z","steps":["trace[1125173190] 'process raft request' (duration: 141.436513ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T23:03:31.862008Z","caller":"traceutil/trace.go:171","msg":"trace[523633999] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"145.548244ms","start":"2026-07-02T23:03:31.716419Z","end":"2026-07-02T23:03:31.861967Z","steps":["trace[523633999] 'process raft request' (duration: 145.204239ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T23:03:31.862293Z","caller":"traceutil/trace.go:171","msg":"trace[1191946040] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"141.109979ms","start":"2026-07-02T23:03:31.721169Z","end":"2026-07-02T23:03:31.862279Z","steps":["trace[1191946040] 'process raft request' (duration: 140.575975ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T23:03:32.198569Z","caller":"traceutil/trace.go:171","msg":"trace[2066432236] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"130.49873ms","start":"2026-07-02T23:03:32.068042Z","end":"2026-07-02T23:03:32.19854Z","steps":["trace[2066432236] 'process raft request' (duration: 123.989117ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T23:03:32.852021Z","caller":"traceutil/trace.go:171","msg":"trace[767291629] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"344.077479ms","start":"2026-07-02T23:03:32.507921Z","end":"2026-07-02T23:03:32.851999Z","steps":["trace[767291629] 'process raft request' (duration: 343.908042ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T23:03:32.85238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T23:03:32.507887Z","time spent":"344.204485ms","remote":"127.0.0.1:56312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7288,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T23:03:32.978941Z","caller":"traceutil/trace.go:171","msg":"trace[1944408575] linearizableReadLoop","detail":"{readStateIndex:397; appliedIndex:396; }","duration":"122.876479ms","start":"2026-07-02T23:03:32.856039Z","end":"2026-07-02T23:03:32.978915Z","steps":["trace[1944408575] 'read index received' (duration: 65.903374ms)","trace[1944408575] 'applied index is now lower than readState.Index' (duration: 56.971515ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T23:03:32.978949Z","caller":"traceutil/trace.go:171","msg":"trace[1936229104] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"137.153619ms","start":"2026-07-02T23:03:32.84177Z","end":"2026-07-02T23:03:32.978923Z","steps":["trace[1936229104] 'process raft request' (duration: 80.234507ms)","trace[1936229104] 'compare' (duration: 56.663951ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T23:03:32.979092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.039216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7303"} {"level":"info","ts":"2026-07-02T23:03:32.979127Z","caller":"traceutil/trace.go:171","msg":"trace[2108626733] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:385; }","duration":"123.111499ms","start":"2026-07-02T23:03:32.856003Z","end":"2026-07-02T23:03:32.979114Z","steps":["trace[2108626733] 'agreement among raft nodes before linearized reading' (duration: 123.000554ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T23:03:34.004047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.763741ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T23:03:34.004152Z","caller":"traceutil/trace.go:171","msg":"trace[683595333] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"130.681156ms","start":"2026-07-02T23:03:33.873455Z","end":"2026-07-02T23:03:34.004136Z","steps":["trace[683595333] 'compare' (duration: 127.614545ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T23:03:38.587542Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276103442637989261,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-02T23:03:38.939703Z","caller":"traceutil/trace.go:171","msg":"trace[748417156] linearizableReadLoop","detail":"{readStateIndex:420; appliedIndex:419; }","duration":"852.959101ms","start":"2026-07-02T23:03:38.086713Z","end":"2026-07-02T23:03:38.939672Z","steps":["trace[748417156] 'read index received' (duration: 852.073885ms)","trace[748417156] 'applied index is now lower than readState.Index' (duration: 883.766µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T23:03:38.939845Z","caller":"traceutil/trace.go:171","msg":"trace[2047510381] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"891.619505ms","start":"2026-07-02T23:03:38.048189Z","end":"2026-07-02T23:03:38.939808Z","steps":["trace[2047510381] 'process raft request' (duration: 891.286731ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T23:03:38.940006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.097811ms","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-02T23:03:38.940192Z","caller":"traceutil/trace.go:171","msg":"trace[959011639] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:406; }","duration":"595.338751ms","start":"2026-07-02T23:03:38.344838Z","end":"2026-07-02T23:03:38.940177Z","steps":["trace[959011639] 'agreement among raft nodes before linearized reading' (duration: 595.054259ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T23:03:38.940216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T23:03:38.048177Z","time spent":"891.899987ms","remote":"127.0.0.1:56382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T23:03:38.940234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T23:03:38.344821Z","time spent":"595.406444ms","remote":"127.0.0.1:56382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-02T23:03:38.940129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"853.412729ms","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-02T23:03:38.940623Z","caller":"traceutil/trace.go:171","msg":"trace[1721599012] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:406; }","duration":"853.911559ms","start":"2026-07-02T23:03:38.086674Z","end":"2026-07-02T23:03:38.940585Z","steps":["trace[1721599012] 'agreement among raft nodes before linearized reading' (duration: 853.419159ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T23:03:38.940663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T23:03:38.086658Z","time spent":"853.996463ms","remote":"127.0.0.1:56382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-02T23:03:40.074259Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276103442637989264,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T23:03:40.282343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"828.935129ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T23:03:40.282577Z","caller":"traceutil/trace.go:171","msg":"trace[573622822] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"1.337063486s","start":"2026-07-02T23:03:38.945485Z","end":"2026-07-02T23:03:40.282549Z","steps":["trace[573622822] 'process raft request' (duration: 507.831185ms)","trace[573622822] 'compare' (duration: 828.806274ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T23:03:40.282599Z","caller":"traceutil/trace.go:171","msg":"trace[301708163] linearizableReadLoop","detail":"{readStateIndex:422; appliedIndex:420; }","duration":"708.663313ms","start":"2026-07-02T23:03:39.573915Z","end":"2026-07-02T23:03:40.282578Z","steps":["trace[301708163] 'read index received' (duration: 573.156131ms)","trace[301708163] 'applied index is now lower than readState.Index' (duration: 135.506022ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T23:03:40.282681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T23:03:38.945462Z","time spent":"1.33717416s","remote":"127.0.0.1:56382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T23:03:40.282688Z","caller":"traceutil/trace.go:171","msg":"trace[876843131] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"1.335179809s","start":"2026-07-02T23:03:38.947479Z","end":"2026-07-02T23:03:40.282659Z","steps":["trace[876843131] 'process raft request' (duration: 1.334996452s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T23:03:40.282731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.816979ms","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-02T23:03:40.28277Z","caller":"traceutil/trace.go:171","msg":"trace[1715912432] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:408; }","duration":"708.897583ms","start":"2026-07-02T23:03:39.573861Z","end":"2026-07-02T23:03:40.282759Z","steps":["trace[1715912432] 'agreement among raft nodes before linearized reading' (duration: 708.805999ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T23:03:40.282813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T23:03:39.573837Z","time spent":"708.968735ms","remote":"127.0.0.1:56382","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-02T23:03:40.282845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T23:03:38.947451Z","time spent":"1.335311994s","remote":"127.0.0.1:56382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T23:03:40.344221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.163353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T23:03:40.344319Z","caller":"traceutil/trace.go:171","msg":"trace[1699518324] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:409; }","duration":"323.317399ms","start":"2026-07-02T23:03:40.020984Z","end":"2026-07-02T23:03:40.344301Z","steps":["trace[1699518324] 'agreement among raft nodes before linearized reading' (duration: 323.180933ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T23:03:40.344348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.102668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6965"} {"level":"info","ts":"2026-07-02T23:03:40.344476Z","caller":"traceutil/trace.go:171","msg":"trace[1465570977] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:409; }","duration":"440.276004ms","start":"2026-07-02T23:03:39.904174Z","end":"2026-07-02T23:03:40.34445Z","steps":["trace[1465570977] 'agreement among raft nodes before linearized reading' (duration: 440.093207ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T23:03:40.344554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T23:03:39.904152Z","time spent":"440.384499ms","remote":"127.0.0.1:56312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6987,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-07-02T23:03:40.344189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.009779ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T23:03:40.344765Z","caller":"traceutil/trace.go:171","msg":"trace[212570267] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:409; }","duration":"667.605393ms","start":"2026-07-02T23:03:39.677147Z","end":"2026-07-02T23:03:40.344753Z","steps":["trace[212570267] 'agreement among raft nodes before linearized reading' (duration: 666.943726ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T23:03:40.344408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T23:03:40.020953Z","time spent":"323.446353ms","remote":"127.0.0.1:56126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-02T23:04:02.559498Z","caller":"traceutil/trace.go:171","msg":"trace[1011949388] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"112.674381ms","start":"2026-07-02T23:04:02.446802Z","end":"2026-07-02T23:04:02.559476Z","steps":["trace[1011949388] 'process raft request' (duration: 111.298447ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T23:04:03.421925Z","caller":"traceutil/trace.go:171","msg":"trace[863386330] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"515.676484ms","start":"2026-07-02T23:04:02.906224Z","end":"2026-07-02T23:04:03.4219Z","steps":["trace[863386330] 'process raft request' (duration: 515.522669ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T23:04:03.422198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T23:04:02.906201Z","time spent":"515.88189ms","remote":"127.0.0.1:56160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T23:04:03.429197Z","caller":"traceutil/trace.go:171","msg":"trace[771844849] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"512.061985ms","start":"2026-07-02T23:04:02.917124Z","end":"2026-07-02T23:04:03.429186Z","steps":["trace[771844849] 'process raft request' (duration: 511.479417ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T23:04:03.429273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T23:04:02.917121Z","time spent":"512.107797ms","remote":"127.0.0.1:56160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T23:04:03.429353Z","caller":"traceutil/trace.go:171","msg":"trace[1375425203] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"519.279967ms","start":"2026-07-02T23:04:02.910065Z","end":"2026-07-02T23:04:03.429345Z","steps":["trace[1375425203] 'process raft request' (duration: 518.42789ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T23:04:03.429385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T23:04:02.91005Z","time spent":"519.320228ms","remote":"127.0.0.1:56160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T23:04:03.429448Z","caller":"traceutil/trace.go:171","msg":"trace[1571063620] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"511.153826ms","start":"2026-07-02T23:04:02.918287Z","end":"2026-07-02T23:04:03.429441Z","steps":["trace[1571063620] 'process raft request' (duration: 510.573408ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T23:04:03.429473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T23:04:02.918274Z","time spent":"511.181467ms","remote":"127.0.0.1:56160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T23:04:03.429506Z","caller":"traceutil/trace.go:171","msg":"trace[2097654325] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"512.323054ms","start":"2026-07-02T23:04:02.91718Z","end":"2026-07-02T23:04:03.429503Z","steps":["trace[2097654325] 'process raft request' (duration: 511.647773ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T23:04:03.429526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T23:04:02.917176Z","time spent":"512.342455ms","remote":"127.0.0.1:56160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T23:04:03.42956Z","caller":"traceutil/trace.go:171","msg":"trace[532312967] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"512.479319ms","start":"2026-07-02T23:04:02.917077Z","end":"2026-07-02T23:04:03.429556Z","steps":["trace[532312967] 'process raft request' (duration: 511.495288ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T23:04:03.42958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T23:04:02.91706Z","time spent":"512.51119ms","remote":"127.0.0.1:56160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T23:04:03.429612Z","caller":"traceutil/trace.go:171","msg":"trace[1412010113] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"507.295203ms","start":"2026-07-02T23:04:02.922313Z","end":"2026-07-02T23:04:03.429609Z","steps":["trace[1412010113] 'process raft request' (duration: 506.616141ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T23:04:03.429632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T23:04:02.922294Z","time spent":"507.329604ms","remote":"127.0.0.1:56160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T23:04:03.429664Z","caller":"traceutil/trace.go:171","msg":"trace[1959642528] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"510.520876ms","start":"2026-07-02T23:04:02.91914Z","end":"2026-07-02T23:04:03.429661Z","steps":["trace[1959642528] 'process raft request' (duration: 509.756582ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T23:04:03.429685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T23:04:02.919134Z","time spent":"510.541377ms","remote":"127.0.0.1:56160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T23:04:03.429767Z","caller":"traceutil/trace.go:171","msg":"trace[1575380431] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"493.302194ms","start":"2026-07-02T23:04:02.93646Z","end":"2026-07-02T23:04:03.429762Z","steps":["trace[1575380431] 'process raft request' (duration: 492.584322ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T23:04:03.429793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T23:04:02.936442Z","time spent":"493.338466ms","remote":"127.0.0.1:56160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T23:04:03.429982Z","caller":"traceutil/trace.go:171","msg":"trace[1998388694] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"485.69399ms","start":"2026-07-02T23:04:02.944283Z","end":"2026-07-02T23:04:03.429977Z","steps":["trace[1998388694] 'process raft request' (duration: 485.05667ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T23:04:03.430011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T23:04:02.944265Z","time spent":"485.733742ms","remote":"127.0.0.1:56160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T23:04:03.430266Z","caller":"traceutil/trace.go:171","msg":"trace[688388054] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"464.611645ms","start":"2026-07-02T23:04:02.965637Z","end":"2026-07-02T23:04:03.430249Z","steps":["trace[688388054] 'process raft request' (duration: 464.247833ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T23:04:03.430361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T23:04:02.96561Z","time spent":"464.704538ms","remote":"127.0.0.1:56160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T23:04:03.430512Z","caller":"traceutil/trace.go:171","msg":"trace[797925499] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"348.051702ms","start":"2026-07-02T23:04:03.082451Z","end":"2026-07-02T23:04:03.430503Z","steps":["trace[797925499] 'process raft request' (duration: 347.826985ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T23:04:03.43056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T23:04:03.082428Z","time spent":"348.112694ms","remote":"127.0.0.1:56160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T23:04:03.430521Z","caller":"traceutil/trace.go:171","msg":"trace[713263598] linearizableReadLoop","detail":"{readStateIndex:556; appliedIndex:545; }","duration":"409.090711ms","start":"2026-07-02T23:04:03.021414Z","end":"2026-07-02T23:04:03.430505Z","steps":["trace[713263598] 'read index received' (duration: 400.448066ms)","trace[713263598] 'applied index is now lower than readState.Index' (duration: 8.641845ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T23:04:03.430705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.268357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T23:04:03.43079Z","caller":"traceutil/trace.go:171","msg":"trace[970267031] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:538; }","duration":"409.38706ms","start":"2026-07-02T23:04:03.021381Z","end":"2026-07-02T23:04:03.430768Z","steps":["trace[970267031] 'agreement among raft nodes before linearized reading' (duration: 409.219085ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T23:04:03.430845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T23:04:03.021365Z","time spent":"409.470283ms","remote":"127.0.0.1:56126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-02T23:04:03.443099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.961674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-02T23:04:03.443159Z","caller":"traceutil/trace.go:171","msg":"trace[940264746] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:540; }","duration":"385.079558ms","start":"2026-07-02T23:04:03.058065Z","end":"2026-07-02T23:04:03.443145Z","steps":["trace[940264746] 'agreement among raft nodes before linearized reading' (duration: 384.907223ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T23:04:03.443323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T23:04:03.058034Z","time spent":"385.229513ms","remote":"127.0.0.1:56382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-02T23:04:09.666314Z","caller":"traceutil/trace.go:171","msg":"trace[617562804] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"142.706922ms","start":"2026-07-02T23:04:09.523581Z","end":"2026-07-02T23:04:09.666288Z","steps":["trace[617562804] 'process raft request' (duration: 142.564678ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T23:04:09.929681Z","caller":"traceutil/trace.go:171","msg":"trace[685482584] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:658; }","duration":"216.831402ms","start":"2026-07-02T23:04:09.712826Z","end":"2026-07-02T23:04:09.929657Z","steps":["trace[685482584] 'read index received' (duration: 215.650667ms)","trace[685482584] 'applied index is now lower than readState.Index' (duration: 1.179915ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T23:04:09.929769Z","caller":"traceutil/trace.go:171","msg":"trace[2017935409] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"305.638243ms","start":"2026-07-02T23:04:09.624105Z","end":"2026-07-02T23:04:09.929743Z","steps":["trace[2017935409] 'process raft request' (duration: 304.653493ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T23:04:09.929984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T23:04:09.624072Z","time spent":"305.809228ms","remote":"127.0.0.1:56210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036301,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T23:04:09.929973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.138431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-44hv6\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-02T23:04:09.930142Z","caller":"traceutil/trace.go:171","msg":"trace[1716474603] range","detail":"{range_begin:/registry/pods/kube-system/cilium-44hv6; range_end:; response_count:1; response_revision:637; }","duration":"217.345717ms","start":"2026-07-02T23:04:09.712778Z","end":"2026-07-02T23:04:09.930123Z","steps":["trace[1716474603] 'agreement among raft nodes before linearized reading' (duration: 216.971906ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T23:04:10.091518Z","caller":"traceutil/trace.go:171","msg":"trace[1066672083] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"141.821392ms","start":"2026-07-02T23:04:09.949675Z","end":"2026-07-02T23:04:10.091497Z","steps":["trace[1066672083] 'process raft request' (duration: 133.948727ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T23:04:10.929324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.558163ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-02T23:04:10.929562Z","caller":"traceutil/trace.go:171","msg":"trace[607662268] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"942.377362ms","start":"2026-07-02T23:04:09.987156Z","end":"2026-07-02T23:04:10.929534Z","steps":["trace[607662268] 'process raft request' (duration: 813.552511ms)","trace[607662268] 'compare' (duration: 128.412549ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T23:04:10.929699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T23:04:09.987133Z","time spent":"942.512206ms","remote":"127.0.0.1:56370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T23:04:10.98349Z","caller":"traceutil/trace.go:171","msg":"trace[2082986624] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:660; }","duration":"544.727582ms","start":"2026-07-02T23:04:10.438734Z","end":"2026-07-02T23:04:10.983462Z","steps":["trace[2082986624] 'read index received' (duration: 362.145698ms)","trace[2082986624] 'applied index is now lower than readState.Index' (duration: 182.580464ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T23:04:10.983795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.033311ms","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-02T23:04:10.983877Z","caller":"traceutil/trace.go:171","msg":"trace[466852434] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:639; }","duration":"545.188676ms","start":"2026-07-02T23:04:10.438669Z","end":"2026-07-02T23:04:10.983858Z","steps":["trace[466852434] 'agreement among raft nodes before linearized reading' (duration: 544.910648ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T23:04:10.98389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.996117ms","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-02T23:04:10.983928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T23:04:10.438644Z","time spent":"545.275308ms","remote":"127.0.0.1:56382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-02T23:04:10.98395Z","caller":"traceutil/trace.go:171","msg":"trace[891139437] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:639; }","duration":"397.12245ms","start":"2026-07-02T23:04:10.586812Z","end":"2026-07-02T23:04:10.983935Z","steps":["trace[891139437] 'agreement among raft nodes before linearized reading' (duration: 396.915794ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T23:04:10.983989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T23:04:10.58679Z","time spent":"397.189263ms","remote":"127.0.0.1:56382","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-02T23:04:10.984011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.117895ms","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-02T23:04:10.984062Z","caller":"traceutil/trace.go:171","msg":"trace[1698745688] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:639; }","duration":"535.235959ms","start":"2026-07-02T23:04:10.448814Z","end":"2026-07-02T23:04:10.98405Z","steps":["trace[1698745688] 'agreement among raft nodes before linearized reading' (duration: 534.799226ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T23:04:10.984183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T23:04:10.448789Z","time spent":"535.382373ms","remote":"127.0.0.1:56382","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-02T23:04:11.772425Z","caller":"traceutil/trace.go:171","msg":"trace[1045304771] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"665.605963ms","start":"2026-07-02T23:04:11.106777Z","end":"2026-07-02T23:04:11.772383Z","steps":["trace[1045304771] 'process raft request' (duration: 665.369286ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T23:04:11.772448Z","caller":"traceutil/trace.go:171","msg":"trace[720535857] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"663.998825ms","start":"2026-07-02T23:04:11.108417Z","end":"2026-07-02T23:04:11.772415Z","steps":["trace[720535857] 'process raft request' (duration: 663.853761ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T23:04:11.77253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.311355ms","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-02T23:04:11.772597Z","caller":"traceutil/trace.go:171","msg":"trace[1684374717] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:647; }","duration":"101.388277ms","start":"2026-07-02T23:04:11.671193Z","end":"2026-07-02T23:04:11.772581Z","steps":["trace[1684374717] 'agreement among raft nodes before linearized reading' (duration: 101.194201ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T23:04:11.772605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T23:04:11.108396Z","time spent":"664.130549ms","remote":"127.0.0.1:56312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T23:04:11.772602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T23:04:11.106763Z","time spent":"665.751827ms","remote":"127.0.0.1:56186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T23:04:11.772729Z","caller":"traceutil/trace.go:171","msg":"trace[790087824] linearizableReadLoop","detail":"{readStateIndex:670; appliedIndex:667; }","duration":"101.17442ms","start":"2026-07-02T23:04:11.671209Z","end":"2026-07-02T23:04:11.772383Z","steps":["trace[790087824] 'read index received' (duration: 100.951674ms)","trace[790087824] 'applied index is now lower than readState.Index' (duration: 221.466µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T23:04:11.772795Z","caller":"traceutil/trace.go:171","msg":"trace[1099909821] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"663.979365ms","start":"2026-07-02T23:04:11.108808Z","end":"2026-07-02T23:04:11.772787Z","steps":["trace[1099909821] 'process raft request' (duration: 663.532752ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T23:04:11.772836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T23:04:11.108801Z","time spent":"664.011526ms","remote":"127.0.0.1:56370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T23:04:12.96706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.034193769s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T23:04:12.967173Z","caller":"traceutil/trace.go:171","msg":"trace[1654803271] linearizableReadLoop","detail":"{readStateIndex:671; appliedIndex:670; }","duration":"946.284835ms","start":"2026-07-02T23:04:12.020871Z","end":"2026-07-02T23:04:12.967156Z","steps":["trace[1654803271] 'read index received' (duration: 54.401µs)","trace[1654803271] 'applied index is now lower than readState.Index' (duration: 946.229344ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T23:04:12.96721Z","caller":"traceutil/trace.go:171","msg":"trace[473919399] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"1.189669864s","start":"2026-07-02T23:04:11.777509Z","end":"2026-07-02T23:04:12.967178Z","steps":["trace[473919399] 'process raft request' (duration: 155.281439ms)","trace[473919399] 'compare' (duration: 1.034073515s)"],"step_count":2} {"level":"warn","ts":"2026-07-02T23:04:12.967246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"946.380808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T23:04:12.967282Z","caller":"traceutil/trace.go:171","msg":"trace[1280791266] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:648; }","duration":"946.45428ms","start":"2026-07-02T23:04:12.020818Z","end":"2026-07-02T23:04:12.967272Z","steps":["trace[1280791266] 'agreement among raft nodes before linearized reading' (duration: 946.385618ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T23:04:12.967318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T23:04:12.020795Z","time spent":"946.515433ms","remote":"127.0.0.1:56126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-02T23:04:12.967353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T23:04:11.77749Z","time spent":"1.189787778s","remote":"127.0.0.1:56382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T23:04:13.510153Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276103442637989721,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-02T23:04:13.719057Z","caller":"traceutil/trace.go:171","msg":"trace[742347548] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"711.969392ms","start":"2026-07-02T23:04:13.007063Z","end":"2026-07-02T23:04:13.719032Z","steps":["trace[742347548] 'process raft request' (duration: 711.843948ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T23:04:13.7191Z","caller":"traceutil/trace.go:171","msg":"trace[1777017597] linearizableReadLoop","detail":"{readStateIndex:672; appliedIndex:672; }","duration":"709.595053ms","start":"2026-07-02T23:04:13.009458Z","end":"2026-07-02T23:04:13.719053Z","steps":["trace[1777017597] 'read index received' (duration: 709.581213ms)","trace[1777017597] 'applied index is now lower than readState.Index' (duration: 10.95µs)"],"step_count":2} {"level":"warn","ts":"2026-07-02T23:04:13.719597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.110258ms","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-02T23:04:13.719662Z","caller":"traceutil/trace.go:171","msg":"trace[1688211641] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:649; }","duration":"710.215501ms","start":"2026-07-02T23:04:13.009425Z","end":"2026-07-02T23:04:13.719641Z","steps":["trace[1688211641] 'agreement among raft nodes before linearized reading' (duration: 709.752168ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T23:04:13.719706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T23:04:13.009412Z","time spent":"710.285463ms","remote":"127.0.0.1:56382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-02T23:04:13.719959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.454237ms","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-02T23:04:13.720063Z","caller":"traceutil/trace.go:171","msg":"trace[741007344] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:649; }","duration":"710.583331ms","start":"2026-07-02T23:04:13.009438Z","end":"2026-07-02T23:04:13.720022Z","steps":["trace[741007344] 'agreement among raft nodes before linearized reading' (duration: 710.19914ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T23:04:13.720114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T23:04:13.009426Z","time spent":"710.671495ms","remote":"127.0.0.1:56382","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-02T23:04:13.719504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T23:04:13.007035Z","time spent":"712.377434ms","remote":"127.0.0.1:56382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":488,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T23:04:15.134946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.027506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T23:04:15.135046Z","caller":"traceutil/trace.go:171","msg":"trace[54624529] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:659; }","duration":"114.17458ms","start":"2026-07-02T23:04:15.020849Z","end":"2026-07-02T23:04:15.135023Z","steps":["trace[54624529] 'range keys from in-memory index tree' (duration: 113.942144ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T23:04:15.13506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.005069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.129\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-02T23:04:15.135139Z","caller":"traceutil/trace.go:171","msg":"trace[529612775] range","detail":"{range_begin:/registry/masterleases/199.204.45.129; range_end:; response_count:1; response_revision:659; }","duration":"111.102453ms","start":"2026-07-02T23:04:15.024015Z","end":"2026-07-02T23:04:15.135118Z","steps":["trace[529612775] 'range keys from in-memory index tree' (duration: 110.814314ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T23:04:15.135415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.869477ms","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-02T23:04:15.135476Z","caller":"traceutil/trace.go:171","msg":"trace[2122406037] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:659; }","duration":"163.96589ms","start":"2026-07-02T23:04:14.971498Z","end":"2026-07-02T23:04:15.135463Z","steps":["trace[2122406037] 'range keys from in-memory index tree' (duration: 163.700191ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T23:04:29.82826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.341695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-02T23:04:29.828545Z","caller":"traceutil/trace.go:171","msg":"trace[1521205214] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:806; }","duration":"480.678264ms","start":"2026-07-02T23:04:29.347838Z","end":"2026-07-02T23:04:29.828517Z","steps":["trace[1521205214] 'count revisions from in-memory index tree' (duration: 480.269243ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T23:04:29.82866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T23:04:29.347815Z","time spent":"480.822997ms","remote":"127.0.0.1:56506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":29,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-07-02T23:04:29.828911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.822387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-02T23:04:29.829421Z","caller":"traceutil/trace.go:171","msg":"trace[2067104040] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:806; }","duration":"615.410711ms","start":"2026-07-02T23:04:29.213978Z","end":"2026-07-02T23:04:29.829389Z","steps":["trace[2067104040] 'range keys from in-memory index tree' (duration: 614.549309ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T23:04:29.829508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T23:04:29.213947Z","time spent":"615.536635ms","remote":"127.0.0.1:56382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-02T23:04:29.829133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.813452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-07-02T23:04:29.829781Z","caller":"traceutil/trace.go:171","msg":"trace[880589368] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:806; }","duration":"589.489391ms","start":"2026-07-02T23:04:29.240259Z","end":"2026-07-02T23:04:29.829748Z","steps":["trace[880589368] 'range keys from in-memory index tree' (duration: 588.673829ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T23:04:29.829875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T23:04:29.240227Z","time spent":"589.630073ms","remote":"127.0.0.1:56382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-02T23:05:04.138666Z","caller":"traceutil/trace.go:171","msg":"trace[1301802991] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"101.148512ms","start":"2026-07-02T23:05:04.037497Z","end":"2026-07-02T23:05:04.138645Z","steps":["trace[1301802991] 'process raft request' (duration: 100.710143ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T23:05:06.149372Z","caller":"traceutil/trace.go:171","msg":"trace[674204679] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"107.741442ms","start":"2026-07-02T23:05:06.041583Z","end":"2026-07-02T23:05:06.149325Z","steps":["trace[674204679] 'process raft request' (duration: 107.569229ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T23:05:06.498899Z","caller":"traceutil/trace.go:171","msg":"trace[476151119] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"345.92062ms","start":"2026-07-02T23:05:06.152951Z","end":"2026-07-02T23:05:06.498872Z","steps":["trace[476151119] 'process raft request' (duration: 345.680145ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T23:05:06.499258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T23:05:06.152932Z","time spent":"346.226376ms","remote":"127.0.0.1:56382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T23:05:06.500052Z","caller":"traceutil/trace.go:171","msg":"trace[312578815] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"344.985831ms","start":"2026-07-02T23:05:06.155036Z","end":"2026-07-02T23:05:06.500022Z","steps":["trace[312578815] 'process raft request' (duration: 344.93169ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T23:05:06.500294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T23:05:06.155029Z","time spent":"345.107174ms","remote":"127.0.0.1:56382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T23:05:06.50043Z","caller":"traceutil/trace.go:171","msg":"trace[2024355100] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"346.282678ms","start":"2026-07-02T23:05:06.1541Z","end":"2026-07-02T23:05:06.500382Z","steps":["trace[2024355100] 'process raft request' (duration: 345.828268ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T23:05:06.500474Z","caller":"traceutil/trace.go:171","msg":"trace[614549988] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"347.225237ms","start":"2026-07-02T23:05:06.153216Z","end":"2026-07-02T23:05:06.500442Z","steps":["trace[614549988] 'process raft request' (duration: 346.592874ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T23:05:06.500825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T23:05:06.153194Z","time spent":"347.547893ms","remote":"127.0.0.1:56382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T23:05:06.500669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T23:05:06.154079Z","time spent":"346.470421ms","remote":"127.0.0.1:56382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T23:05:39.820242Z","caller":"traceutil/trace.go:171","msg":"trace[1450484595] transaction","detail":"{read_only:false; response_revision:1169; number_of_response:1; }","duration":"133.930144ms","start":"2026-07-02T23:05:39.686287Z","end":"2026-07-02T23:05:39.820217Z","steps":["trace[1450484595] 'process raft request' (duration: 133.798172ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T23:05:41.455372Z","caller":"traceutil/trace.go:171","msg":"trace[1034973777] transaction","detail":"{read_only:false; response_revision:1175; number_of_response:1; }","duration":"344.255944ms","start":"2026-07-02T23:05:41.111076Z","end":"2026-07-02T23:05:41.455332Z","steps":["trace[1034973777] 'process raft request' (duration: 343.992079ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T23:05:41.455598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T23:05:41.111041Z","time spent":"344.430176ms","remote":"127.0.0.1:56382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T23:05:43.572347Z","caller":"traceutil/trace.go:171","msg":"trace[1147986242] transaction","detail":"{read_only:false; response_revision:1181; number_of_response:1; }","duration":"110.157196ms","start":"2026-07-02T23:05:43.462162Z","end":"2026-07-02T23:05:43.572319Z","steps":["trace[1147986242] 'process raft request' (duration: 110.004353ms)"],"step_count":1}