{"level":"warn","ts":"2026-09-18T01:21:26.3621Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-18T01:21:26.362317Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.76:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.76:2380","--initial-cluster=instance=https://162.253.55.76:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.76:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.76: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-09-18T01:21:26.362442Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-18T01:21:26.362479Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.76:2380"]} {"level":"info","ts":"2026-09-18T01:21:26.362515Z","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-09-18T01:21:26.363434Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.76:2379"]} {"level":"info","ts":"2026-09-18T01:21:26.363523Z","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://162.253.55.76:2380"],"listen-peer-urls":["https://162.253.55.76:2380"],"advertise-client-urls":["https://162.253.55.76:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.76:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.76: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-09-18T01:21:26.446316Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"82.561346ms"} {"level":"info","ts":"2026-09-18T01:21:26.464397Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"30ad0c2b6ecdd25e","cluster-id":"855d190e6ef0a61f"} {"level":"info","ts":"2026-09-18T01:21:26.464567Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30ad0c2b6ecdd25e switched to configuration voters=()"} {"level":"info","ts":"2026-09-18T01:21:26.464666Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30ad0c2b6ecdd25e became follower at term 0"} {"level":"info","ts":"2026-09-18T01:21:26.464698Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 30ad0c2b6ecdd25e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-18T01:21:26.4648Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30ad0c2b6ecdd25e became follower at term 1"} {"level":"info","ts":"2026-09-18T01:21:26.464956Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30ad0c2b6ecdd25e switched to configuration voters=(3507473065473593950)"} {"level":"warn","ts":"2026-09-18T01:21:26.477451Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-18T01:21:26.496776Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-18T01:21:26.498327Z","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-09-18T01:21:26.501068Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"30ad0c2b6ecdd25e","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-18T01:21:26.501395Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"30ad0c2b6ecdd25e","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-18T01:21:26.501572Z","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-09-18T01:21:26.501688Z","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-09-18T01:21:26.501705Z","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-09-18T01:21:26.513004Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30ad0c2b6ecdd25e switched to configuration voters=(3507473065473593950)"} {"level":"info","ts":"2026-09-18T01:21:26.513311Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"855d190e6ef0a61f","local-member-id":"30ad0c2b6ecdd25e","added-peer-id":"30ad0c2b6ecdd25e","added-peer-peer-urls":["https://162.253.55.76:2380"]} {"level":"info","ts":"2026-09-18T01:21:26.515142Z","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-09-18T01:21:26.515319Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.76:2380"} {"level":"info","ts":"2026-09-18T01:21:26.515405Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.76:2380"} {"level":"info","ts":"2026-09-18T01:21:26.516869Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-18T01:21:26.516825Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"30ad0c2b6ecdd25e","initial-advertise-peer-urls":["https://162.253.55.76:2380"],"listen-peer-urls":["https://162.253.55.76:2380"],"advertise-client-urls":["https://162.253.55.76:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.76:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-18T01:21:26.765755Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30ad0c2b6ecdd25e is starting a new election at term 1"} {"level":"info","ts":"2026-09-18T01:21:26.765903Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30ad0c2b6ecdd25e became pre-candidate at term 1"} {"level":"info","ts":"2026-09-18T01:21:26.765943Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30ad0c2b6ecdd25e received MsgPreVoteResp from 30ad0c2b6ecdd25e at term 1"} {"level":"info","ts":"2026-09-18T01:21:26.765978Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30ad0c2b6ecdd25e became candidate at term 2"} {"level":"info","ts":"2026-09-18T01:21:26.765997Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30ad0c2b6ecdd25e received MsgVoteResp from 30ad0c2b6ecdd25e at term 2"} {"level":"info","ts":"2026-09-18T01:21:26.766022Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30ad0c2b6ecdd25e became leader at term 2"} {"level":"info","ts":"2026-09-18T01:21:26.766042Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 30ad0c2b6ecdd25e elected leader 30ad0c2b6ecdd25e at term 2"} {"level":"info","ts":"2026-09-18T01:21:26.768386Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T01:21:26.768851Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"30ad0c2b6ecdd25e","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.76:2379]}","request-path":"/0/members/30ad0c2b6ecdd25e/attributes","cluster-id":"855d190e6ef0a61f","publish-timeout":"7s"} {"level":"info","ts":"2026-09-18T01:21:26.768917Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-18T01:21:26.769277Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-18T01:21:26.769307Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-18T01:21:26.769848Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-18T01:21:26.770681Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-18T01:21:26.771089Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.76:2379"} {"level":"info","ts":"2026-09-18T01:21:26.771305Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"855d190e6ef0a61f","local-member-id":"30ad0c2b6ecdd25e","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T01:21:26.771518Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T01:21:26.771557Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-09-18T01:21:36.280187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.326758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-09-18T01:21:36.280301Z","caller":"traceutil/trace.go:171","msg":"trace[976173741] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:295; }","duration":"442.511623ms","start":"2026-09-18T01:21:35.837769Z","end":"2026-09-18T01:21:36.280281Z","steps":["trace[976173741] 'range keys from in-memory index tree' (duration: 442.188654ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:21:36.280353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:21:35.837741Z","time spent":"442.602845ms","remote":"127.0.0.1:43220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-09-18T01:21:36.691451Z","caller":"traceutil/trace.go:171","msg":"trace[38907100] transaction","detail":"{read_only:false; response_revision:296; number_of_response:1; }","duration":"407.05402ms","start":"2026-09-18T01:21:36.284346Z","end":"2026-09-18T01:21:36.6914Z","steps":["trace[38907100] 'process raft request' (duration: 406.866285ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:21:36.692107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:21:36.284314Z","time spent":"407.230005ms","remote":"127.0.0.1:43296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":192,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T01:21:36.905574Z","caller":"traceutil/trace.go:171","msg":"trace[1999956856] linearizableReadLoop","detail":"{readStateIndex:308; appliedIndex:307; }","duration":"160.743692ms","start":"2026-09-18T01:21:36.744799Z","end":"2026-09-18T01:21:36.905543Z","steps":["trace[1999956856] 'read index received' (duration: 160.608368ms)","trace[1999956856] 'applied index is now lower than readState.Index' (duration: 134.164µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T01:21:36.905909Z","caller":"traceutil/trace.go:171","msg":"trace[517845489] transaction","detail":"{read_only:false; response_revision:297; number_of_response:1; }","duration":"168.061323ms","start":"2026-09-18T01:21:36.737791Z","end":"2026-09-18T01:21:36.905852Z","steps":["trace[517845489] 'process raft request' (duration: 167.5788ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:21:36.905974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.231014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"warn","ts":"2026-09-18T01:21:36.906077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.164632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"warn","ts":"2026-09-18T01:21:36.905975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.272737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"warn","ts":"2026-09-18T01:21:36.906101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.326441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"warn","ts":"2026-09-18T01:21:36.906276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.15793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"warn","ts":"2026-09-18T01:21:36.906309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.625023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4322"} {"level":"info","ts":"2026-09-18T01:21:36.906315Z","caller":"traceutil/trace.go:171","msg":"trace[749548469] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:297; }","duration":"141.339837ms","start":"2026-09-18T01:21:36.764675Z","end":"2026-09-18T01:21:36.906015Z","steps":["trace[749548469] 'agreement among raft nodes before linearized reading' (duration: 141.195663ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:21:36.906406Z","caller":"traceutil/trace.go:171","msg":"trace[293584093] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:297; }","duration":"125.595438ms","start":"2026-09-18T01:21:36.780739Z","end":"2026-09-18T01:21:36.906334Z","steps":["trace[293584093] 'agreement among raft nodes before linearized reading' (duration: 125.2756ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:21:36.906446Z","caller":"traceutil/trace.go:171","msg":"trace[1788172062] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:297; }","duration":"109.352445ms","start":"2026-09-18T01:21:36.79702Z","end":"2026-09-18T01:21:36.906372Z","steps":["trace[1788172062] 'agreement among raft nodes before linearized reading' (duration: 108.981975ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:21:36.906434Z","caller":"traceutil/trace.go:171","msg":"trace[1254529444] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:297; }","duration":"134.764966ms","start":"2026-09-18T01:21:36.771643Z","end":"2026-09-18T01:21:36.906408Z","steps":["trace[1254529444] 'agreement among raft nodes before linearized reading' (duration: 134.375076ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:21:36.906255Z","caller":"traceutil/trace.go:171","msg":"trace[329228524] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:297; }","duration":"161.43325ms","start":"2026-09-18T01:21:36.744767Z","end":"2026-09-18T01:21:36.906201Z","steps":["trace[329228524] 'agreement among raft nodes before linearized reading' (duration: 161.04435ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:21:36.906297Z","caller":"traceutil/trace.go:171","msg":"trace[1695982001] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:297; }","duration":"113.628735ms","start":"2026-09-18T01:21:36.792649Z","end":"2026-09-18T01:21:36.906278Z","steps":["trace[1695982001] 'agreement among raft nodes before linearized reading' (duration: 113.259056ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:21:56.464525Z","caller":"traceutil/trace.go:171","msg":"trace[1988081688] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"166.568573ms","start":"2026-09-18T01:21:56.297906Z","end":"2026-09-18T01:21:56.464475Z","steps":["trace[1988081688] 'process raft request' (duration: 132.464389ms)","trace[1988081688] 'compare' (duration: 33.82007ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T01:21:56.464554Z","caller":"traceutil/trace.go:171","msg":"trace[1267503878] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"165.923004ms","start":"2026-09-18T01:21:56.298588Z","end":"2026-09-18T01:21:56.464511Z","steps":["trace[1267503878] 'process raft request' (duration: 165.774622ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:21:56.464537Z","caller":"traceutil/trace.go:171","msg":"trace[537655656] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"163.612178ms","start":"2026-09-18T01:21:56.3009Z","end":"2026-09-18T01:21:56.464512Z","steps":["trace[537655656] 'process raft request' (duration: 163.559167ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:21:56.465213Z","caller":"traceutil/trace.go:171","msg":"trace[948123653] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"164.950423ms","start":"2026-09-18T01:21:56.300247Z","end":"2026-09-18T01:21:56.465197Z","steps":["trace[948123653] 'process raft request' (duration: 164.154854ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:21:56.478935Z","caller":"traceutil/trace.go:171","msg":"trace[1472816080] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"131.102483ms","start":"2026-09-18T01:21:56.347798Z","end":"2026-09-18T01:21:56.4789Z","steps":["trace[1472816080] 'process raft request' (duration: 130.880841ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:21:56.669104Z","caller":"traceutil/trace.go:171","msg":"trace[1846456348] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"117.451365ms","start":"2026-09-18T01:21:56.55162Z","end":"2026-09-18T01:21:56.669071Z","steps":["trace[1846456348] 'process raft request' (duration: 117.387865ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:21:56.669704Z","caller":"traceutil/trace.go:171","msg":"trace[1153678592] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"126.006834ms","start":"2026-09-18T01:21:56.543678Z","end":"2026-09-18T01:21:56.669685Z","steps":["trace[1153678592] 'process raft request' (duration: 77.644186ms)","trace[1153678592] 'compare' (duration: 47.523869ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T01:21:56.808306Z","caller":"traceutil/trace.go:171","msg":"trace[896668044] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"129.330563ms","start":"2026-09-18T01:21:56.678935Z","end":"2026-09-18T01:21:56.808265Z","steps":["trace[896668044] 'process raft request' (duration: 91.946191ms)","trace[896668044] 'compare' (duration: 37.144328ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T01:21:56.808561Z","caller":"traceutil/trace.go:171","msg":"trace[1575596891] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"129.1635ms","start":"2026-09-18T01:21:56.67938Z","end":"2026-09-18T01:21:56.808543Z","steps":["trace[1575596891] 'process raft request' (duration: 128.864947ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:21:56.808749Z","caller":"traceutil/trace.go:171","msg":"trace[1426468932] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"129.346483ms","start":"2026-09-18T01:21:56.679359Z","end":"2026-09-18T01:21:56.808705Z","steps":["trace[1426468932] 'process raft request' (duration: 128.826397ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:21:56.808535Z","caller":"traceutil/trace.go:171","msg":"trace[1347302443] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"125.036214ms","start":"2026-09-18T01:21:56.68348Z","end":"2026-09-18T01:21:56.808516Z","steps":["trace[1347302443] 'process raft request' (duration: 124.868372ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:21:56.80859Z","caller":"traceutil/trace.go:171","msg":"trace[1103170543] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"128.707075ms","start":"2026-09-18T01:21:56.67985Z","end":"2026-09-18T01:21:56.808558Z","steps":["trace[1103170543] 'process raft request' (duration: 128.458892ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:22:13.28315Z","caller":"traceutil/trace.go:171","msg":"trace[1300632100] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"168.396162ms","start":"2026-09-18T01:22:13.114704Z","end":"2026-09-18T01:22:13.2831Z","steps":["trace[1300632100] 'process raft request' (duration: 163.839817ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:22:13.492451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.375471ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T01:22:13.492675Z","caller":"traceutil/trace.go:171","msg":"trace[1465744968] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"202.868198ms","start":"2026-09-18T01:22:13.289777Z","end":"2026-09-18T01:22:13.492645Z","steps":["trace[1465744968] 'process raft request' (duration: 33.99776ms)","trace[1465744968] 'compare' (duration: 168.231799ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T01:22:19.748714Z","caller":"traceutil/trace.go:171","msg":"trace[95920496] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"135.229576ms","start":"2026-09-18T01:22:19.613431Z","end":"2026-09-18T01:22:19.748661Z","steps":["trace[95920496] 'process raft request' (duration: 44.922229ms)","trace[95920496] 'compare' (duration: 90.169245ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T01:22:21.683422Z","caller":"traceutil/trace.go:171","msg":"trace[114157461] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:637; }","duration":"289.191859ms","start":"2026-09-18T01:22:21.394168Z","end":"2026-09-18T01:22:21.68336Z","steps":["trace[114157461] 'read index received' (duration: 286.813413ms)","trace[114157461] 'applied index is now lower than readState.Index' (duration: 2.377326ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T01:22:21.683688Z","caller":"traceutil/trace.go:171","msg":"trace[781534201] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"305.73739ms","start":"2026-09-18T01:22:21.377905Z","end":"2026-09-18T01:22:21.683642Z","steps":["trace[781534201] 'process raft request' (duration: 303.590218ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:22:21.683963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.787488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T01:22:21.684047Z","caller":"traceutil/trace.go:171","msg":"trace[343318412] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:613; }","duration":"289.910039ms","start":"2026-09-18T01:22:21.394119Z","end":"2026-09-18T01:22:21.684029Z","steps":["trace[343318412] 'agreement among raft nodes before linearized reading' (duration: 289.758027ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:22:21.684503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:22:21.377874Z","time spent":"305.852152ms","remote":"127.0.0.1:43200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036265,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T01:22:21.940592Z","caller":"traceutil/trace.go:171","msg":"trace[1392823723] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"169.769491ms","start":"2026-09-18T01:22:21.770745Z","end":"2026-09-18T01:22:21.940514Z","steps":["trace[1392823723] 'process raft request' (duration: 169.084041ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:22:22.598009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.288266ms","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-09-18T01:22:22.598102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.117947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-sqxwn\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-09-18T01:22:22.598134Z","caller":"traceutil/trace.go:171","msg":"trace[1155918196] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:617; }","duration":"487.48382ms","start":"2026-09-18T01:22:22.110621Z","end":"2026-09-18T01:22:22.598105Z","steps":["trace[1155918196] 'range keys from in-memory index tree' (duration: 487.095104ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:22:22.598211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:22:22.110598Z","time spent":"487.601721ms","remote":"127.0.0.1:43336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-09-18T01:22:22.598263Z","caller":"traceutil/trace.go:171","msg":"trace[2088923366] range","detail":"{range_begin:/registry/pods/kube-system/cilium-sqxwn; range_end:; response_count:1; response_revision:617; }","duration":"482.3102ms","start":"2026-09-18T01:22:22.115869Z","end":"2026-09-18T01:22:22.598179Z","steps":["trace[2088923366] 'range keys from in-memory index tree' (duration: 481.947105ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:22:22.598322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:22:22.115814Z","time spent":"482.498573ms","remote":"127.0.0.1:43292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-sqxwn\" "} {"level":"info","ts":"2026-09-18T01:22:22.663191Z","caller":"traceutil/trace.go:171","msg":"trace[133722796] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"275.369402ms","start":"2026-09-18T01:22:22.3878Z","end":"2026-09-18T01:22:22.66317Z","steps":["trace[133722796] 'process raft request' (duration: 263.211187ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:22:22.663111Z","caller":"traceutil/trace.go:171","msg":"trace[187963286] linearizableReadLoop","detail":"{readStateIndex:643; appliedIndex:642; }","duration":"269.869438ms","start":"2026-09-18T01:22:22.393144Z","end":"2026-09-18T01:22:22.663014Z","steps":["trace[187963286] 'read index received' (duration: 257.062202ms)","trace[187963286] 'applied index is now lower than readState.Index' (duration: 12.798656ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T01:22:22.663736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.575958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T01:22:22.663786Z","caller":"traceutil/trace.go:171","msg":"trace[1866583085] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:620; }","duration":"270.674909ms","start":"2026-09-18T01:22:22.393097Z","end":"2026-09-18T01:22:22.663772Z","steps":["trace[1866583085] 'agreement among raft nodes before linearized reading' (duration: 270.02169ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:22:24.424621Z","caller":"traceutil/trace.go:171","msg":"trace[1198285572] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"127.85647ms","start":"2026-09-18T01:22:24.296637Z","end":"2026-09-18T01:22:24.424493Z","steps":["trace[1198285572] 'process raft request' (duration: 127.682328ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:22:38.289416Z","caller":"traceutil/trace.go:171","msg":"trace[1831145916] linearizableReadLoop","detail":"{readStateIndex:813; appliedIndex:812; }","duration":"125.052104ms","start":"2026-09-18T01:22:38.164309Z","end":"2026-09-18T01:22:38.289361Z","steps":["trace[1831145916] 'read index received' (duration: 124.688698ms)","trace[1831145916] 'applied index is now lower than readState.Index' (duration: 362.116µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T01:22:38.289562Z","caller":"traceutil/trace.go:171","msg":"trace[389393518] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"192.939881ms","start":"2026-09-18T01:22:38.096612Z","end":"2026-09-18T01:22:38.289552Z","steps":["trace[389393518] 'process raft request' (duration: 192.512944ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:22:38.289943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.621073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-09-18T01:22:38.289969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.987755ms","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-09-18T01:22:38.290023Z","caller":"traceutil/trace.go:171","msg":"trace[1215378595] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:783; }","duration":"125.747985ms","start":"2026-09-18T01:22:38.164258Z","end":"2026-09-18T01:22:38.290006Z","steps":["trace[1215378595] 'agreement among raft nodes before linearized reading' (duration: 125.534782ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:22:38.290103Z","caller":"traceutil/trace.go:171","msg":"trace[1067626586] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:783; }","duration":"111.172818ms","start":"2026-09-18T01:22:38.178915Z","end":"2026-09-18T01:22:38.290088Z","steps":["trace[1067626586] 'agreement among raft nodes before linearized reading' (duration: 110.823492ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:22:51.161892Z","caller":"traceutil/trace.go:171","msg":"trace[2071819918] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"125.28684ms","start":"2026-09-18T01:22:51.036571Z","end":"2026-09-18T01:22:51.161858Z","steps":["trace[2071819918] 'process raft request' (duration: 118.050061ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:22:51.196268Z","caller":"traceutil/trace.go:171","msg":"trace[282418268] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"155.927979ms","start":"2026-09-18T01:22:51.04032Z","end":"2026-09-18T01:22:51.196248Z","steps":["trace[282418268] 'process raft request' (duration: 155.519172ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:23:06.141894Z","caller":"traceutil/trace.go:171","msg":"trace[1686645932] transaction","detail":"{read_only:false; response_revision:1167; number_of_response:1; }","duration":"107.12557ms","start":"2026-09-18T01:23:06.034739Z","end":"2026-09-18T01:23:06.141864Z","steps":["trace[1686645932] 'process raft request' (duration: 106.998918ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:24:04.092649Z","caller":"traceutil/trace.go:171","msg":"trace[555225881] transaction","detail":"{read_only:false; response_revision:1873; number_of_response:1; }","duration":"141.840547ms","start":"2026-09-18T01:24:03.950775Z","end":"2026-09-18T01:24:04.092616Z","steps":["trace[555225881] 'process raft request' (duration: 129.611728ms)","trace[555225881] 'compare' (duration: 12.091386ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T01:24:04.475847Z","caller":"traceutil/trace.go:171","msg":"trace[938994958] linearizableReadLoop","detail":"{readStateIndex:1948; appliedIndex:1947; }","duration":"280.153193ms","start":"2026-09-18T01:24:04.195665Z","end":"2026-09-18T01:24:04.475819Z","steps":["trace[938994958] 'read index received' (duration: 280.03786ms)","trace[938994958] 'applied index is now lower than readState.Index' (duration: 114.153µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T01:24:04.475984Z","caller":"traceutil/trace.go:171","msg":"trace[1178224942] transaction","detail":"{read_only:false; response_revision:1874; number_of_response:1; }","duration":"447.45289ms","start":"2026-09-18T01:24:04.028487Z","end":"2026-09-18T01:24:04.47594Z","steps":["trace[1178224942] 'process raft request' (duration: 447.141904ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:24:04.476037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.353908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T01:24:04.476093Z","caller":"traceutil/trace.go:171","msg":"trace[84630501] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1874; }","duration":"280.474ms","start":"2026-09-18T01:24:04.195606Z","end":"2026-09-18T01:24:04.47608Z","steps":["trace[84630501] 'agreement among raft nodes before linearized reading' (duration: 280.368488ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:24:04.47645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:24:04.028454Z","time spent":"447.818738ms","remote":"127.0.0.1:43278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7740,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T01:24:04.506018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.392565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-18T01:24:04.506078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.450059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9d1d294a-9880-4e22-b7d3-b29313ea4c64\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-09-18T01:24:04.506098Z","caller":"traceutil/trace.go:171","msg":"trace[1998853505] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1875; }","duration":"111.538198ms","start":"2026-09-18T01:24:04.394542Z","end":"2026-09-18T01:24:04.50608Z","steps":["trace[1998853505] 'agreement among raft nodes before linearized reading' (duration: 111.353034ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:24:04.50602Z","caller":"traceutil/trace.go:171","msg":"trace[522538693] transaction","detail":"{read_only:false; response_revision:1875; number_of_response:1; }","duration":"153.143938ms","start":"2026-09-18T01:24:04.35284Z","end":"2026-09-18T01:24:04.505984Z","steps":["trace[522538693] 'process raft request' (duration: 152.868932ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:24:04.506138Z","caller":"traceutil/trace.go:171","msg":"trace[733910899] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9d1d294a-9880-4e22-b7d3-b29313ea4c64; range_end:; response_count:1; response_revision:1875; }","duration":"280.576582ms","start":"2026-09-18T01:24:04.225545Z","end":"2026-09-18T01:24:04.506121Z","steps":["trace[733910899] 'agreement among raft nodes before linearized reading' (duration: 280.322016ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:24:05.049583Z","caller":"traceutil/trace.go:171","msg":"trace[1324338887] transaction","detail":"{read_only:false; response_revision:1877; number_of_response:1; }","duration":"322.907697ms","start":"2026-09-18T01:24:04.726655Z","end":"2026-09-18T01:24:05.049562Z","steps":["trace[1324338887] 'process raft request' (duration: 322.781715ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:24:05.049605Z","caller":"traceutil/trace.go:171","msg":"trace[1608479658] linearizableReadLoop","detail":"{readStateIndex:1951; appliedIndex:1951; }","duration":"290.004243ms","start":"2026-09-18T01:24:04.759559Z","end":"2026-09-18T01:24:05.049563Z","steps":["trace[1608479658] 'read index received' (duration: 289.981913ms)","trace[1608479658] 'applied index is now lower than readState.Index' (duration: 18.97µs)"],"step_count":2} {"level":"warn","ts":"2026-09-18T01:24:05.049732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:24:04.726636Z","time spent":"323.01164ms","remote":"127.0.0.1:43292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3731,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T01:24:05.049932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.33042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-18T01:24:05.049986Z","caller":"traceutil/trace.go:171","msg":"trace[681001888] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1877; }","duration":"290.473583ms","start":"2026-09-18T01:24:04.759496Z","end":"2026-09-18T01:24:05.04997Z","steps":["trace[681001888] 'agreement among raft nodes before linearized reading' (duration: 290.221768ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:24:05.057383Z","caller":"traceutil/trace.go:171","msg":"trace[1297133628] transaction","detail":"{read_only:false; response_revision:1878; number_of_response:1; }","duration":"258.572049ms","start":"2026-09-18T01:24:04.798789Z","end":"2026-09-18T01:24:05.057361Z","steps":["trace[1297133628] 'process raft request' (duration: 258.058817ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:24:05.057607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.233648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-09-18T01:24:05.057679Z","caller":"traceutil/trace.go:171","msg":"trace[424856533] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1878; }","duration":"151.357611ms","start":"2026-09-18T01:24:04.906301Z","end":"2026-09-18T01:24:05.057658Z","steps":["trace[424856533] 'agreement among raft nodes before linearized reading' (duration: 151.206968ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:24:05.05718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.980211ms","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-09-18T01:24:05.057854Z","caller":"traceutil/trace.go:171","msg":"trace[421656486] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1878; }","duration":"157.693216ms","start":"2026-09-18T01:24:04.900143Z","end":"2026-09-18T01:24:05.057837Z","steps":["trace[421656486] 'agreement among raft nodes before linearized reading' (duration: 156.858147ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:24:18.762031Z","caller":"traceutil/trace.go:171","msg":"trace[694924929] linearizableReadLoop","detail":"{readStateIndex:2077; appliedIndex:2076; }","duration":"114.492664ms","start":"2026-09-18T01:24:18.64752Z","end":"2026-09-18T01:24:18.762013Z","steps":["trace[694924929] 'read index received' (duration: 114.386292ms)","trace[694924929] 'applied index is now lower than readState.Index' (duration: 105.772µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T01:24:18.762282Z","caller":"traceutil/trace.go:171","msg":"trace[1370540997] transaction","detail":"{read_only:false; response_revision:2000; number_of_response:1; }","duration":"195.779027ms","start":"2026-09-18T01:24:18.566494Z","end":"2026-09-18T01:24:18.762273Z","steps":["trace[1370540997] 'process raft request' (duration: 195.409059ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:24:18.762476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.949814ms","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-09-18T01:24:18.762495Z","caller":"traceutil/trace.go:171","msg":"trace[165035532] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2000; }","duration":"115.022995ms","start":"2026-09-18T01:24:18.647466Z","end":"2026-09-18T01:24:18.762489Z","steps":["trace[165035532] 'agreement among raft nodes before linearized reading' (duration: 114.926253ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:24:38.962006Z","caller":"traceutil/trace.go:171","msg":"trace[1554442256] linearizableReadLoop","detail":"{readStateIndex:2201; appliedIndex:2200; }","duration":"100.309981ms","start":"2026-09-18T01:24:38.861671Z","end":"2026-09-18T01:24:38.961981Z","steps":["trace[1554442256] 'read index received' (duration: 100.142418ms)","trace[1554442256] 'applied index is now lower than readState.Index' (duration: 166.713µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T01:24:38.962126Z","caller":"traceutil/trace.go:171","msg":"trace[1915362662] transaction","detail":"{read_only:false; response_revision:2119; number_of_response:1; }","duration":"118.903826ms","start":"2026-09-18T01:24:38.84317Z","end":"2026-09-18T01:24:38.962074Z","steps":["trace[1915362662] 'process raft request' (duration: 118.647661ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:24:38.962483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.836644ms","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-09-18T01:24:38.962554Z","caller":"traceutil/trace.go:171","msg":"trace[633990320] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2119; }","duration":"100.971827ms","start":"2026-09-18T01:24:38.861566Z","end":"2026-09-18T01:24:38.962538Z","steps":["trace[633990320] 'agreement among raft nodes before linearized reading' (duration: 100.554828ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:24:39.128448Z","caller":"traceutil/trace.go:171","msg":"trace[1089075512] transaction","detail":"{read_only:false; response_revision:2120; number_of_response:1; }","duration":"161.200059ms","start":"2026-09-18T01:24:38.967134Z","end":"2026-09-18T01:24:39.128334Z","steps":["trace[1089075512] 'process raft request' (duration: 139.530088ms)","trace[1089075512] 'compare' (duration: 21.542019ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T01:24:39.509681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.875021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T01:24:39.50978Z","caller":"traceutil/trace.go:171","msg":"trace[928182956] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2125; }","duration":"115.024864ms","start":"2026-09-18T01:24:39.39473Z","end":"2026-09-18T01:24:39.509755Z","steps":["trace[928182956] 'range keys from in-memory index tree' (duration: 114.750849ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:25:45.930446Z","caller":"traceutil/trace.go:171","msg":"trace[2079658594] transaction","detail":"{read_only:false; response_revision:2585; number_of_response:1; }","duration":"100.34755ms","start":"2026-09-18T01:25:45.830076Z","end":"2026-09-18T01:25:45.930423Z","steps":["trace[2079658594] 'process raft request' (duration: 100.217287ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:26:21.962611Z","caller":"traceutil/trace.go:171","msg":"trace[1018830240] linearizableReadLoop","detail":"{readStateIndex:2885; appliedIndex:2884; }","duration":"133.525625ms","start":"2026-09-18T01:26:21.82906Z","end":"2026-09-18T01:26:21.962586Z","steps":["trace[1018830240] 'read index received' (duration: 133.25956ms)","trace[1018830240] 'applied index is now lower than readState.Index' (duration: 265.175µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T01:26:21.963062Z","caller":"traceutil/trace.go:171","msg":"trace[113164941] transaction","detail":"{read_only:false; response_revision:2780; number_of_response:1; }","duration":"173.742311ms","start":"2026-09-18T01:26:21.789305Z","end":"2026-09-18T01:26:21.963047Z","steps":["trace[113164941] 'process raft request' (duration: 173.078718ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:26:21.963409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.33157ms","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-09-18T01:26:21.963493Z","caller":"traceutil/trace.go:171","msg":"trace[256248652] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2780; }","duration":"134.477903ms","start":"2026-09-18T01:26:21.829002Z","end":"2026-09-18T01:26:21.963479Z","steps":["trace[256248652] 'agreement among raft nodes before linearized reading' (duration: 134.33398ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:30:56.453019Z","caller":"traceutil/trace.go:171","msg":"trace[369864797] linearizableReadLoop","detail":"{readStateIndex:5195; appliedIndex:5194; }","duration":"136.377912ms","start":"2026-09-18T01:30:56.316615Z","end":"2026-09-18T01:30:56.452993Z","steps":["trace[369864797] 'read index received' (duration: 136.195018ms)","trace[369864797] 'applied index is now lower than readState.Index' (duration: 182.024µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T01:30:56.453523Z","caller":"traceutil/trace.go:171","msg":"trace[1772045044] transaction","detail":"{read_only:false; response_revision:5003; number_of_response:1; }","duration":"238.670851ms","start":"2026-09-18T01:30:56.214402Z","end":"2026-09-18T01:30:56.453073Z","steps":["trace[1772045044] 'process raft request' (duration: 238.456277ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:30:56.453943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.306321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2207411"} {"level":"info","ts":"2026-09-18T01:30:56.453995Z","caller":"traceutil/trace.go:171","msg":"trace[451350153] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:5003; }","duration":"137.404374ms","start":"2026-09-18T01:30:56.316578Z","end":"2026-09-18T01:30:56.453983Z","steps":["trace[451350153] 'agreement among raft nodes before linearized reading' (duration: 136.542965ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:30:56.605716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.164867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-18T01:30:56.605831Z","caller":"traceutil/trace.go:171","msg":"trace[1844501898] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5003; }","duration":"119.338291ms","start":"2026-09-18T01:30:56.48647Z","end":"2026-09-18T01:30:56.605808Z","steps":["trace[1844501898] 'count revisions from in-memory index tree' (duration: 119.062674ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:30:56.605885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.820815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"info","ts":"2026-09-18T01:30:56.605958Z","caller":"traceutil/trace.go:171","msg":"trace[1606574471] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5003; }","duration":"115.926897ms","start":"2026-09-18T01:30:56.490014Z","end":"2026-09-18T01:30:56.605941Z","steps":["trace[1606574471] 'range keys from in-memory index tree' (duration: 115.673211ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:30:56.989921Z","caller":"traceutil/trace.go:171","msg":"trace[125050063] transaction","detail":"{read_only:false; response_revision:5007; number_of_response:1; }","duration":"185.270054ms","start":"2026-09-18T01:30:56.804625Z","end":"2026-09-18T01:30:56.989895Z","steps":["trace[125050063] 'process raft request' (duration: 184.67354ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:30:57.05907Z","caller":"traceutil/trace.go:171","msg":"trace[965153886] transaction","detail":"{read_only:false; response_revision:5008; number_of_response:1; }","duration":"253.116696ms","start":"2026-09-18T01:30:56.80593Z","end":"2026-09-18T01:30:57.059047Z","steps":["trace[965153886] 'process raft request' (duration: 252.897071ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:30:57.059384Z","caller":"traceutil/trace.go:171","msg":"trace[1000231571] transaction","detail":"{read_only:false; response_revision:5009; number_of_response:1; }","duration":"252.88966ms","start":"2026-09-18T01:30:56.806475Z","end":"2026-09-18T01:30:57.059364Z","steps":["trace[1000231571] 'process raft request' (duration: 252.518822ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:30:57.059514Z","caller":"traceutil/trace.go:171","msg":"trace[1969205894] linearizableReadLoop","detail":"{readStateIndex:5202; appliedIndex:5198; }","duration":"247.194277ms","start":"2026-09-18T01:30:56.812294Z","end":"2026-09-18T01:30:57.059488Z","steps":["trace[1969205894] 'read index received' (duration: 177.114615ms)","trace[1969205894] 'applied index is now lower than readState.Index' (duration: 70.078552ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T01:30:57.059641Z","caller":"traceutil/trace.go:171","msg":"trace[1290454319] transaction","detail":"{read_only:false; response_revision:5010; number_of_response:1; }","duration":"252.955502ms","start":"2026-09-18T01:30:56.806674Z","end":"2026-09-18T01:30:57.05963Z","steps":["trace[1290454319] 'process raft request' (duration: 252.594404ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:30:57.059817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.498433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1101a79d-7a67-4637-9f13-36ac630be975\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-09-18T01:30:57.059889Z","caller":"traceutil/trace.go:171","msg":"trace[1159151676] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1101a79d-7a67-4637-9f13-36ac630be975; range_end:; response_count:1; response_revision:5010; }","duration":"247.610776ms","start":"2026-09-18T01:30:56.812257Z","end":"2026-09-18T01:30:57.059868Z","steps":["trace[1159151676] 'agreement among raft nodes before linearized reading' (duration: 247.382831ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:31:07.816923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.750957ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T01:31:07.81714Z","caller":"traceutil/trace.go:171","msg":"trace[1495723938] linearizableReadLoop","detail":"{readStateIndex:5353; appliedIndex:5351; }","duration":"201.373048ms","start":"2026-09-18T01:31:07.615699Z","end":"2026-09-18T01:31:07.817072Z","steps":["trace[1495723938] 'read index received' (duration: 79.392486ms)","trace[1495723938] 'applied index is now lower than readState.Index' (duration: 121.979402ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T01:31:07.817467Z","caller":"traceutil/trace.go:171","msg":"trace[224261664] transaction","detail":"{read_only:false; response_revision:5154; number_of_response:1; }","duration":"228.52781ms","start":"2026-09-18T01:31:07.588924Z","end":"2026-09-18T01:31:07.817451Z","steps":["trace[224261664] 'process raft request' (duration: 106.172119ms)","trace[224261664] 'compare' (duration: 121.440821ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T01:31:07.817928Z","caller":"traceutil/trace.go:171","msg":"trace[504885328] transaction","detail":"{read_only:false; response_revision:5155; number_of_response:1; }","duration":"227.494347ms","start":"2026-09-18T01:31:07.590401Z","end":"2026-09-18T01:31:07.817895Z","steps":["trace[504885328] 'process raft request' (duration: 226.621357ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:31:07.818265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.517274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-18T01:31:07.818739Z","caller":"traceutil/trace.go:171","msg":"trace[1914595966] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5155; }","duration":"202.668957ms","start":"2026-09-18T01:31:07.615655Z","end":"2026-09-18T01:31:07.818324Z","steps":["trace[1914595966] 'agreement among raft nodes before linearized reading' (duration: 202.451813ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:31:08.216992Z","caller":"traceutil/trace.go:171","msg":"trace[1073304453] transaction","detail":"{read_only:false; response_revision:5156; number_of_response:1; }","duration":"392.69648ms","start":"2026-09-18T01:31:07.824271Z","end":"2026-09-18T01:31:08.216967Z","steps":["trace[1073304453] 'process raft request' (duration: 322.781629ms)","trace[1073304453] 'compare' (duration: 69.746507ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T01:31:08.217137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:31:07.824248Z","time spent":"392.808952ms","remote":"127.0.0.1:43336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T01:31:08.217125Z","caller":"traceutil/trace.go:171","msg":"trace[902740419] linearizableReadLoop","detail":"{readStateIndex:5355; appliedIndex:5353; }","duration":"387.507727ms","start":"2026-09-18T01:31:07.829593Z","end":"2026-09-18T01:31:08.217101Z","steps":["trace[902740419] 'read index received' (duration: 317.473644ms)","trace[902740419] 'applied index is now lower than readState.Index' (duration: 70.032873ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T01:31:08.21727Z","caller":"traceutil/trace.go:171","msg":"trace[754530031] transaction","detail":"{read_only:false; response_revision:5157; number_of_response:1; }","duration":"392.816932ms","start":"2026-09-18T01:31:07.824442Z","end":"2026-09-18T01:31:08.217259Z","steps":["trace[754530031] 'process raft request' (duration: 392.486425ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:31:08.217371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:31:07.824421Z","time spent":"392.882124ms","remote":"127.0.0.1:43336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T01:31:08.217815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.226863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10391"} {"level":"info","ts":"2026-09-18T01:31:08.217853Z","caller":"traceutil/trace.go:171","msg":"trace[1712299947] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5157; }","duration":"388.300415ms","start":"2026-09-18T01:31:07.829542Z","end":"2026-09-18T01:31:08.217842Z","steps":["trace[1712299947] 'agreement among raft nodes before linearized reading' (duration: 388.027028ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:31:08.217879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:31:07.8295Z","time spent":"388.373086ms","remote":"127.0.0.1:43292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":10414,"request content":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-09-18T01:31:08.217998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.209909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-09-18T01:31:08.21806Z","caller":"traceutil/trace.go:171","msg":"trace[1802442509] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5157; }","duration":"334.305601ms","start":"2026-09-18T01:31:07.883732Z","end":"2026-09-18T01:31:08.218037Z","steps":["trace[1802442509] 'agreement among raft nodes before linearized reading' (duration: 334.123477ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:31:08.21811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:31:07.883714Z","time spent":"334.383982ms","remote":"127.0.0.1:43256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-09-18T01:31:26.791049Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2808} {"level":"info","ts":"2026-09-18T01:31:26.87217Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2808,"took":"79.73844ms","hash":3918876505,"current-db-size-bytes":52006912,"current-db-size":"52 MB","current-db-size-in-use-bytes":27516928,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-18T01:31:26.872241Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3918876505,"revision":2808,"compact-revision":-1} {"level":"info","ts":"2026-09-18T01:31:56.19397Z","caller":"traceutil/trace.go:171","msg":"trace[1620606136] linearizableReadLoop","detail":"{readStateIndex:5794; appliedIndex:5793; }","duration":"108.930941ms","start":"2026-09-18T01:31:56.085018Z","end":"2026-09-18T01:31:56.193949Z","steps":["trace[1620606136] 'read index received' (duration: 108.739977ms)","trace[1620606136] 'applied index is now lower than readState.Index' (duration: 189.504µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T01:31:56.194084Z","caller":"traceutil/trace.go:171","msg":"trace[2011143596] transaction","detail":"{read_only:false; response_revision:5584; number_of_response:1; }","duration":"126.247921ms","start":"2026-09-18T01:31:56.067815Z","end":"2026-09-18T01:31:56.194063Z","steps":["trace[2011143596] 'process raft request' (duration: 126.003915ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:31:56.194339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.279019ms","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-09-18T01:31:56.194468Z","caller":"traceutil/trace.go:171","msg":"trace[1811532294] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5584; }","duration":"109.422883ms","start":"2026-09-18T01:31:56.084986Z","end":"2026-09-18T01:31:56.194409Z","steps":["trace[1811532294] 'agreement among raft nodes before linearized reading' (duration: 109.114696ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:31:56.318407Z","caller":"traceutil/trace.go:171","msg":"trace[844114067] transaction","detail":"{read_only:false; response_revision:5585; number_of_response:1; }","duration":"119.754739ms","start":"2026-09-18T01:31:56.19863Z","end":"2026-09-18T01:31:56.318385Z","steps":["trace[844114067] 'process raft request' (duration: 119.557005ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:31:57.03347Z","caller":"traceutil/trace.go:171","msg":"trace[1611744328] transaction","detail":"{read_only:false; response_revision:5588; number_of_response:1; }","duration":"167.379561ms","start":"2026-09-18T01:31:56.866072Z","end":"2026-09-18T01:31:57.033452Z","steps":["trace[1611744328] 'process raft request' (duration: 167.233877ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:31:57.368562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.267284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-18T01:31:57.368587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.764624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T01:31:57.368656Z","caller":"traceutil/trace.go:171","msg":"trace[2086019092] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5588; }","duration":"111.842796ms","start":"2026-09-18T01:31:57.256796Z","end":"2026-09-18T01:31:57.368639Z","steps":["trace[2086019092] 'range keys from in-memory index tree' (duration: 111.691353ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:31:57.368655Z","caller":"traceutil/trace.go:171","msg":"trace[1614224438] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5588; }","duration":"111.406057ms","start":"2026-09-18T01:31:57.257225Z","end":"2026-09-18T01:31:57.368631Z","steps":["trace[1614224438] 'range keys from in-memory index tree' (duration: 111.139191ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:31:57.368731Z","caller":"traceutil/trace.go:171","msg":"trace[2028882824] transaction","detail":"{read_only:false; response_revision:5589; number_of_response:1; }","duration":"111.035239ms","start":"2026-09-18T01:31:57.257642Z","end":"2026-09-18T01:31:57.368677Z","steps":["trace[2028882824] 'process raft request' (duration: 91.122403ms)","trace[2028882824] 'compare' (duration: 19.678181ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T01:35:00.835809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.480042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T01:35:00.835872Z","caller":"traceutil/trace.go:171","msg":"trace[1430355181] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6884; }","duration":"440.601615ms","start":"2026-09-18T01:35:00.395257Z","end":"2026-09-18T01:35:00.835858Z","steps":["trace[1430355181] 'range keys from in-memory index tree' (duration: 440.38162ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:35:00.835898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:35:00.395228Z","time spent":"440.664066ms","remote":"127.0.0.1:43112","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-09-18T01:35:00.836135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.50473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.76\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-09-18T01:35:00.836163Z","caller":"traceutil/trace.go:171","msg":"trace[222409912] range","detail":"{range_begin:/registry/masterleases/162.253.55.76; range_end:; response_count:1; response_revision:6884; }","duration":"481.553121ms","start":"2026-09-18T01:35:00.354601Z","end":"2026-09-18T01:35:00.836155Z","steps":["trace[222409912] 'range keys from in-memory index tree' (duration: 481.326046ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:35:00.836181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:35:00.354588Z","time spent":"481.588612ms","remote":"127.0.0.1:43140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/162.253.55.76\" "} {"level":"info","ts":"2026-09-18T01:35:00.860316Z","caller":"traceutil/trace.go:171","msg":"trace[1292385414] transaction","detail":"{read_only:false; response_revision:6885; number_of_response:1; }","duration":"331.606273ms","start":"2026-09-18T01:35:00.52869Z","end":"2026-09-18T01:35:00.860296Z","steps":["trace[1292385414] 'process raft request' (duration: 331.45421ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:35:00.860446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:35:00.528667Z","time spent":"331.699885ms","remote":"127.0.0.1:43336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T01:35:00.860708Z","caller":"traceutil/trace.go:171","msg":"trace[2113934958] linearizableReadLoop","detail":"{readStateIndex:7140; appliedIndex:7139; }","duration":"148.661058ms","start":"2026-09-18T01:35:00.712038Z","end":"2026-09-18T01:35:00.860699Z","steps":["trace[2113934958] 'read index received' (duration: 148.177877ms)","trace[2113934958] 'applied index is now lower than readState.Index' (duration: 482.381µs)"],"step_count":2} {"level":"warn","ts":"2026-09-18T01:35:00.860768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.733849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-18T01:35:00.860788Z","caller":"traceutil/trace.go:171","msg":"trace[1914281552] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:6885; }","duration":"148.77643ms","start":"2026-09-18T01:35:00.712006Z","end":"2026-09-18T01:35:00.860782Z","steps":["trace[1914281552] 'agreement among raft nodes before linearized reading' (duration: 148.739749ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:36:26.800584Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5307} {"level":"info","ts":"2026-09-18T01:36:26.871323Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5307,"took":"68.751215ms","hash":2755600206,"current-db-size-bytes":52006912,"current-db-size":"52 MB","current-db-size-in-use-bytes":18702336,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-18T01:36:26.871374Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2755600206,"revision":5307,"compact-revision":2808}