Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 23.12.2022 13:40
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 14398264 kB
MemAvailable 30115444 kB
Buffers 0 kB
Cached 17320828 kB
SwapCached 3260 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 2022-12-23 13:37:22
DTS_END                         2022-12-23 13:37:22.6860
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/58134
TRN_ID                          210479
ATT_ID                          559
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: 8580.03 26052 1029603
customer order: creation 161.73 544 19408
customer order: refuse 14.17 504 1700
order to supplier: creation 43.62 1481 5234
order to supplier: removal 3.29 180931 395
invoice (draft): creation 43.19 3791 5183
invoice (draft): removal 47.40 1154 5688
invoice accept: apply 39.52 27284 4742
invoice accept: cancel 43.45 1454 5214
customer reserve: creation 3641.75 254 437010
customer reserve: removal 4265.83 110 511900
realization accept: apply 78.16 337 9379
realization accept: cancel 76.91 190 9229
payment from customer: creation 54.94 213 6593
payment from customer: removal 5.47 167 656
payment to supplier: creation 45.75 224 5490
payment to supplier: removal 3.12 145 374
service: total inventory turnovers 3.12 6130 374
service: total monetary turnovers 7.67 460 920
service: aggregate perf. data 0.91 20647 109
service: refresh index statistics 0.04 275027 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 23-12-2022 13:40:27 to 23-12-2022 13:40:29.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7557
TEST_TIME 2 7137
TEST_TIME 3 6028
TEST_TIME 4 6329
TEST_TIME 5 6551
TEST_TIME 6 6740
TEST_TIME 7 6708
TEST_TIME 8 6364
TEST_TIME 9 6524
TEST_TIME 10 6694
TEST_TIME 11 6545
TEST_TIME 12 6728
TEST_TIME 13 6920
TEST_TIME 14 6998
TEST_TIME 15 7035
TEST_TIME 16 7051
TEST_TIME 17 7328
TEST_TIME 18 7513
TEST_TIME 19 7486
TEST_TIME 20 7671
TEST_TIME 21 7737
TEST_TIME 22 7767
TEST_TIME 23 7920
TEST_TIME 24 8133
TEST_TIME 25 8318
TEST_TIME 26 8859
TEST_TIME 27 8873
TEST_TIME 28 9026
TEST_TIME 29 9187
TEST_TIME 30 9292
TEST_TIME 31 9463
TEST_TIME 32 9541
TEST_TIME 33 9563
TEST_TIME 34 9904
TEST_TIME 35 9961
TEST_TIME 36 9979
TEST_TIME 37 10254
TEST_TIME 38 10115
TEST_TIME 39 9935
TEST_TIME 40 9946
TEST_TIME 41 9964
TEST_TIME 42 9830
TEST_TIME 43 9710
TEST_TIME 44 9817
TEST_TIME 45 9919
TEST_TIME 46 9780
TEST_TIME 47 9908
TEST_TIME 48 9847
TEST_TIME 49 9779
TEST_TIME 50 9698
TEST_TIME 51 9709
TEST_TIME 52 9630
TEST_TIME 53 9591
TEST_TIME 54 9537
TEST_TIME 55 9468
TEST_TIME 56 9375
TEST_TIME 57 9292
TEST_TIME 58 9224
TEST_TIME 59 9162
TEST_TIME 60 9181
TEST_TIME 61 9106
TEST_TIME 62 9049
TEST_TIME 63 9051
TEST_TIME 64 9092
TEST_TIME 65 9006
TEST_TIME 66 9080
TEST_TIME 67 9101
TEST_TIME 68 9036
TEST_TIME 69 9016
TEST_TIME 70 8947
TEST_TIME 71 8909
TEST_TIME 72 8823
TEST_TIME 73 8776
TEST_TIME 74 8769
TEST_TIME 75 8752
TEST_TIME 76 8758
TEST_TIME 77 8708
TEST_TIME 78 8709
TEST_TIME 79 8687
TEST_TIME 80 8689
TEST_TIME 81 8680
TEST_TIME 82 8667
TEST_TIME 83 8641
TEST_TIME 84 8672
TEST_TIME 85 8760
TEST_TIME 86 8823
TEST_TIME 87 8945
TEST_TIME 88 8958
TEST_TIME 89 8884
TEST_TIME 90 8811
TEST_TIME 91 8797
TEST_TIME 92 8736
TEST_TIME 93 8752
TEST_TIME 94 8705
TEST_TIME 95 8673
TEST_TIME 96 8639
TEST_TIME 97 8625
TEST_TIME 98 8630
TEST_TIME 99 8629
TEST_TIME 100 8616
TEST_TIME 101 8649
TEST_TIME 102 8625
TEST_TIME 103 8590
TEST_TIME 104 8555
TEST_TIME 105 8563
TEST_TIME 106 8527
TEST_TIME 107 8640
TEST_TIME 108 8696
TEST_TIME 109 8877
TEST_TIME 110 8914
TEST_TIME 111 8873
TEST_TIME 112 8818
TEST_TIME 113 8770
TEST_TIME 114 8754
TEST_TIME 115 8745
TEST_TIME 116 8735
TEST_TIME 117 8722
TEST_TIME 118 8683
TEST_TIME 119 8657
TEST_TIME 120 8607

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 23-12-2022 13:40:31 to 23-12-2022 13:40:34.

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 1430769 1430769 0 0.00 0 22744 14 0 0
doc_list_biud 1430754 1430754 0 0.00 0 26188 47 0 0
sp_add_invoice_to_stock 4861 4742 119 2.44 76 204634 27285 0 119
sp_cancel_adding_invoice 5214 5214 0 0.00 7 96641 1455 0 0
sp_cancel_client_order 1700 1700 0 0.00 6 9486 505 0 0
sp_cancel_customer_reserve 511900 511900 0 0.00 1 26215 111 0 0
sp_cancel_pay_from_customer 656 656 0 0.00 4 5985 167 0 0
sp_cancel_pay_to_supplier 374 374 0 0.00 3 2435 146 0 0
sp_cancel_supplier_invoice 5693 5688 5 0.08 3 15449 1154 0 5
sp_cancel_supplier_order 395 395 0 0.00 9 1420319 180932 0 0
sp_cancel_write_off 9229 9229 0 0.00 2 12392 190 0 0
sp_client_order 19408 19408 0 0.00 5 34409 545 0 0
sp_customer_reserve 437010 437010 0 0.00 1 44312 255 0 0
sp_fill_shopping_cart 29813 29813 0 0.00 1 33174 111 0 0
sp_fill_shopping_cart_clo_res 436980 436980 0 0.00 0 22478 21 0 0
sp_get_clo_for_invoice 4697 4697 0 0.00 0 32163 412 0 0
sp_kill_cost_storno 15473 15473 0 0.00 0 12390 86 0 0
sp_kill_qstorno_ret_qs2qd 1039112 1039112 0 0.00 0 26071 49 0 0
sp_kill_qty_storno 548201 548201 0 0.00 0 26184 114 0 0
sp_lock_selected_doc 19578 19578 0 0.00 0 5789 7 0 0
sp_make_cost_storno 26159 26159 0 0.00 0 20684 100 0 0
sp_multiply_rows_for_pdistr 26157 26157 0 0.00 0 12965 47 0 0
sp_multiply_rows_for_qdistr 19407 19407 0 0.00 0 11681 180 0 0
sp_pay_from_customer 6593 6593 0 0.00 5 12990 213 0 0
sp_pay_to_supplier 5490 5490 0 0.00 7 20726 225 0 0
sp_payment_common 12082 12082 0 0.00 2 8626 54 0 0
sp_qd_handle_on_cancel_clo 1700 1700 0 0.00 1 9470 425 0 0
sp_qd_handle_on_invoice_upd_sts 9910 9910 0 0.00 0 24926 644 0 0
sp_qd_handle_on_reserve_upd_sts 9379 9379 0 0.00 0 14461 80 0 0
sp_reserve_write_off 9379 9379 0 0.00 8 15305 338 0 0
sp_supplier_invoice 5183 5183 0 0.00 9 74746 3792 0 0
sp_supplier_order 5234 5234 0 0.00 8 37763 1482 0 0
srv_aggregate_perf_data 110 109 1 0.90 2670 291677 20647 0 1
srv_fill_mon_cache_memory 72 72 0 0.00 719 115287 31837 0 0
srv_find_qd_qs_mism 982937 982937 0 0.00 0 22743 9 0 0
srv_make_invnt_saldo 374 374 0 0.00 35 205805 6130 0 0
srv_make_money_saldo 920 920 0 0.00 1 20968 461 0 0
srv_recalc_idx_stat 5 5 0 0.00 35042 535177 275027 0 0
t$perf-abend:sp_add_invoice_to_stock 119 119 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 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 4748 4748 0 0.00 1 17076 1184 0 0
t$perf-norm:sp_cancel_adding_invoice 405 405 0 0.00 2 7510 542 0 0
t$perf-norm:sp_cancel_client_order 1700 1700 0 0.00 0 3016 42 0 0
t$perf-norm:sp_cancel_customer_reserve 684 684 0 0.00 0 2287 36 0 0
t$perf-norm:sp_cancel_pay_from_customer 656 656 0 0.00 0 610 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 374 374 0 0.00 0 1989 27 0 0
t$perf-norm:sp_cancel_supplier_invoice 372 372 0 0.00 0 6505 69 0 0
t$perf-norm:sp_cancel_supplier_order 395 395 0 0.00 0 111038 7905 0 0
t$perf-norm:sp_cancel_write_off 920 920 0 0.00 0 821 20 0 0
t$perf-norm:sp_client_order 19409 19409 0 0.00 0 6467 45 0 0
t$perf-norm:sp_customer_reserve 988 988 0 0.00 0 2925 19 0 0
t$perf-norm:sp_pay_from_customer 6594 6594 0 0.00 0 5635 24 0 0
t$perf-norm:sp_pay_to_supplier 5490 5490 0 0.00 0 10319 27 0 0
t$perf-norm:sp_reserve_write_off 9379 9379 0 0.00 0 6190 31 0 0
t$perf-norm:sp_supplier_invoice 5183 5183 0 0.00 0 3168 40 0 0
t$perf-norm:sp_supplier_order 5234 5234 0 0.00 0 6536 42 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 2161 32 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 719 23 0 0
t$perf-norm:srv_make_invnt_saldo 374 374 0 0.00 0 1383 24 0 0
t$perf-norm:srv_make_money_saldo 920 920 0 0.00 0 1067 7 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 201 93 0 0
v_add_invoice_to_stock 9624 9386 238 2.47 0 5137 30 0 238
v_all_customers 35341 35341 0 0.00 0 137 5 0 0
v_all_suppliers 28724 28724 0 0.00 0 67 5 0 0
v_all_wares 38806 38806 0 0.00 0 136 5 0 0
v_cancel_adding_invoice 810 810 0 0.00 0 911 18 0 0
v_cancel_client_order 3400 3400 0 0.00 0 2324 11 0 0
v_cancel_customer_prepayment 1312 1312 0 0.00 0 604 13 0 0
v_cancel_customer_reserve 1368 1368 0 0.00 0 1840 13 0 0
v_cancel_payment_to_supplier 748 748 0 0.00 0 2347 16 0 0
v_cancel_supplier_invoice 754 744 10 1.32 0 1875 30 0 10
v_cancel_supplier_order 790 790 0 0.00 0 4618 19 0 0
v_cancel_write_off 1840 1840 0 0.00 0 4234 34 0 0
v_max_id_clo_ord 5229 5229 0 0.00 0 844 6 0 0
v_max_id_clo_res 988 988 0 0.00 0 2476 12 0 0
v_max_id_ord_sup 5178 5178 0 0.00 0 12486 14 0 0
v_max_non_paid_invoice 1534 1534 0 0.00 0 98 9 0 0
v_max_non_paid_realizn 3466 3466 0 0.00 0 7424 11 0 0
v_min_id_clo_ord 5229 5229 0 0.00 0 2787 9 0 0
v_min_id_clo_res 988 988 0 0.00 0 3828 15 0 0
v_min_id_ord_sup 5177 5177 0 0.00 0 7520 13 0 0
v_min_non_paid_invoice 5490 5490 0 0.00 0 5811 15 0 0
v_min_non_paid_realizn 6591 6591 0 0.00 0 8614 23 0 0
v_our_firm 9714 9714 0 0.00 0 133 6 0 0
v_reserve_write_off 18758 18758 0 0.00 0 5402 13 0 0
x_lock_depdocs_on_canc_invoice 10530 10530 0 0.00 0 12316 149 0 0
x_lock_depdocs_on_canc_sup_ord 395 395 0 0.00 0 3105 396 0 0
x_make_qstorno_1000_1200 5234 5234 0 0.00 2 37434 1266 0 0
x_make_qstorno_1200_2000 5183 5183 0 0.00 5 73984 3467 0 0
x_make_qstorno_2100_3300 437010 437010 0 0.00 0 44303 219 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-12-2022 13:40:35 to 23-12-2022 13:40:39.

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
11:07:26 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
11:08:27 19685 8192000000 9713183136 10009849856 793887456 8.834 100 88 176 0 822914320 7657504 21369360
11:09:52 21428 8192000000 9757210768 10061701120 828020512 9.179 100 92 184 0 862602480 12275280 22306688
11:11:39 14782 8192000000 9801511360 10120593408 870867552 9.609 100 84 165 0 911136800 21169728 19099520
11:12:59 7676 8192000000 9880647008 10211733504 921830272 10.114 100 87 174 0 988959440 47801392 19327776
11:14:08 5198 8192000000 9930833232 10271936512 958215936 10.472 100 83 166 0 1037915936 62214768 17485232
11:15:18 2133 8192000000 9939411360 10291154944 988130608 10.763 100 97 190 0 1059483856 52075216 19278032
11:16:23 1238 8192000000 10007174736 10365902848 1013418560 11.008 100 96 184 0 1124526384 93290256 17817568
11:17:26 968 8192000000 9996717008 10361454592 1035295440 11.219 100 93 179 0 1121548240 66612432 19640368
11:18:29 903 8192000000 10019477504 10393669632 1051007312 11.370 100 92 180 0 1139917344 70076096 18833936
11:19:32 1015 8192000000 10038902752 10416295936 1061627968 11.472 100 95 183 0 1159427664 80072944 17726752
11:20:35 1356 8192000000 10053643808 10430947328 1062882848 11.484 100 95 186 2 1171699664 89652448 19164368
11:21:38 1180 8192000000 10049916400 10428366848 1070803424 11.560 100 95 177 3 1174524672 85876624 17844624
11:22:42 1092 8192000000 10058255088 10443677696 1074765584 11.598 100 95 184 1 1179624576 86216176 18642816
11:23:44 1314 8192000000 10046313600 10435837952 1080132512 11.649 100 90 157 0 1173100736 75941232 17026992
11:24:46 841 8192000000 10045237824 10434351104 1082031760 11.667 100 80 157 2 1172165120 74606480 15526880
11:25:48 1135 8192000000 10079866032 10463252480 1091808144 11.760 100 98 195 0 1203491520 90778624 20904752
11:26:51 3221 8192000000 10082106160 10470952960 1094767776 11.788 100 92 175 2 1206762176 93018976 18975424
11:27:56 2701 8192000000 10059408672 10454384640 1091969728 11.761 100 63 123 0 1178629168 74601856 12057584
11:29:01 897 8192000000 10043108896 10436861952 1087626736 11.720 100 80 156 0 1166027392 62222304 16178352
11:30:03 997 8192000000 10042714256 10431078400 1089828640 11.741 100 91 181 3 1164450656 55790512 18831504
11:31:05 1126 8192000000 10043611952 10429407232 1081014448 11.657 100 94 172 4 1166617728 68126832 17476448
11:32:08 1232 8192000000 10030050816 10409140224 1064373504 11.498 100 94 182 0 1151594576 69496544 17724528
11:33:10 1494 8192000000 10017953680 10397192192 1061416016 11.470 100 96 183 0 1146138016 65233552 19488448
11:34:13 1250 8192000000 9979685936 10355204096 1037652544 11.242 99 88 172 1 1105950896 51243104 17055248
11:35:15 1124 8192000000 9974006512 10347716608 1011916112 10.994 98 93 181 0 1096234352 66050944 18267296
11:36:17 1261 8192000000 9988191968 10364977152 1040831056 11.273 100 84 156 0 1109145024 51411216 16902752
11:37:20 1148 8192000000 9987239904 10359316480 1041627792 11.280 100 96 177 2 1110681680 50445024 18608864
11:38:32 14406 8192000000 10038038528 10425552896 1049807344 11.359 99 69 135 0 1149962064 86588976 13565744
11:40:01 1743 8192000000 10025177968 10406608896 1078750656 11.636 100 81 149 3 1144621472 50204176 15666640
11:41:06 1193 8192000000 10083036752 10469949440 1089902912 11.742 100 93 181 1 1204779072 95316160 19560000
11:42:14 1530 8192000000 10101208880 10492653568 1099657232 11.834 100 93 178 1 1217652784 98123808 19871744
11:43:18 1196 8192000000 10111162480 10500071424 1102811648 11.864 100 95 179 1 1228912112 106571584 19528880
11:44:23 1130 8192000000 10110033568 10501525504 1104018544 11.876 99 87 165 1 1225616016 104723712 16873760
11:45:29 4340 8192000000 10165224880 10559619072 1118615552 12.014 100 78 150 1 1286710800 151752224 16343024
11:46:42 1661 8192000000 10181305712 10584641536 1130706608 12.128 100 88 169 1 1296419216 146642112 19070496
11:47:49 3774 8192000000 10181288496 10583629824 1140652080 12.222 100 76 141 0 1289454976 134112896 14690000
11:48:57 4436 8192000000 10244240592 10648662016 1153215424 12.340 100 74 142 0 1356725552 188805040 14705088
11:50:05 2023 8192000000 10270924080 10678751232 1166079376 12.460 100 97 191 1 1375798896 186664304 23055216
11:51:09 878 8192000000 10290575216 10695610368 1176145008 12.554 100 98 189 0 1401767872 204102096 21520768
11:52:12 957 8192000000 10312052528 10714644480 1172818496 12.523 100 91 170 1 1415679920 224004432 18856992
11:53:17 1869 8192000000 10342322096 10748788736 1170559520 12.502 100 91 173 0 1451095200 260764816 19770864
11:54:23 2685 8192000000 10345315936 10794209280 1181576640 12.605 100 86 166 1 1456947456 256375424 18995392
11:55:31 1567 8192000000 10369070720 10785669120 1189744624 12.681 100 94 181 0 1476967232 267404736 19817872
11:56:36 869 8192000000 10372977632 10786758656 1174927408 12.543 100 95 186 0 1478177312 282492048 20757856
11:57:40 1284 8192000000 10400186400 10823503872 1180701296 12.597 100 89 172 0 1505790192 304984256 20104640
11:58:44 1467 8192000000 10395251120 10806800384 1158339648 12.388 99 91 176 1 1507946528 327837440 21769440
11:59:50 1030 8192000000 10414955200 10856992768 1173928080 12.534 100 93 177 2 1523710672 328466976 21315616
12:00:54 711 8192000000 10440477360 10868961280 1166002000 12.459 100 99 193 0 1540745568 351587984 23155584
12:01:58 905 8192000000 10390247232 10858659840 1153631424 12.344 100 96 189 3 1492672672 316559888 22481360
12:03:02 1306 8192000000 10395741376 10868674560 1164152288 12.442 100 93 182 3 1500637728 314944928 21540512
12:04:09 1835 8192000000 10378187392 10788651008 1151634432 12.325 100 89 178 1 1482369408 310571904 20163072
12:05:16 7826 8192000000 10357262064 10809634816 1143967152 12.253 100 51 102 0 1465488800 308759664 12761984
12:06:25 26087 8192000000 10309643232 10781704192 1138342512 12.200 99 32 62 0 1438105616 291322000 8441104
12:07:55 34312 8192000000 10295733616 10772844544 1134373376 12.163 99 22 44 0 1417954656 278433200 5148080
12:09:38 30545 8192000000 10275266960 10702692352 1123602848 12.061 99 31 60 0 1378150416 247240720 7306848
12:11:10 12945 8192000000 10192367424 10710351872 1131641808 12.137 100 37 74 1 1322313072 181074608 9596656
12:12:27 6674 8192000000 10215365360 10633248768 1137722016 12.194 100 54 104 1 1307921568 157657440 12542112
12:13:38 858 8192000000 10190197616 10641936384 1149494960 12.305 100 82 150 2 1285192768 118899232 16798576
12:14:41 34288 8192000000 10230677616 10681991168 1165789744 12.457 100 56 110 0 1331938976 153082912 13066320
12:16:48 17744 8192000000 10267550544 10711826432 1158918208 12.393 100 29 58 0 1368918848 202637792 7362848
12:18:12 32917 8192000000 10209129232 10690441216 1125821952 12.082 98 19 38 0 1308204736 177847584 4535200
12:19:54 30770 8192000000 10255950688 10695528448 1138618384 12.203 100 32 62 1 1352097696 206262976 7216336
12:21:33 69588 8192000000 10148258544 10625204224 1130468960 12.126 99 18 36 1 1308325392 173003200 4853232
12:23:44 36791 8192000000 10089905248 10530136064 1139962544 12.215 100 34 66 1 1198646816 51459056 7225216
12:25:22 115286 8192000000 10090206320 10533191680 1124612112 12.071 99 27 53 0 1178809792 48425760 5771920
12:28:21 95197 8192000000 10095953552 10546929664 1118122960 12.009 97 27 52 0 1178300960 54520544 5657456
12:30:57 31746 8192000000 10095275440 10550665216 1130614272 12.127 99 26 44 0 1192619536 57074768 4930496
12:32:29 85720 8192000000 10117663024 10573864960 1126253376 12.086 98 18 36 0 1208618752 77827504 4537872
12:35:39 7915 8192000000 10169633360 10611806208 1144782080 12.260 100 43 86 0 1269417152 115454976 9180096
12:36:51 3638 8192000000 10177948816 10602835968 1157529904 12.380 100 69 131 3 1276099584 104251824 14317856
12:38:00 64582 8192000000 10220411200 10679656448 1156932096 12.375 99 17 34 0 1289736064 128609888 4194080
12:40:05 58869 8192000000 10225666832 10730004480 1163709760 12.438 100 16 32 0 1335795824 168484992 3601072
12:42:15 72320 8192000000 10139451088 10664448000 1132810944 12.148 98 25 48 0 1265042256 125296256 6935056
12:45:12 56679 8192000000 10108161136 10558496768 1148281152 12.293 99 37 74 0 1206078592 50641088 7156352
12:47:22 58232 8192000000 10102710912 10581483520 1134429824 12.163 99 12 24 0 1215023088 78295344 2297920
12:49:21 35440 8192000000 10104443216 10586697728 1150239168 12.312 100 14 29 0 1220291376 67135984 2916224
12:50:57 59809 8192000000 10067105824 10535022592 1134742208 12.166 99 11 19 0 1168744560 31651312 2351040
12:54:10 56552 8192000000 10110341024 10556346368 1125806544 12.082 98 31 62 0 1195951152 63321616 6822992
12:56:12 70469 8192000000 10126361120 10599350272 1122028848 12.046 97 29 57 0 1224751136 96562464 6159824
12:58:29 33248 8192000000 10176318576 10617528320 1137982144 12.197 98 33 66 0 1258990112 113715808 7292160
13:00:03 21694 8192000000 10188042480 10629476352 1132034672 12.141 98 35 70 1 1264978544 124438240 8505632
13:01:26 51128 8192000000 10194688064 10658570240 1133863168 12.158 99 22 42 1 1289832704 150527568 5441968
13:03:28 97938 8192000000 10073871536 10556583936 1098867856 11.827 96 19 38 0 1192695808 89609664 4218288
13:06:07 80795 8192000000 10032156656 10469376000 1079191872 11.640 96 30 61 0 1122666000 36044512 7429616
13:08:33 81561 8192000000 10087354048 10564055040 1131073728 12.131 100 6 12 0 1200886912 68805040 1008144
13:10:55 42834 8192000000 10065332192 10550079488 1136904768 12.186 100 8 16 0 1173194688 34784032 1505888
13:12:45 49276 8192000000 10051087840 10492940288 1117053808 11.999 97 24 48 0 1139647648 18044320 4549520
13:14:40 68091 8192000000 10098757664 10563940352 1104373168 11.879 96 15 31 0 1177505616 69762096 3370352
13:16:56 26458 8192000000 10158520608 10590076928 1139621504 12.212 99 35 69 0 1241342432 94321712 7399216
13:18:23 43901 8192000000 10178604864 10640834560 1150645136 12.316 99 30 60 0 1264519392 106963440 6910816
13:20:34 50643 8192000000 10231979248 10670198784 1144954560 12.262 99 24 48 0 1312862464 161569296 6338608
13:22:27 21647 8192000000 10307567296 10763870208 1164739984 12.448 100 39 77 0 1410654976 237722208 8192784
13:23:53 57504 8192000000 10252069328 10737229824 1142787168 12.242 100 23 46 0 1407560432 258771824 6001440
13:25:59 24226 8192000000 10129824800 10669752320 1117359024 12.002 98 27 52 0 1277256992 154310144 5587824
13:28:12 94847 8192000000 10063701488 10501378048 1125511392 12.079 99 38 76 1 1162913712 29416416 7985904
13:31:16 35537 8192000000 10097039072 10535940096 1117572896 12.004 98 39 74 0 1173075536 47990512 7512128
13:33:13 42457 8192000000 10089233264 10561744896 1145260304 12.265 100 14 29 0 1195845520 47089136 3496080
13:34:56 60696 8192000000 10080870768 10536976384 1138768352 12.204 99 7 14 0 1175954080 35396704 1789024
13:36:57 38838 8192000000 9496032112 9882169344 613121056 6.963 54 7 14 0 671507552 57277200 1109296

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 23-12-2022 13:40:41 to 23-12-2022 13:40:57.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 119 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 23-12-2022 13:40:58 to 23-12-2022 13:41:00.

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          210131
MON$OLDEST_ACTIVE               210707
MON$OLDEST_SNAPSHOT             210707
MON$NEXT_TRANSACTION            210707
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                       2111680
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             757
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2885 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2885 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2885 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2885 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 ---
:384: 
:385: mscs-test-161.budzdorov.ru    Fri Dec 23 13:37:47 2022
:386:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:387: 
:388: 
:389: mscs-test-161.budzdorov.ru    Fri Dec 23 13:37:47 2022
:390:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:391: 
:392: 
:393: mscs-test-161.budzdorov.ru    Fri Dec 23 13:37:47 2022
:394:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:395: 
:396: 
:397: mscs-test-161.budzdorov.ru    Fri Dec 23 13:37:47 2022
:398:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:399: 
--- end of diff output ---

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

23.12.22 13:41:02. 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
2022-12-23 13:41:04 No severe PSQL-related problems occured
23.12.22 13:41:05. End of report.