Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.01.2023 04:37
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 14955864 kB
MemAvailable 30322460 kB
Buffers 20 kB
Cached 16652304 kB
SwapCached 4488 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.3
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2023-01-28 04:37:24
DTS_END                         2023-01-28 04:37:24.7200
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/39788
TRN_ID                          208253
ATT_ID                          593
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 9004.22 18869 1080506
customer order: creation 162.90 470 19548
customer order: refuse 14.36 458 1723
order to supplier: creation 42.71 1411 5125
order to supplier: removal 3.24 175119 389
invoice (draft): creation 42.78 3297 5133
invoice (draft): removal 51.43 1089 6172
invoice accept: apply 39.39 27871 4727
invoice accept: cancel 46.08 1223 5529
customer reserve: creation 3752.45 260 450294
customer reserve: removal 4569.56 95 548347
realization accept: apply 78.96 297 9475
realization accept: cancel 80.42 179 9650
payment from customer: creation 53.75 180 6450
payment from customer: removal 5.58 130 669
payment to supplier: creation 45.58 182 5469
payment to supplier: removal 2.83 120 340
service: total inventory turnovers 3.95 3989 474
service: total monetary turnovers 7.27 434 872
service: aggregate perf. data 0.97 15084 116
service: refresh index statistics 0.03 145501 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 28-01-2023 04:38:14 to 28-01-2023 04:38:17.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6642
TEST_TIME 2 5344
TEST_TIME 3 5107
TEST_TIME 4 5386
TEST_TIME 5 5774
TEST_TIME 6 5800
TEST_TIME 7 5763
TEST_TIME 8 5979
TEST_TIME 9 6067
TEST_TIME 10 5933
TEST_TIME 11 6435
TEST_TIME 12 6591
TEST_TIME 13 6602
TEST_TIME 14 6691
TEST_TIME 15 6660
TEST_TIME 16 6792
TEST_TIME 17 6686
TEST_TIME 18 6801
TEST_TIME 19 6870
TEST_TIME 20 6945
TEST_TIME 21 6948
TEST_TIME 22 7034
TEST_TIME 23 7143
TEST_TIME 24 7669
TEST_TIME 25 7610
TEST_TIME 26 7895
TEST_TIME 27 8003
TEST_TIME 28 8149
TEST_TIME 29 8723
TEST_TIME 30 9216
TEST_TIME 31 9459
TEST_TIME 32 9503
TEST_TIME 33 9840
TEST_TIME 34 9920
TEST_TIME 35 10032
TEST_TIME 36 10091
TEST_TIME 37 10284
TEST_TIME 38 10510
TEST_TIME 39 10597
TEST_TIME 40 10442
TEST_TIME 41 10252
TEST_TIME 42 10188
TEST_TIME 43 9996
TEST_TIME 44 9895
TEST_TIME 45 10116
TEST_TIME 46 9979
TEST_TIME 47 9887
TEST_TIME 48 9928
TEST_TIME 49 9844
TEST_TIME 50 9823
TEST_TIME 51 9739
TEST_TIME 52 9674
TEST_TIME 53 9605
TEST_TIME 54 9490
TEST_TIME 55 9429
TEST_TIME 56 9480
TEST_TIME 57 9424
TEST_TIME 58 9408
TEST_TIME 59 9442
TEST_TIME 60 9405
TEST_TIME 61 9435
TEST_TIME 62 9365
TEST_TIME 63 9388
TEST_TIME 64 9383
TEST_TIME 65 9468
TEST_TIME 66 9552
TEST_TIME 67 9480
TEST_TIME 68 9554
TEST_TIME 69 9487
TEST_TIME 70 9486
TEST_TIME 71 9618
TEST_TIME 72 9543
TEST_TIME 73 9461
TEST_TIME 74 9403
TEST_TIME 75 9333
TEST_TIME 76 9249
TEST_TIME 77 9189
TEST_TIME 78 9214
TEST_TIME 79 9247
TEST_TIME 80 9202
TEST_TIME 81 9165
TEST_TIME 82 9093
TEST_TIME 83 9034
TEST_TIME 84 9092
TEST_TIME 85 9045
TEST_TIME 86 9132
TEST_TIME 87 9117
TEST_TIME 88 9098
TEST_TIME 89 9127
TEST_TIME 90 9143
TEST_TIME 91 9150
TEST_TIME 92 9114
TEST_TIME 93 9261
TEST_TIME 94 9217
TEST_TIME 95 9228
TEST_TIME 96 9254
TEST_TIME 97 9275
TEST_TIME 98 9239
TEST_TIME 99 9198
TEST_TIME 100 9145
TEST_TIME 101 9181
TEST_TIME 102 9106
TEST_TIME 103 9122
TEST_TIME 104 9121
TEST_TIME 105 9111
TEST_TIME 106 9091
TEST_TIME 107 9060
TEST_TIME 108 9010
TEST_TIME 109 8989
TEST_TIME 110 9024
TEST_TIME 111 9005
TEST_TIME 112 8985
TEST_TIME 113 8993
TEST_TIME 114 9009
TEST_TIME 115 9015
TEST_TIME 116 9026
TEST_TIME 117 9037
TEST_TIME 118 9031
TEST_TIME 119 9022
TEST_TIME 120 9028

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 28-01-2023 04:38:18 to 28-01-2023 04:38:22.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1494577 1494577 0 0.00 0 18832 13 0 0
doc_list_biud 1494571 1494571 0 0.00 0 89285 41 0 0
sp_add_invoice_to_stock 4850 4727 123 2.53 71 217441 27872 0 123
sp_cancel_adding_invoice 5529 5529 0 0.00 7 94342 1223 0 0
sp_cancel_client_order 1723 1723 0 0.00 5 15555 458 0 0
sp_cancel_customer_reserve 548347 548347 0 0.00 1 33609 96 0 0
sp_cancel_pay_from_customer 669 669 0 0.00 4 9783 131 0 0
sp_cancel_pay_to_supplier 340 340 0 0.00 4 1611 121 0 0
sp_cancel_supplier_invoice 6185 6172 13 0.21 3 89299 1089 0 13
sp_cancel_supplier_order 389 389 0 0.00 10 1422631 175119 0 0
sp_cancel_write_off 9650 9650 0 0.00 2 15285 179 0 0
sp_client_order 19548 19548 0 0.00 5 30878 470 0 0
sp_customer_reserve 450294 450294 0 0.00 0 134476 261 0 0
sp_fill_shopping_cart 29801 29801 0 0.00 1 16144 81 0 0
sp_fill_shopping_cart_clo_res 450259 450259 0 0.00 0 19662 17 0 0
sp_get_clo_for_invoice 4687 4687 0 0.00 0 23253 258 0 0
sp_kill_cost_storno 16188 16188 0 0.00 0 9773 77 0 0
sp_kill_qstorno_ret_qs2qd 1112905 1112905 0 0.00 0 89272 43 0 0
sp_kill_qty_storno 585970 585970 0 0.00 0 89281 98 0 0
sp_lock_selected_doc 19686 19686 0 0.00 0 6719 7 0 0
sp_make_cost_storno 26080 26080 0 0.00 0 15585 79 0 0
sp_multiply_rows_for_pdistr 26079 26079 0 0.00 0 6542 35 0 0
sp_multiply_rows_for_qdistr 19548 19548 0 0.00 0 15728 167 0 0
sp_pay_from_customer 6450 6450 0 0.00 6 36535 180 0 0
sp_pay_to_supplier 5469 5469 0 0.00 6 14880 183 0 0
sp_payment_common 11919 11919 0 0.00 2 5876 44 0 0
sp_qd_handle_on_cancel_clo 1723 1723 0 0.00 0 15537 386 0 0
sp_qd_handle_on_invoice_upd_sts 10214 10214 0 0.00 0 30104 534 0 0
sp_qd_handle_on_reserve_upd_sts 9475 9475 0 0.00 0 25994 77 0 0
sp_reserve_write_off 9475 9475 0 0.00 9 30662 297 0 0
sp_supplier_invoice 5133 5133 0 0.00 8 122413 3298 0 0
sp_supplier_order 5125 5125 0 0.00 7 29237 1412 0 0
srv_aggregate_perf_data 117 116 1 0.85 2460 145986 15084 0 1
srv_fill_mon_cache_memory 71 71 0 0.00 799 166654 34743 0 0
srv_find_qd_qs_mism 1033817 1033817 0 0.00 0 18831 11 0 0
srv_make_invnt_saldo 474 474 0 0.00 80 63669 3989 0 0
srv_make_money_saldo 872 872 0 0.00 1 19653 435 0 0
srv_recalc_idx_stat 4 4 0 0.00 65839 242645 145502 0 0
t$perf-abend:sp_add_invoice_to_stock 123 123 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4736 4736 0 0.00 0 15594 936 0 0
t$perf-norm:sp_cancel_adding_invoice 386 386 0 0.00 2 9107 472 0 0
t$perf-norm:sp_cancel_client_order 1723 1723 0 0.00 0 5314 31 0 0
t$perf-norm:sp_cancel_customer_reserve 647 647 0 0.00 0 943 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 669 669 0 0.00 0 736 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 340 340 0 0.00 0 1158 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 397 397 0 0.00 0 522 27 0 0
t$perf-norm:sp_cancel_supplier_order 389 389 0 0.00 0 104399 7290 0 0
t$perf-norm:sp_cancel_write_off 976 976 0 0.00 0 2657 22 0 0
t$perf-norm:sp_client_order 19548 19548 0 0.00 0 6132 37 0 0
t$perf-norm:sp_customer_reserve 971 971 0 0.00 0 1883 17 0 0
t$perf-norm:sp_pay_from_customer 6450 6450 0 0.00 0 16099 23 0 0
t$perf-norm:sp_pay_to_supplier 5469 5469 0 0.00 0 4190 22 0 0
t$perf-norm:sp_reserve_write_off 9475 9475 0 0.00 0 16697 22 0 0
t$perf-norm:sp_supplier_invoice 5133 5133 0 0.00 0 2526 30 0 0
t$perf-norm:sp_supplier_order 5125 5125 0 0.00 0 2924 30 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 1199 29 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 1234 35 0 0
t$perf-norm:srv_make_invnt_saldo 474 474 0 0.00 0 4817 31 0 0
t$perf-norm:srv_make_money_saldo 872 872 0 0.00 0 5924 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 24 192 91 0 0
v_add_invoice_to_stock 9613 9367 246 2.55 0 20932 29 0 246
v_all_customers 35808 35808 0 0.00 0 128 4 0 0
v_all_suppliers 28744 28744 0 0.00 0 154 4 0 0
v_all_wares 39096 39096 0 0.00 0 189 4 0 0
v_cancel_adding_invoice 772 772 0 0.00 0 1211 18 0 0
v_cancel_client_order 3446 3446 0 0.00 0 8221 12 0 0
v_cancel_customer_prepayment 1338 1338 0 0.00 0 1956 14 0 0
v_cancel_customer_reserve 1294 1294 0 0.00 1 14737 23 0 0
v_cancel_payment_to_supplier 680 680 0 0.00 0 830 12 0 0
v_cancel_supplier_invoice 820 794 26 3.17 0 30396 65 0 26
v_cancel_supplier_order 778 778 0 0.00 0 1956 11 0 0
v_cancel_write_off 1952 1952 0 0.00 0 1425 23 0 0
v_max_id_clo_ord 5123 5123 0 0.00 0 6015 6 0 0
v_max_id_clo_res 971 971 0 0.00 0 469 9 0 0
v_max_id_ord_sup 5129 5129 0 0.00 0 6014 7 0 0
v_max_non_paid_invoice 1349 1349 0 0.00 0 143 7 0 0
v_max_non_paid_realizn 3245 3245 0 0.00 0 5225 9 0 0
v_min_id_clo_ord 5123 5123 0 0.00 0 2123 8 0 0
v_min_id_clo_res 971 971 0 0.00 0 6799 22 0 0
v_min_id_ord_sup 5129 5129 0 0.00 0 5339 16 0 0
v_min_non_paid_invoice 5469 5469 0 0.00 0 4981 17 0 0
v_min_non_paid_realizn 6450 6450 0 0.00 0 5295 17 0 0
v_our_firm 9698 9698 0 0.00 0 65 4 0 0
v_reserve_write_off 18950 18950 0 0.00 0 30584 15 0 0
x_lock_depdocs_on_canc_invoice 11304 11304 0 0.00 0 29748 135 0 0
x_lock_depdocs_on_canc_sup_ord 389 389 0 0.00 0 29024 439 0 0
x_make_qstorno_1000_1200 5125 5125 0 0.00 3 28688 1252 0 0
x_make_qstorno_1200_2000 5133 5133 0 0.00 3 122191 3056 0 0
x_make_qstorno_2100_3300 450294 450294 0 0.00 0 134475 231 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-01-2023 04:38:23 to 28-01-2023 04:38:27.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
02:07:28 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
02:08:30 13565 8192000000 9715652912 10010292224 790560192 8.801 100 83 166 0 818784480 7350736 20873552
02:09:49 16765 8192000000 9750708224 10056163328 824957392 9.148 100 89 179 0 861612896 15193008 21462496
02:11:11 20047 8192000000 9783341936 10100772864 856568384 9.466 100 84 168 0 892522208 16082192 19871632
02:12:36 16566 8192000000 9843203712 10173820928 907003696 9.968 100 75 145 0 951539776 29136144 15399936
02:13:55 4639 8192000000 9918087152 10259070976 952213696 10.413 100 88 172 0 1031974480 61266672 18494112
02:15:03 4405 8192000000 9965613104 10317942784 991012544 10.791 100 90 177 2 1073790416 63798752 18979120
02:16:10 1366 8192000000 9985628704 10343907328 1013532464 11.010 100 92 178 4 1106623168 75438544 17652160
02:17:15 1526 8192000000 10011827312 10379292672 1031986512 11.188 100 92 180 0 1125044832 75421632 17636688
02:18:18 1477 8192000000 10006932608 10374529024 1044060480 11.304 100 83 165 0 1132842048 72180752 16600816
02:19:21 1136 8192000000 10042981872 10415239168 1064206624 11.497 100 97 179 2 1165692528 83172224 18313680
02:20:25 1178 8192000000 10014606608 10388393984 1066303648 11.517 100 93 184 2 1132666416 47687568 18675200
02:21:27 2694 8192000000 10038135776 10420260864 1068455264 11.537 100 87 167 3 1163837744 77111168 18271312
02:22:32 1531 8192000000 10051045648 10441084928 1074815792 11.598 100 78 150 2 1174064288 84504048 14744448
02:23:34 1091 8192000000 10051323136 10438594560 1086693168 11.711 100 96 185 1 1175596928 71593216 17310544
02:24:36 1009 8192000000 10042298320 10426839040 1086580880 11.710 100 97 178 1 1161881680 56454336 18846464
02:25:38 1084 8192000000 10062282992 10451288064 1092904064 11.770 100 87 179 1 1184961184 74071536 17985584
02:26:40 1224 8192000000 10079992288 10469240832 1093986720 11.781 100 95 183 0 1201944752 88357504 19600528
02:27:42 967 8192000000 10057172912 10447200256 1100663920 11.844 100 87 165 1 1176100048 58506656 16929472
02:28:44 1098 8192000000 10026020960 10411819008 1098052224 11.819 100 94 180 1 1145901808 29415632 18433952
02:29:47 1096 8192000000 10030787536 10416234496 1080210352 11.649 100 90 165 3 1154820544 56593680 18016512
02:30:48 1423 8192000000 10047359824 10435002368 1080941008 11.656 99 86 163 4 1170113744 72269824 16902912
02:31:51 955 8192000000 10030005408 10414936064 1074872960 11.599 100 90 168 1 1155640624 62807088 17960576
02:32:53 1016 8192000000 10011939408 10391027712 1065264064 11.507 100 95 182 0 1134309104 49563136 19481904
02:33:55 1708 8192000000 10007869504 10386984960 1047393088 11.336 100 79 149 2 1133744080 70290720 16060272
02:34:58 5958 8192000000 9985526432 10371575808 1033016176 11.197 100 73 133 0 1104790928 57760480 14014272
02:36:08 5474 8192000000 10037303984 10421805056 1057671760 11.434 100 74 132 1 1156928176 85365088 13891328
02:37:16 1127 8192000000 9990384944 10361876480 1043815344 11.301 99 93 166 3 1112029168 52329216 15884608
02:38:22 6881 8192000000 9996516448 10373476352 1053421488 11.393 99 74 119 1 1117523696 52005536 12096672
02:39:33 1861 8192000000 10017371520 10398629888 1064399104 11.499 99 81 156 1 1138397376 57651648 16346624
02:40:46 5203 8192000000 10070908112 10458193920 1086535248 11.710 100 72 141 2 1185051504 83763008 14753248
02:41:59 9139 8192000000 10080525152 10476134400 1077013920 11.619 99 71 138 1 1185652448 93805456 14833072
02:43:13 1132 8192000000 10116230864 10511773696 1105965152 11.894 100 77 154 4 1241692400 119171808 16555440
02:44:19 1393 8192000000 10130057600 10517262336 1115495600 11.984 100 93 174 2 1247332448 114033136 17803712
02:45:25 3243 8192000000 10191425152 10579906560 1122805408 12.053 100 91 171 0 1305710080 165357584 17547088
02:46:34 3687 8192000000 10181203536 10573004800 1132431296 12.144 100 85 157 4 1291644320 143135488 16077536
02:47:45 1604 8192000000 10209426224 10618122240 1142038192 12.235 100 85 154 4 1318492544 159810128 16644224
02:48:49 976 8192000000 10220139184 10629578752 1156573712 12.371 100 92 181 0 1330045504 152771344 20700448
02:49:55 3034 8192000000 10245499472 10637897728 1160600768 12.409 100 90 173 1 1356488592 177074032 18813792
02:51:02 1727 8192000000 10279211008 10679836672 1173769648 12.532 100 85 157 2 1390010672 199007184 17233840
02:52:07 3045 8192000000 10314239648 10724896768 1175013312 12.544 100 86 165 1 1428194016 233649488 19531216
02:53:18 3647 8192000000 10335799664 10757767168 1184185616 12.629 100 81 158 0 1444351520 241839936 18325968
02:54:26 4239 8192000000 10384048256 10801987584 1193415904 12.715 100 89 168 1 1496342208 283101088 19825216
02:55:34 1291 8192000000 10429695552 10839339008 1206957488 12.841 100 100 197 0 1532987392 303326432 22703472
02:56:39 2115 8192000000 10468474144 10888609792 1205467408 12.827 100 89 171 2 1577534784 351740992 20326384
02:57:45 3122 8192000000 10480261008 10908528640 1203984464 12.813 100 84 160 1 1585203936 361675248 19544224
02:58:51 1535 8192000000 10532330208 10954166272 1193377808 12.715 100 90 179 0 1631023536 416209232 21436496
02:59:57 790 8192000000 10538783776 10975907840 1205614208 12.828 100 99 196 0 1643320416 412640576 25065632
03:01:01 914 8192000000 10543984176 11017248768 1206591264 12.838 100 98 191 0 1649827824 420456944 22779616
03:02:06 9312 8192000000 10547175328 10995355648 1204665968 12.820 100 51 103 0 1658175344 439944624 13564752
03:03:18 7849 8192000000 10526394432 10989506560 1189905072 12.682 99 47 93 1 1646466336 444483056 12078208
03:04:29 20083 8192000000 10546476192 10995621888 1177194624 12.564 100 54 107 0 1654073072 462866560 14011888
03:06:06 6381 8192000000 10472403840 10964795392 1169146960 12.489 100 49 98 0 1587671760 405826864 12697936
03:07:33 28143 8192000000 10321593792 10798383104 1149823328 12.308 99 31 62 0 1441504944 284356752 7324864
03:09:03 21708 8192000000 10259764816 10753830912 1162059632 12.423 100 31 56 1 1394166320 225861728 6244960
03:10:30 7171 8192000000 10225006304 10677616640 1150341552 12.313 100 48 87 2 1329915504 169271120 10302832
03:11:38 29207 8192000000 10226501056 10724757504 1155886304 12.365 99 36 68 2 1357274944 193779856 7608784
03:13:09 14769 8192000000 10258080624 10708590592 1146305008 12.275 100 20 40 0 1359633936 208550944 4777984
03:14:49 52540 8192000000 10111606832 10585657344 1108320720 11.917 97 11 22 0 1216724624 105722128 2681776
03:16:43 15888 8192000000 10115032992 10552471552 1147957840 12.290 100 32 61 1 1229867424 75273504 6636080
03:18:02 109146 8192000000 10169673248 10652938240 1142264208 12.237 100 37 71 0 1251058224 101570880 7223136
03:20:58 82644 8192000000 10133769616 10566336512 1100354768 11.841 96 48 96 1 1199069696 88627264 10087664
03:23:31 53580 8192000000 10108475680 10527703040 1112448272 11.956 99 43 81 0 1176793232 55652736 8692224
03:25:32 20596 8192000000 10088928944 10557411328 1131771344 12.138 100 23 46 0 1193330944 56913776 4645824
03:26:56 35936 8192000000 10063412800 10542034944 1117275392 12.001 99 11 20 0 1189140032 69814672 2049968
03:28:36 166654 8192000000 10070694640 10528309248 1038294720 11.248 92 23 45 2 1135603264 92176560 5131984
03:32:31 39347 8192000000 10092057696 10564841472 1120242704 12.029 100 12 23 0 1215354896 92435488 2676704
03:34:16 23812 8192000000 10048952832 10490249216 1097686592 11.816 99 20 35 0 1152898016 52047184 3164240
03:35:40 41081 8192000000 10057376128 10518343680 1105018032 11.885 99 15 30 0 1153682608 45373232 3291344
03:37:23 24959 8192000000 10074255856 10511572992 1131289904 12.134 100 31 62 0 1187868128 50433568 6144656
03:38:50 33819 8192000000 10103411984 10555584512 1141586736 12.230 100 25 49 0 1203858240 57434880 4836624
03:40:25 34391 8192000000 10159922544 10628108288 1156592096 12.371 100 30 60 0 1251485568 88529472 6364000
03:42:06 49402 8192000000 10130416928 10594570240 1153977360 12.347 100 14 26 1 1233466288 76594528 2894400
03:44:03 31383 8192000000 10126633648 10563350528 1160175056 12.405 100 20 40 0 1228740912 64664352 3901504
03:45:40 87343 8192000000 10158128768 10589765632 1127742464 12.100 97 38 72 0 1190131856 54920784 7468608
03:48:08 57699 8192000000 10095016720 10618417152 1115524928 11.985 97 14 28 0 1201567024 83292800 2749296
03:50:17 140393 8192000000 10077134720 10525405184 1123915952 12.064 98 19 38 1 1168130960 40416112 3798896
03:53:41 5171 8192000000 10097194336 10550968320 1151051904 12.319 100 20 40 0 1208308192 53456832 3799456
03:54:48 52074 8192000000 10141971744 10616242176 1148272512 12.293 100 27 54 1 1265589408 111364640 5952256
03:56:40 70130 8192000000 10088460864 10543038464 1154488752 12.352 100 16 31 1 1210360896 53002192 2869952
03:58:54 57198 8192000000 10126829312 10586406912 1157075520 12.376 100 15 31 0 1229362320 68647792 3639008
04:00:52 49968 8192000000 10116416464 10652225536 1155298672 12.359 100 12 24 0 1226757136 68712864 2745600
04:02:43 71711 8192000000 10057821664 10534187008 1104914560 11.884 97 12 25 0 1184758848 77014816 2829472
04:05:00 53471 8192000000 10136455408 10601316352 1147138752 12.283 99 35 68 1 1209693344 55474560 7080032
04:06:55 57056 8192000000 10106916112 10568220672 1136544512 12.183 99 30 57 0 1193694544 51181520 5968512
04:09:18 19054 8192000000 10137551184 10567421952 1138111456 12.198 99 40 80 0 1224500816 78386944 8002416
04:11:48 43403 8192000000 10116581824 10559193088 1120033472 12.027 97 31 62 1 1176359216 49950096 6375648
04:13:44 68246 8192000000 10092522176 10545655808 1117504000 12.003 97 28 56 0 1178482832 55354880 5623952
04:15:53 143562 8192000000 10123491744 10614964224 1117290768 12.001 98 32 64 0 1225278992 101195920 6792304
04:19:36 49660 8192000000 10101779552 10563444736 1107117280 11.905 98 20 39 1 1171097424 60102384 3877760
04:21:29 62873 8192000000 10050117088 10520723456 1092569904 11.767 97 8 16 0 1155911568 61632000 1709664
04:23:34 44197 8192000000 10064362976 10521583616 1086884080 11.713 97 17 34 1 1129301296 38188240 4228976
04:25:29 84511 8192000000 10072364992 10514112512 1114166336 11.972 100 18 36 0 1166487472 48262000 4059136
04:28:08 49993 8192000000 10054990912 10491867136 1107003648 11.904 99 17 34 0 1142525536 32579152 2942736
04:30:00 16900 8192000000 10085832864 10531708928 1117156400 12.000 100 43 85 1 1192310144 66656912 8496832
04:31:18 40607 8192000000 10081243632 10520293376 1092194448 11.764 97 26 52 0 1158778400 61302096 5281856
04:33:07 39799 8192000000 10076750864 10533724160 1102412080 11.861 99 24 46 1 1188654560 81434496 4807984
04:34:51 21337 8192000000 10078536528 10504519680 1103524784 11.871 99 43 86 0 1157843920 45706864 8612272
04:36:18 59785 8192000000 10107459104 10606145536 1087688032 11.721 97 42 80 0 1192476592 96549232 8239328

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 28-01-2023 04:38:29 to 28-01-2023 04:38:45.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 123 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 28-01-2023 04:38:46 to 28-01-2023 04:38:48.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          204772
MON$OLDEST_ACTIVE               208374
MON$OLDEST_SNAPSHOT             208374
MON$NEXT_TRANSACTION            208374
MON$PAGE_BUFFERS                1000000
MON$SQL_DIALECT                 3
MON$SHUTDOWN_MODE               0
MON$SWEEP_INTERVAL              0
MON$READ_ONLY                   0
MON$FORCED_WRITES               1
MON$RESERVE_SPACE               1
MON$CREATION_DATE               2020-04-30 07:30:08.6030 GMT
MON$PAGES                       2062368
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             760
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2894 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2894 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2894 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2894 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:33: 
:34: mscs-test-161.budzdorov.ru    Sat Jan 28 04:37:27 2023
:35:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:36: 
:37: 
:38: mscs-test-161.budzdorov.ru    Sat Jan 28 04:37:35 2023
:39:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:40: 
:41: 
:42: mscs-test-161.budzdorov.ru    Sat Jan 28 04:37:35 2023
:43:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:44: 
--- end of diff output ---

Final processing ISQL logs in /var/tmp/logs-oltp40 according to config parameter 'remove_isql_logs'

28.01.23 04:38:49. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2023-01-28 04:38:52 No severe PSQL-related problems occured
28.01.23 04:38:54. End of report.