{"level":"warn","ts":"2026-07-01T00:46:59.170943Z","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-01T00:46:59.171073Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.195:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.195:2380","--initial-cluster=instance=https://199.19.213.195:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.195:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.195: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-01T00:46:59.17135Z","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-01T00:46:59.171382Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.195:2380"]} {"level":"info","ts":"2026-07-01T00:46:59.171491Z","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-01T00:46:59.172834Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.195:2379"]} {"level":"info","ts":"2026-07-01T00:46:59.172967Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.195:2380"],"listen-peer-urls":["https://199.19.213.195:2380"],"advertise-client-urls":["https://199.19.213.195:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.195:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.195: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-01T00:46:59.188117Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"14.773494ms"} {"level":"info","ts":"2026-07-01T00:46:59.205524Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2808ca5e53a916c4","cluster-id":"b7ef986abcd4d0b8"} {"level":"info","ts":"2026-07-01T00:46:59.205665Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2808ca5e53a916c4 switched to configuration voters=()"} {"level":"info","ts":"2026-07-01T00:46:59.205704Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2808ca5e53a916c4 became follower at term 0"} {"level":"info","ts":"2026-07-01T00:46:59.205725Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2808ca5e53a916c4 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-01T00:46:59.205743Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2808ca5e53a916c4 became follower at term 1"} {"level":"info","ts":"2026-07-01T00:46:59.205802Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2808ca5e53a916c4 switched to configuration voters=(2884778067810129604)"} {"level":"warn","ts":"2026-07-01T00:46:59.213948Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-01T00:46:59.216114Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-01T00:46:59.217433Z","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-01T00:46:59.220045Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2808ca5e53a916c4","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-01T00:46:59.220313Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2808ca5e53a916c4","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-01T00:46:59.220433Z","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-01T00:46:59.22057Z","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-01T00:46:59.220611Z","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-01T00:46:59.22105Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2808ca5e53a916c4 switched to configuration voters=(2884778067810129604)"} {"level":"info","ts":"2026-07-01T00:46:59.223029Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b7ef986abcd4d0b8","local-member-id":"2808ca5e53a916c4","added-peer-id":"2808ca5e53a916c4","added-peer-peer-urls":["https://199.19.213.195:2380"]} {"level":"info","ts":"2026-07-01T00:46:59.224849Z","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-01T00:46:59.225002Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.195:2380"} {"level":"info","ts":"2026-07-01T00:46:59.225027Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.195:2380"} {"level":"info","ts":"2026-07-01T00:46:59.225921Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2808ca5e53a916c4","initial-advertise-peer-urls":["https://199.19.213.195:2380"],"listen-peer-urls":["https://199.19.213.195:2380"],"advertise-client-urls":["https://199.19.213.195:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.195:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-01T00:46:59.225976Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-01T00:46:59.406108Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2808ca5e53a916c4 is starting a new election at term 1"} {"level":"info","ts":"2026-07-01T00:46:59.406241Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2808ca5e53a916c4 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-01T00:46:59.40631Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2808ca5e53a916c4 received MsgPreVoteResp from 2808ca5e53a916c4 at term 1"} {"level":"info","ts":"2026-07-01T00:46:59.406361Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2808ca5e53a916c4 became candidate at term 2"} {"level":"info","ts":"2026-07-01T00:46:59.4064Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2808ca5e53a916c4 received MsgVoteResp from 2808ca5e53a916c4 at term 2"} {"level":"info","ts":"2026-07-01T00:46:59.406447Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2808ca5e53a916c4 became leader at term 2"} {"level":"info","ts":"2026-07-01T00:46:59.406484Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2808ca5e53a916c4 elected leader 2808ca5e53a916c4 at term 2"} {"level":"info","ts":"2026-07-01T00:46:59.437864Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T00:46:59.443571Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2808ca5e53a916c4","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.195:2379]}","request-path":"/0/members/2808ca5e53a916c4/attributes","cluster-id":"b7ef986abcd4d0b8","publish-timeout":"7s"} {"level":"info","ts":"2026-07-01T00:46:59.443621Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-01T00:46:59.444051Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-01T00:46:59.444044Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-01T00:46:59.444078Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-01T00:46:59.447234Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-01T00:46:59.447457Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.195:2379"} {"level":"info","ts":"2026-07-01T00:46:59.504475Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b7ef986abcd4d0b8","local-member-id":"2808ca5e53a916c4","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T00:46:59.504736Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T00:46:59.5048Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T00:47:01.024204Z","caller":"traceutil/trace.go:171","msg":"trace[1185158981] transaction","detail":"{read_only:false; response_revision:30; number_of_response:1; }","duration":"101.515712ms","start":"2026-07-01T00:47:00.922663Z","end":"2026-07-01T00:47:01.024178Z","steps":["trace[1185158981] 'process raft request' (duration: 101.419499ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:47:01.024199Z","caller":"traceutil/trace.go:171","msg":"trace[1763175822] transaction","detail":"{read_only:false; response_revision:29; number_of_response:1; }","duration":"103.921981ms","start":"2026-07-01T00:47:00.920241Z","end":"2026-07-01T00:47:01.024163Z","steps":["trace[1763175822] 'process raft request' (duration: 96.638433ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:47:01.024416Z","caller":"traceutil/trace.go:171","msg":"trace[1397350926] transaction","detail":"{read_only:false; response_revision:31; number_of_response:1; }","duration":"100.244385ms","start":"2026-07-01T00:47:00.924158Z","end":"2026-07-01T00:47:01.024402Z","steps":["trace[1397350926] 'process raft request' (duration: 99.962977ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:47:01.024931Z","caller":"traceutil/trace.go:171","msg":"trace[726526370] linearizableReadLoop","detail":"{readStateIndex:35; appliedIndex:32; }","duration":"100.617936ms","start":"2026-07-01T00:47:00.9243Z","end":"2026-07-01T00:47:01.024918Z","steps":["trace[726526370] 'read index received' (duration: 92.631107ms)","trace[726526370] 'applied index is now lower than readState.Index' (duration: 7.986119ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:47:01.025207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.843962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-node-lease/\" range_end:\"/registry/resourcequotas/kube-node-lease0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-01T00:47:01.02534Z","caller":"traceutil/trace.go:171","msg":"trace[149848326] range","detail":"{range_begin:/registry/resourcequotas/kube-node-lease/; range_end:/registry/resourcequotas/kube-node-lease0; response_count:0; response_revision:36; }","duration":"101.018516ms","start":"2026-07-01T00:47:00.924283Z","end":"2026-07-01T00:47:01.025302Z","steps":["trace[149848326] 'agreement among raft nodes before linearized reading' (duration: 100.736779ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:47:04.980261Z","caller":"traceutil/trace.go:171","msg":"trace[143592330] linearizableReadLoop","detail":"{readStateIndex:270; appliedIndex:269; }","duration":"126.948516ms","start":"2026-07-01T00:47:04.853285Z","end":"2026-07-01T00:47:04.980233Z","steps":["trace[143592330] 'read index received' (duration: 96.642756ms)","trace[143592330] 'applied index is now lower than readState.Index' (duration: 30.30436ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:47:04.980391Z","caller":"traceutil/trace.go:171","msg":"trace[1859400167] transaction","detail":"{read_only:false; response_revision:261; number_of_response:1; }","duration":"172.401335ms","start":"2026-07-01T00:47:04.807938Z","end":"2026-07-01T00:47:04.980339Z","steps":["trace[1859400167] 'process raft request' (duration: 141.972882ms)","trace[1859400167] 'compare' (duration: 30.175646ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:47:04.980517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.206223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-07-01T00:47:04.98063Z","caller":"traceutil/trace.go:171","msg":"trace[1303452959] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:261; }","duration":"127.272195ms","start":"2026-07-01T00:47:04.853278Z","end":"2026-07-01T00:47:04.98055Z","steps":["trace[1303452959] 'agreement among raft nodes before linearized reading' (duration: 127.063909ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:47:05.480427Z","caller":"traceutil/trace.go:171","msg":"trace[810066516] transaction","detail":"{read_only:false; response_revision:263; number_of_response:1; }","duration":"494.429753ms","start":"2026-07-01T00:47:04.985968Z","end":"2026-07-01T00:47:05.480398Z","steps":["trace[810066516] 'process raft request' (duration: 494.138275ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:47:05.480721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.635867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-07-01T00:47:05.48078Z","caller":"traceutil/trace.go:171","msg":"trace[1394232452] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/disruption-controller; range_end:; response_count:1; response_revision:263; }","duration":"482.713989ms","start":"2026-07-01T00:47:04.998052Z","end":"2026-07-01T00:47:05.480766Z","steps":["trace[1394232452] 'agreement among raft nodes before linearized reading' (duration: 482.482673ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:47:05.480418Z","caller":"traceutil/trace.go:171","msg":"trace[2122891203] linearizableReadLoop","detail":"{readStateIndex:272; appliedIndex:271; }","duration":"482.322249ms","start":"2026-07-01T00:47:04.998059Z","end":"2026-07-01T00:47:05.480382Z","steps":["trace[2122891203] 'read index received' (duration: 482.129254ms)","trace[2122891203] 'applied index is now lower than readState.Index' (duration: 191.935µs)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:47:05.480823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:47:04.997996Z","time spent":"482.819042ms","remote":"127.0.0.1:48168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" "} {"level":"warn","ts":"2026-07-01T00:47:05.48131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:47:04.985944Z","time spent":"494.611409ms","remote":"127.0.0.1:48306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":435,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-01T00:47:05.678291Z","caller":"traceutil/trace.go:171","msg":"trace[1751814229] transaction","detail":"{read_only:false; number_of_response:0; response_revision:263; }","duration":"278.169731ms","start":"2026-07-01T00:47:05.400105Z","end":"2026-07-01T00:47:05.678274Z","steps":["trace[1751814229] 'process raft request' (duration: 278.088059ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:47:05.678285Z","caller":"traceutil/trace.go:171","msg":"trace[1418684712] linearizableReadLoop","detail":"{readStateIndex:276; appliedIndex:272; }","duration":"197.378128ms","start":"2026-07-01T00:47:05.480876Z","end":"2026-07-01T00:47:05.678254Z","steps":["trace[1418684712] 'read index received' (duration: 175.300053ms)","trace[1418684712] 'applied index is now lower than readState.Index' (duration: 22.076955ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:47:05.678308Z","caller":"traceutil/trace.go:171","msg":"trace[652741678] transaction","detail":"{read_only:false; number_of_response:0; response_revision:263; }","duration":"278.753698ms","start":"2026-07-01T00:47:05.399531Z","end":"2026-07-01T00:47:05.678284Z","steps":["trace[652741678] 'process raft request' (duration: 256.688913ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:47:05.67839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.83361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:5513"} {"level":"info","ts":"2026-07-01T00:47:05.678436Z","caller":"traceutil/trace.go:171","msg":"trace[2034132171] transaction","detail":"{read_only:false; number_of_response:0; response_revision:263; }","duration":"278.765508ms","start":"2026-07-01T00:47:05.399666Z","end":"2026-07-01T00:47:05.678431Z","steps":["trace[2034132171] 'process raft request' (duration: 278.4886ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:47:05.678444Z","caller":"traceutil/trace.go:171","msg":"trace[1639755612] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:263; }","duration":"278.934223ms","start":"2026-07-01T00:47:05.399496Z","end":"2026-07-01T00:47:05.678431Z","steps":["trace[1639755612] 'agreement among raft nodes before linearized reading' (duration: 278.806619ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:47:05.678446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.208469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T00:47:05.678484Z","caller":"traceutil/trace.go:171","msg":"trace[1147953694] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:263; }","duration":"437.307542ms","start":"2026-07-01T00:47:05.241165Z","end":"2026-07-01T00:47:05.678472Z","steps":["trace[1147953694] 'agreement among raft nodes before linearized reading' (duration: 437.22508ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:47:05.678531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:47:05.241138Z","time spent":"437.384224ms","remote":"127.0.0.1:48246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-01T00:47:05.67854Z","caller":"traceutil/trace.go:171","msg":"trace[760212990] transaction","detail":"{read_only:false; number_of_response:0; response_revision:263; }","duration":"278.262194ms","start":"2026-07-01T00:47:05.40027Z","end":"2026-07-01T00:47:05.678532Z","steps":["trace[760212990] 'process raft request' (duration: 277.950726ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:47:05.678615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.034619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-01T00:47:05.678662Z","caller":"traceutil/trace.go:171","msg":"trace[738682423] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:263; }","duration":"616.107832ms","start":"2026-07-01T00:47:05.062543Z","end":"2026-07-01T00:47:05.678651Z","steps":["trace[738682423] 'agreement among raft nodes before linearized reading' (duration: 616.03434ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:47:05.678699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:47:05.062529Z","time spent":"616.162753ms","remote":"127.0.0.1:48246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-01T00:47:05.678549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.433607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/expand-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T00:47:05.678799Z","caller":"traceutil/trace.go:171","msg":"trace[2048663569] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/expand-controller; range_end:; response_count:0; response_revision:263; }","duration":"194.745235ms","start":"2026-07-01T00:47:05.484042Z","end":"2026-07-01T00:47:05.678787Z","steps":["trace[2048663569] 'agreement among raft nodes before linearized reading' (duration: 194.445177ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:47:49.750464Z","caller":"traceutil/trace.go:171","msg":"trace[36083518] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"273.308172ms","start":"2026-07-01T00:47:49.477095Z","end":"2026-07-01T00:47:49.750403Z","steps":["trace[36083518] 'process raft request' (duration: 270.905988ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:47:49.750268Z","caller":"traceutil/trace.go:171","msg":"trace[706675253] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:634; }","duration":"247.17896ms","start":"2026-07-01T00:47:49.50277Z","end":"2026-07-01T00:47:49.749949Z","steps":["trace[706675253] 'read index received' (duration: 244.41373ms)","trace[706675253] 'applied index is now lower than readState.Index' (duration: 2.76309ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:47:49.753851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.696754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-v9q9j\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-01T00:47:49.753928Z","caller":"traceutil/trace.go:171","msg":"trace[2040192915] range","detail":"{range_begin:/registry/pods/kube-system/cilium-v9q9j; range_end:; response_count:1; response_revision:606; }","duration":"251.191553ms","start":"2026-07-01T00:47:49.502713Z","end":"2026-07-01T00:47:49.753904Z","steps":["trace[2040192915] 'agreement among raft nodes before linearized reading' (duration: 247.267761ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:47:50.250649Z","caller":"traceutil/trace.go:171","msg":"trace[1926408674] linearizableReadLoop","detail":"{readStateIndex:637; appliedIndex:636; }","duration":"313.612492ms","start":"2026-07-01T00:47:49.937011Z","end":"2026-07-01T00:47:50.250624Z","steps":["trace[1926408674] 'read index received' (duration: 313.293697ms)","trace[1926408674] 'applied index is now lower than readState.Index' (duration: 315.715µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:47:50.250877Z","caller":"traceutil/trace.go:171","msg":"trace[1429075344] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"432.482448ms","start":"2026-07-01T00:47:49.818263Z","end":"2026-07-01T00:47:50.250746Z","steps":["trace[1429075344] 'process raft request' (duration: 432.167802ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:47:50.251093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.848704ms","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-01T00:47:50.251154Z","caller":"traceutil/trace.go:171","msg":"trace[226837804] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:608; }","duration":"129.948325ms","start":"2026-07-01T00:47:50.12119Z","end":"2026-07-01T00:47:50.251138Z","steps":["trace[226837804] 'agreement among raft nodes before linearized reading' (duration: 129.726242ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:47:50.251163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.151093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-01T00:47:50.251173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:47:49.81824Z","time spent":"432.788642ms","remote":"127.0.0.1:48210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-01T00:47:50.251234Z","caller":"traceutil/trace.go:171","msg":"trace[298499897] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:608; }","duration":"314.252904ms","start":"2026-07-01T00:47:49.93696Z","end":"2026-07-01T00:47:50.251213Z","steps":["trace[298499897] 'agreement among raft nodes before linearized reading' (duration: 313.805526ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:47:50.251281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:47:49.936943Z","time spent":"314.325206ms","remote":"127.0.0.1:48246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-01T00:47:50.251438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.055652ms","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-01T00:47:50.251495Z","caller":"traceutil/trace.go:171","msg":"trace[1861037955] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:608; }","duration":"308.147304ms","start":"2026-07-01T00:47:49.943333Z","end":"2026-07-01T00:47:50.25148Z","steps":["trace[1861037955] 'agreement among raft nodes before linearized reading' (duration: 307.466572ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:47:50.251654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:47:49.943323Z","time spent":"308.316988ms","remote":"127.0.0.1:48246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-01T00:47:50.251751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.308564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-01T00:47:50.251995Z","caller":"traceutil/trace.go:171","msg":"trace[1924939474] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:608; }","duration":"304.826484ms","start":"2026-07-01T00:47:49.947146Z","end":"2026-07-01T00:47:50.251972Z","steps":["trace[1924939474] 'agreement among raft nodes before linearized reading' (duration: 303.774335ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:47:50.252149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:47:49.947085Z","time spent":"305.041808ms","remote":"127.0.0.1:48246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-01T00:47:53.807197Z","caller":"traceutil/trace.go:171","msg":"trace[257246784] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"101.927222ms","start":"2026-07-01T00:47:53.705241Z","end":"2026-07-01T00:47:53.807168Z","steps":["trace[257246784] 'process raft request' (duration: 101.777589ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:49:20.05114Z","caller":"traceutil/trace.go:171","msg":"trace[1709106346] transaction","detail":"{read_only:false; response_revision:1818; number_of_response:1; }","duration":"101.121056ms","start":"2026-07-01T00:49:19.949976Z","end":"2026-07-01T00:49:20.051097Z","steps":["trace[1709106346] 'process raft request' (duration: 100.871553ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:49:20.362063Z","caller":"traceutil/trace.go:171","msg":"trace[1736289886] transaction","detail":"{read_only:false; response_revision:1819; number_of_response:1; }","duration":"302.875277ms","start":"2026-07-01T00:49:20.059163Z","end":"2026-07-01T00:49:20.362038Z","steps":["trace[1736289886] 'process raft request' (duration: 239.655347ms)","trace[1736289886] 'compare' (duration: 63.065998ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:49:20.362332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:49:20.059148Z","time spent":"302.972908ms","remote":"127.0.0.1:48246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T00:49:26.10076Z","caller":"traceutil/trace.go:171","msg":"trace[2035168071] transaction","detail":"{read_only:false; response_revision:1894; number_of_response:1; }","duration":"103.202117ms","start":"2026-07-01T00:49:25.997533Z","end":"2026-07-01T00:49:26.100735Z","steps":["trace[2035168071] 'process raft request' (duration: 103.055595ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:49:26.236656Z","caller":"traceutil/trace.go:171","msg":"trace[1987584903] transaction","detail":"{read_only:false; response_revision:1896; number_of_response:1; }","duration":"129.832631ms","start":"2026-07-01T00:49:26.10679Z","end":"2026-07-01T00:49:26.236622Z","steps":["trace[1987584903] 'process raft request' (duration: 105.818104ms)","trace[1987584903] 'compare' (duration: 23.839195ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:49:26.237053Z","caller":"traceutil/trace.go:171","msg":"trace[1072384139] transaction","detail":"{read_only:false; response_revision:1897; number_of_response:1; }","duration":"130.227856ms","start":"2026-07-01T00:49:26.106814Z","end":"2026-07-01T00:49:26.237042Z","steps":["trace[1072384139] 'process raft request' (duration: 129.76761ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:49:26.237159Z","caller":"traceutil/trace.go:171","msg":"trace[398717213] transaction","detail":"{read_only:false; response_revision:1898; number_of_response:1; }","duration":"130.165875ms","start":"2026-07-01T00:49:26.106982Z","end":"2026-07-01T00:49:26.237147Z","steps":["trace[398717213] 'process raft request' (duration: 129.866391ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:49:57.560809Z","caller":"traceutil/trace.go:171","msg":"trace[1992358466] linearizableReadLoop","detail":"{readStateIndex:2203; appliedIndex:2202; }","duration":"203.478631ms","start":"2026-07-01T00:49:57.357309Z","end":"2026-07-01T00:49:57.560787Z","steps":["trace[1992358466] 'read index received' (duration: 203.38659ms)","trace[1992358466] 'applied index is now lower than readState.Index' (duration: 91.101µs)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:49:57.561026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.660622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T00:49:57.561346Z","caller":"traceutil/trace.go:171","msg":"trace[2102746884] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:2125; }","duration":"204.041806ms","start":"2026-07-01T00:49:57.357294Z","end":"2026-07-01T00:49:57.561335Z","steps":["trace[2102746884] 'agreement among raft nodes before linearized reading' (duration: 203.638882ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:49:57.560867Z","caller":"traceutil/trace.go:171","msg":"trace[480862542] transaction","detail":"{read_only:false; response_revision:2125; number_of_response:1; }","duration":"228.549739ms","start":"2026-07-01T00:49:57.332285Z","end":"2026-07-01T00:49:57.560834Z","steps":["trace[480862542] 'process raft request' (duration: 228.366768ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:49:58.054905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.135972ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T00:49:58.055283Z","caller":"traceutil/trace.go:171","msg":"trace[488632447] linearizableReadLoop","detail":"{readStateIndex:2206; appliedIndex:2204; }","duration":"157.006968ms","start":"2026-07-01T00:49:57.898258Z","end":"2026-07-01T00:49:58.055265Z","steps":["trace[488632447] 'read index received' (duration: 30.402562ms)","trace[488632447] 'applied index is now lower than readState.Index' (duration: 126.603476ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:49:58.055207Z","caller":"traceutil/trace.go:171","msg":"trace[1880545442] transaction","detail":"{read_only:false; response_revision:2127; number_of_response:1; }","duration":"419.223994ms","start":"2026-07-01T00:49:57.635944Z","end":"2026-07-01T00:49:58.055168Z","steps":["trace[1880545442] 'process raft request' (duration: 134.630178ms)","trace[1880545442] 'compare' (duration: 283.836028ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:49:58.055431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.17458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"warn","ts":"2026-07-01T00:49:58.055426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:49:57.635932Z","time spent":"419.417706ms","remote":"127.0.0.1:48246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T00:49:58.055502Z","caller":"traceutil/trace.go:171","msg":"trace[2024302163] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:2128; }","duration":"157.289651ms","start":"2026-07-01T00:49:57.8982Z","end":"2026-07-01T00:49:58.05549Z","steps":["trace[2024302163] 'agreement among raft nodes before linearized reading' (duration: 157.13102ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:49:58.055532Z","caller":"traceutil/trace.go:171","msg":"trace[1865291656] transaction","detail":"{read_only:false; response_revision:2128; number_of_response:1; }","duration":"210.016356ms","start":"2026-07-01T00:49:57.84539Z","end":"2026-07-01T00:49:58.055406Z","steps":["trace[1865291656] 'process raft request' (duration: 209.610522ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:51:14.576544Z","caller":"traceutil/trace.go:171","msg":"trace[1769841103] transaction","detail":"{read_only:false; response_revision:2753; number_of_response:1; }","duration":"108.229691ms","start":"2026-07-01T00:51:14.468288Z","end":"2026-07-01T00:51:14.576518Z","steps":["trace[1769841103] 'process raft request' (duration: 107.672574ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:51:20.490776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.347011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T00:51:20.490896Z","caller":"traceutil/trace.go:171","msg":"trace[1955774175] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:2790; }","duration":"164.544707ms","start":"2026-07-01T00:51:20.326325Z","end":"2026-07-01T00:51:20.490869Z","steps":["trace[1955774175] 'count revisions from in-memory index tree' (duration: 164.227768ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:51:20.491411Z","caller":"traceutil/trace.go:171","msg":"trace[1525266951] transaction","detail":"{read_only:false; response_revision:2791; number_of_response:1; }","duration":"143.091847ms","start":"2026-07-01T00:51:20.348293Z","end":"2026-07-01T00:51:20.491385Z","steps":["trace[1525266951] 'process raft request' (duration: 115.777324ms)","trace[1525266951] 'compare' (duration: 26.924292ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:51:20.741427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.311421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1296"} {"level":"info","ts":"2026-07-01T00:51:20.741544Z","caller":"traceutil/trace.go:171","msg":"trace[1259105941] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2791; }","duration":"134.499507ms","start":"2026-07-01T00:51:20.607017Z","end":"2026-07-01T00:51:20.741517Z","steps":["trace[1259105941] 'range keys from in-memory index tree' (duration: 134.060235ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:51:20.741644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.514429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-07-01T00:51:20.741733Z","caller":"traceutil/trace.go:171","msg":"trace[1617270728] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:2791; }","duration":"114.648843ms","start":"2026-07-01T00:51:20.627066Z","end":"2026-07-01T00:51:20.741715Z","steps":["trace[1617270728] 'range keys from in-memory index tree' (duration: 114.363093ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:53:48.054469Z","caller":"traceutil/trace.go:171","msg":"trace[523518076] linearizableReadLoop","detail":"{readStateIndex:4616; appliedIndex:4615; }","duration":"109.724279ms","start":"2026-07-01T00:53:47.944709Z","end":"2026-07-01T00:53:48.054433Z","steps":["trace[523518076] 'read index received' (duration: 62.784587ms)","trace[523518076] 'applied index is now lower than readState.Index' (duration: 46.938212ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:53:48.054642Z","caller":"traceutil/trace.go:171","msg":"trace[1910185413] transaction","detail":"{read_only:false; response_revision:4416; number_of_response:1; }","duration":"134.804453ms","start":"2026-07-01T00:53:47.919808Z","end":"2026-07-01T00:53:48.054613Z","steps":["trace[1910185413] 'process raft request' (duration: 87.655467ms)","trace[1910185413] 'compare' (duration: 46.75644ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:53:48.054843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.112616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-017096b0-f8f9-461b-b565-a63e2a8d49c6\" ","response":"range_response_count:1 size:4064"} {"level":"info","ts":"2026-07-01T00:53:48.054899Z","caller":"traceutil/trace.go:171","msg":"trace[1910102094] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-017096b0-f8f9-461b-b565-a63e2a8d49c6; range_end:; response_count:1; response_revision:4416; }","duration":"110.236538ms","start":"2026-07-01T00:53:47.944646Z","end":"2026-07-01T00:53:48.054882Z","steps":["trace[1910102094] 'agreement among raft nodes before linearized reading' (duration: 110.026254ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:53:52.999313Z","caller":"traceutil/trace.go:171","msg":"trace[1083944988] transaction","detail":"{read_only:false; response_revision:4518; number_of_response:1; }","duration":"104.928551ms","start":"2026-07-01T00:53:52.894355Z","end":"2026-07-01T00:53:52.999283Z","steps":["trace[1083944988] 'process raft request' (duration: 104.776128ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:53:56.822723Z","caller":"traceutil/trace.go:171","msg":"trace[52245689] linearizableReadLoop","detail":"{readStateIndex:4806; appliedIndex:4805; }","duration":"125.709998ms","start":"2026-07-01T00:53:56.696991Z","end":"2026-07-01T00:53:56.822701Z","steps":["trace[52245689] 'read index received' (duration: 125.542046ms)","trace[52245689] 'applied index is now lower than readState.Index' (duration: 166.822µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:53:56.823033Z","caller":"traceutil/trace.go:171","msg":"trace[1197928928] transaction","detail":"{read_only:false; response_revision:4600; number_of_response:1; }","duration":"194.273305ms","start":"2026-07-01T00:53:56.62875Z","end":"2026-07-01T00:53:56.823023Z","steps":["trace[1197928928] 'process raft request' (duration: 193.808978ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:56.823253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.248668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-07-01T00:53:56.823282Z","caller":"traceutil/trace.go:171","msg":"trace[1067218769] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:4600; }","duration":"126.316529ms","start":"2026-07-01T00:53:56.696956Z","end":"2026-07-01T00:53:56.823273Z","steps":["trace[1067218769] 'agreement among raft nodes before linearized reading' (duration: 126.212608ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:54:10.437894Z","caller":"traceutil/trace.go:171","msg":"trace[1662012235] transaction","detail":"{read_only:false; response_revision:4787; number_of_response:1; }","duration":"101.828362ms","start":"2026-07-01T00:54:10.3359Z","end":"2026-07-01T00:54:10.437729Z","steps":["trace[1662012235] 'process raft request' (duration: 101.248872ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:56:59.563976Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3135} {"level":"info","ts":"2026-07-01T00:56:59.673805Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3135,"took":"108.384253ms","hash":3430366011,"current-db-size-bytes":57614336,"current-db-size":"58 MB","current-db-size-in-use-bytes":31162368,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-01T00:56:59.6739Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3430366011,"revision":3135,"compact-revision":-1} {"level":"warn","ts":"2026-07-01T00:57:43.031378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.034198ms","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":"warn","ts":"2026-07-01T00:57:43.031505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.434595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T00:57:43.031517Z","caller":"traceutil/trace.go:171","msg":"trace[934467119] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:6661; }","duration":"363.21729ms","start":"2026-07-01T00:57:42.668256Z","end":"2026-07-01T00:57:43.031473Z","steps":["trace[934467119] 'count revisions from in-memory index tree' (duration: 362.944667ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:57:43.031532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.566288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-07-01T00:57:43.031657Z","caller":"traceutil/trace.go:171","msg":"trace[604526815] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:6661; }","duration":"161.74885ms","start":"2026-07-01T00:57:42.869879Z","end":"2026-07-01T00:57:43.031628Z","steps":["trace[604526815] 'range keys from in-memory index tree' (duration: 161.356166ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:57:43.031582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:57:42.668236Z","time spent":"363.328282ms","remote":"127.0.0.1:59730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-07-01T00:57:43.03181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.049332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T00:57:43.031974Z","caller":"traceutil/trace.go:171","msg":"trace[976907625] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6661; }","duration":"302.241085ms","start":"2026-07-01T00:57:42.729705Z","end":"2026-07-01T00:57:43.031946Z","steps":["trace[976907625] 'range keys from in-memory index tree' (duration: 301.992251ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:57:43.032058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:57:42.72969Z","time spent":"302.354856ms","remote":"127.0.0.1:38762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-01T00:57:43.032081Z","caller":"traceutil/trace.go:171","msg":"trace[530897681] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:6661; }","duration":"286.516945ms","start":"2026-07-01T00:57:42.745028Z","end":"2026-07-01T00:57:43.031545Z","steps":["trace[530897681] 'count revisions from in-memory index tree' (duration: 286.380134ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:57:43.050642Z","caller":"traceutil/trace.go:171","msg":"trace[1734623193] transaction","detail":"{read_only:false; response_revision:6662; number_of_response:1; }","duration":"139.917598ms","start":"2026-07-01T00:57:42.910702Z","end":"2026-07-01T00:57:43.05062Z","steps":["trace[1734623193] 'process raft request' (duration: 139.724105ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:57:43.050667Z","caller":"traceutil/trace.go:171","msg":"trace[1821500534] linearizableReadLoop","detail":"{readStateIndex:6968; appliedIndex:6968; }","duration":"109.880275ms","start":"2026-07-01T00:57:42.940766Z","end":"2026-07-01T00:57:43.050646Z","steps":["trace[1821500534] 'read index received' (duration: 109.869725ms)","trace[1821500534] 'applied index is now lower than readState.Index' (duration: 8.5µs)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:57:43.051048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.271289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-07-01T00:57:43.051129Z","caller":"traceutil/trace.go:171","msg":"trace[1241549957] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:6662; }","duration":"110.38066ms","start":"2026-07-01T00:57:42.940731Z","end":"2026-07-01T00:57:43.051112Z","steps":["trace[1241549957] 'agreement among raft nodes before linearized reading' (duration: 110.097527ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:57:43.150718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.226153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T00:57:43.150713Z","caller":"traceutil/trace.go:171","msg":"trace[2030813678] transaction","detail":"{read_only:false; response_revision:6663; number_of_response:1; }","duration":"114.062825ms","start":"2026-07-01T00:57:43.036524Z","end":"2026-07-01T00:57:43.150587Z","steps":["trace[2030813678] 'process raft request' (duration: 94.796942ms)","trace[2030813678] 'compare' (duration: 18.881928ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:57:43.150808Z","caller":"traceutil/trace.go:171","msg":"trace[706936020] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6663; }","duration":"117.389115ms","start":"2026-07-01T00:57:43.033396Z","end":"2026-07-01T00:57:43.150785Z","steps":["trace[706936020] 'agreement among raft nodes before linearized reading' (duration: 117.249564ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:57:55.908965Z","caller":"traceutil/trace.go:171","msg":"trace[1042519496] transaction","detail":"{read_only:false; response_revision:6804; number_of_response:1; }","duration":"101.406085ms","start":"2026-07-01T00:57:55.807535Z","end":"2026-07-01T00:57:55.908941Z","steps":["trace[1042519496] 'process raft request' (duration: 100.421773ms)"],"step_count":1}