Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.10.2022 16:43
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 15422608 kB
MemAvailable 30490360 kB
Buffers 0 kB
Cached 16306776 kB
SwapCached 1948 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 3.0.11
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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-10-21 16:42:15
DTS_END                         2022-10-21 16:42:15.1860
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(57:17)
IP                              ::1/35128
TRN_ID                          242975
ATT_ID                          636
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: 9384.25 21769 1126110
customer order: creation 174.30 389 20916
customer order: refuse 14.41 329 1729
order to supplier: creation 46.70 1241 5604
order to supplier: removal 3.72 85388 446
invoice (draft): creation 47.58 3040 5710
invoice (draft): removal 54.82 670 6578
invoice accept: apply 42.29 16936 5075
invoice accept: cancel 48.85 659 5862
customer reserve: creation 3895.15 168 467418
customer reserve: removal 4763.63 52 571635
realization accept: apply 83.83 182 10060
realization accept: cancel 77.49 108 9299
payment from customer: creation 59.53 104 7144
payment from customer: removal 5.97 106 716
payment to supplier: creation 49.34 119 5921
payment to supplier: removal 3.58 78 430
service: total inventory turnovers 4.92 909 590
service: total monetary turnovers 7.22 26 866
service: aggregate perf. data 0.89 16468 107
service: refresh index statistics 0.03 308400 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-10-2022 16:43:55 to 21-10-2022 16:43:57.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3877
TEST_TIME 2 3236
TEST_TIME 3 2644
TEST_TIME 4 3981
TEST_TIME 5 4986
TEST_TIME 6 5324
TEST_TIME 7 5114
TEST_TIME 8 5490
TEST_TIME 9 5284
TEST_TIME 10 5459
TEST_TIME 11 5872
TEST_TIME 12 6460
TEST_TIME 13 6816
TEST_TIME 14 7182
TEST_TIME 15 7430
TEST_TIME 16 7382
TEST_TIME 17 7731
TEST_TIME 18 8593
TEST_TIME 19 8937
TEST_TIME 20 9176
TEST_TIME 21 9567
TEST_TIME 22 9404
TEST_TIME 23 9324
TEST_TIME 24 9564
TEST_TIME 25 9634
TEST_TIME 26 10102
TEST_TIME 27 10344
TEST_TIME 28 10340
TEST_TIME 29 10186
TEST_TIME 30 10340
TEST_TIME 31 10571
TEST_TIME 32 10474
TEST_TIME 33 10249
TEST_TIME 34 10063
TEST_TIME 35 10341
TEST_TIME 36 10147
TEST_TIME 37 10120
TEST_TIME 38 9978
TEST_TIME 39 9904
TEST_TIME 40 9893
TEST_TIME 41 9927
TEST_TIME 42 9856
TEST_TIME 43 9757
TEST_TIME 44 9735
TEST_TIME 45 9716
TEST_TIME 46 9710
TEST_TIME 47 9707
TEST_TIME 48 9608
TEST_TIME 49 9561
TEST_TIME 50 9542
TEST_TIME 51 9574
TEST_TIME 52 9515
TEST_TIME 53 9513
TEST_TIME 54 9574
TEST_TIME 55 9618
TEST_TIME 56 9607
TEST_TIME 57 9632
TEST_TIME 58 9799
TEST_TIME 59 9753
TEST_TIME 60 9706
TEST_TIME 61 9803
TEST_TIME 62 9827
TEST_TIME 63 9859
TEST_TIME 64 9924
TEST_TIME 65 9935
TEST_TIME 66 9822
TEST_TIME 67 9682
TEST_TIME 68 9761
TEST_TIME 69 9702
TEST_TIME 70 9666
TEST_TIME 71 9610
TEST_TIME 72 9579
TEST_TIME 73 9551
TEST_TIME 74 9501
TEST_TIME 75 9453
TEST_TIME 76 9461
TEST_TIME 77 9461
TEST_TIME 78 9407
TEST_TIME 79 9446
TEST_TIME 80 9586
TEST_TIME 81 9678
TEST_TIME 82 9736
TEST_TIME 83 9700
TEST_TIME 84 9695
TEST_TIME 85 9735
TEST_TIME 86 9747
TEST_TIME 87 9721
TEST_TIME 88 9625
TEST_TIME 89 9653
TEST_TIME 90 9595
TEST_TIME 91 9546
TEST_TIME 92 9486
TEST_TIME 93 9515
TEST_TIME 94 9504
TEST_TIME 95 9488
TEST_TIME 96 9483
TEST_TIME 97 9473
TEST_TIME 98 9503
TEST_TIME 99 9552
TEST_TIME 100 9613
TEST_TIME 101 9621
TEST_TIME 102 9595
TEST_TIME 103 9611
TEST_TIME 104 9586
TEST_TIME 105 9645
TEST_TIME 106 9674
TEST_TIME 107 9648
TEST_TIME 108 9718
TEST_TIME 109 9650
TEST_TIME 110 9585
TEST_TIME 111 9560
TEST_TIME 112 9519
TEST_TIME 113 9550
TEST_TIME 114 9538
TEST_TIME 115 9503
TEST_TIME 116 9475
TEST_TIME 117 9491
TEST_TIME 118 9469
TEST_TIME 119 9453
TEST_TIME 120 9388

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 21-10-2022 16:43:59 to 21-10-2022 16:44:02.

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 1560088 1560088 0 0.00 0 29889 6 0 0
doc_list_biud 1560087 1560087 0 0.00 0 61823 24 0 0
sp_add_invoice_to_stock 5199 5075 124 2.38 42 196113 16936 0 124
sp_cancel_adding_invoice 5862 5862 0 0.00 4 40746 659 0 0
sp_cancel_client_order 1729 1729 0 0.00 4 6774 329 0 0
sp_cancel_customer_reserve 571635 571635 0 0.00 0 61824 52 0 0
sp_cancel_pay_from_customer 716 716 0 0.00 3 9062 107 0 0
sp_cancel_pay_to_supplier 430 430 0 0.00 2 9561 78 0 0
sp_cancel_supplier_invoice 6582 6578 4 0.06 1 17028 670 0 4
sp_cancel_supplier_order 446 446 0 0.00 8 863144 85389 0 0
sp_cancel_write_off 9299 9299 0 0.00 1 13247 109 0 0
sp_client_order 20916 20916 0 0.00 3 34385 390 0 0
sp_customer_reserve 467418 467418 0 0.00 0 133484 169 0 0
sp_fill_shopping_cart 32221 32221 0 0.00 1 23298 37 0 0
sp_fill_shopping_cart_clo_res 467415 467415 0 0.00 0 24951 14 0 0
sp_get_clo_for_invoice 5071 5071 0 0.00 0 20115 296 0 0
sp_kill_cost_storno 16307 16307 0 0.00 0 13235 54 0 0
sp_kill_qstorno_ret_qs2qd 1159593 1159593 0 0.00 0 61821 28 0 0
sp_kill_qty_storno 610679 610679 0 0.00 0 61823 62 0 0
sp_lock_selected_doc 20923 20923 0 0.00 0 10037 6 0 0
sp_make_cost_storno 28196 28196 0 0.00 0 29881 52 0 0
sp_multiply_rows_for_pdistr 28195 28195 0 0.00 0 10405 25 0 0
sp_multiply_rows_for_qdistr 20916 20916 0 0.00 0 31363 200 0 0
sp_pay_from_customer 7144 7144 0 0.00 3 13188 104 0 0
sp_pay_to_supplier 5921 5921 0 0.00 4 14645 120 0 0
sp_payment_common 13065 13065 0 0.00 1 13186 31 0 0
sp_qd_handle_on_cancel_clo 1729 1729 0 0.00 0 6766 297 0 0
sp_qd_handle_on_invoice_upd_sts 10933 10933 0 0.00 0 26738 314 0 0
sp_qd_handle_on_reserve_upd_sts 10059 10059 0 0.00 0 30053 63 0 0
sp_reserve_write_off 10060 10060 0 0.00 6 30116 182 0 0
sp_supplier_invoice 5710 5710 0 0.00 6 91151 3041 0 0
sp_supplier_order 5604 5604 0 0.00 5 35012 1242 0 0
srv_aggregate_perf_data 107 107 0 0.00 2737 195100 16469 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 843 139760 44308 0 0
srv_find_qd_qs_mism 1078282 1078282 0 0.00 0 24118 5 0 0
srv_make_invnt_saldo 590 590 0 0.00 41 26629 910 0 0
srv_make_money_saldo 866 866 0 0.00 1 1585 27 0 0
srv_recalc_idx_stat 4 4 0 0.00 259046 358412 308401 0 0
t$perf-abend:sp_add_invoice_to_stock 124 124 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5075 5075 0 0.00 0 17933 171 0 0
t$perf-norm:sp_cancel_adding_invoice 378 378 0 0.00 0 4611 84 0 0
t$perf-norm:sp_cancel_client_order 1729 1729 0 0.00 0 3454 15 0 0
t$perf-norm:sp_cancel_customer_reserve 745 745 0 0.00 0 722 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 716 716 0 0.00 0 4645 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 430 430 0 0.00 0 1117 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 401 401 0 0.00 0 1682 17 0 0
t$perf-norm:sp_cancel_supplier_order 446 446 0 0.00 0 19753 1359 0 0
t$perf-norm:sp_cancel_write_off 948 948 0 0.00 0 3139 9 0 0
t$perf-norm:sp_client_order 20916 20916 0 0.00 0 7177 18 0 0
t$perf-norm:sp_customer_reserve 956 956 0 0.00 0 1734 5 0 0
t$perf-norm:sp_pay_from_customer 7144 7144 0 0.00 0 7176 10 0 0
t$perf-norm:sp_pay_to_supplier 5921 5921 0 0.00 0 6238 10 0 0
t$perf-norm:sp_reserve_write_off 10060 10060 0 0.00 0 5466 10 0 0
t$perf-norm:sp_supplier_invoice 5710 5710 0 0.00 0 24382 24 0 0
t$perf-norm:sp_supplier_order 5604 5604 0 0.00 0 4438 17 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 307 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 72 2 0 0
t$perf-norm:srv_make_invnt_saldo 590 590 0 0.00 0 1369 26 0 0
t$perf-norm:srv_make_money_saldo 866 866 0 0.00 0 1059 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10390 10142 248 2.38 0 5964 20 0 248
v_all_customers 37956 37956 0 0.00 0 16 1 0 0
v_all_suppliers 30894 30894 0 0.00 0 38 1 0 0
v_all_wares 41832 41832 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 756 756 0 0.00 0 250 6 0 0
v_cancel_client_order 3458 3458 0 0.00 0 3504 5 0 0
v_cancel_customer_prepayment 1432 1432 0 0.00 0 3214 8 0 0
v_cancel_customer_reserve 1490 1490 0 0.00 0 825 4 0 0
v_cancel_payment_to_supplier 860 860 0 0.00 0 955 6 0 0
v_cancel_supplier_invoice 810 802 8 0.98 0 993 16 0 8
v_cancel_supplier_order 892 892 0 0.00 0 6313 17 0 0
v_cancel_write_off 1896 1896 0 0.00 0 2116 13 0 0
v_max_id_clo_ord 5602 5602 0 0.00 0 168 1 0 0
v_max_id_clo_res 956 956 0 0.00 0 2182 14 0 0
v_max_id_ord_sup 5702 5702 0 0.00 0 5614 4 0 0
v_max_non_paid_invoice 1778 1778 0 0.00 0 1760 3 0 0
v_max_non_paid_realizn 3911 3911 0 0.00 0 5653 6 0 0
v_min_id_clo_ord 5602 5602 0 0.00 0 14228 15 0 0
v_min_id_clo_res 956 956 0 0.00 0 2275 8 0 0
v_min_id_ord_sup 5702 5702 0 0.00 0 4485 4 0 0
v_min_non_paid_invoice 5921 5921 0 0.00 0 13008 20 0 0
v_min_non_paid_realizn 7144 7144 0 0.00 0 11736 22 0 0
v_our_firm 10342 10342 0 0.00 0 20 1 0 0
v_reserve_write_off 20118 20118 0 0.00 0 4725 5 0 0
x_lock_depdocs_on_canc_invoice 12039 12039 0 0.00 0 23796 107 0 0
x_lock_depdocs_on_canc_sup_ord 446 446 0 0.00 0 22358 419 0 0
x_make_qstorno_1000_1200 5604 5604 0 0.00 1 34995 1151 0 0
x_make_qstorno_1200_2000 5710 5710 0 0.00 3 80776 2926 0 0
x_make_qstorno_2100_3300 467418 467418 0 0.00 0 133481 152 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-10-2022 16:44:03 to 21-10-2022 16:44:07.

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
14:12:19 4 8192000000 8801705552 8813907968 7731408 0.094 2 2 3 0 7872512 66144 74960
14:13:19 19077 8192000000 9897819088 10208165888 980322528 10.687 100 84 168 0 1009017424 6277968 22416928
14:14:42 27554 8192000000 9952002608 10276155392 1032201680 11.190 100 87 174 0 1063133952 8412048 22520224
14:16:16 15160 8192000000 9977761568 10309709824 1063184464 11.487 100 84 168 0 1094307072 10107744 21014864
14:17:40 8482 8192000000 10035554448 10380525568 1122594128 12.051 100 85 168 0 1153969296 11113120 20262048
14:18:53 5468 8192000000 10085959024 10444877824 1170875296 12.505 100 80 160 0 1202618464 13587664 18155504
14:20:02 2592 8192000000 10136010544 10500993024 1211964400 12.887 100 80 160 0 1242786512 13222032 17600080
14:21:06 1786 8192000000 10169139008 10542272512 1251304848 13.250 100 90 176 0 1282938832 12655808 18978176
14:22:10 2109 8192000000 10200293152 10580004864 1285294960 13.561 100 86 172 2 1317418448 12740928 19382560
14:23:13 609 8192000000 10224217872 10609725440 1307418032 13.763 100 92 181 2 1342602944 15165584 20019328
14:24:15 1196 8192000000 10219786848 10614939648 1309833840 13.785 100 73 144 2 1336501232 10038848 16628544
14:25:17 5164 8192000000 10199669840 10614661120 1315526240 13.836 100 16 32 0 1324897808 5637152 3734416
14:26:24 9869 8192000000 10215885184 10633150464 1327801760 13.947 100 25 45 1 1339737584 6763088 5172736
14:27:35 17074 8192000000 10217293872 10637119488 1332468896 13.989 100 14 28 1 1341289696 5324656 3496144
14:28:55 59221 8192000000 10238084192 10650087424 1338955744 14.048 100 43 86 0 1355222336 6031232 10235360
14:30:55 6499 8192000000 10223221520 10650865664 1338006896 14.039 100 22 44 0 1349425712 6083456 5335360
14:32:01 3480 8192000000 10215395696 10629152768 1308565552 13.773 99 31 62 0 1323070592 7538272 6966768
14:33:05 13772 8192000000 10174758160 10586476544 1228994416 13.045 94 15 32 0 1237297664 5307616 2995632
14:34:20 24749 8192000000 10175007088 10575572992 1258994352 13.321 98 52 101 3 1275980336 5972896 11013088
14:35:48 2707 8192000000 10156170384 10551070720 1254783488 13.282 100 36 71 0 1269956144 7088480 8084176
14:36:51 10460 8192000000 10147089376 10548535296 1231754816 13.070 98 20 41 0 1241822944 5545008 4523120
14:38:04 17795 8192000000 10178479120 10587340800 1275131168 13.469 99 19 39 0 1285015824 5708016 4176640
14:39:26 28510 8192000000 10183055520 10600460288 1292466528 13.627 100 19 38 1 1301603488 5515824 3621136
14:40:56 8231 8192000000 10196663008 10611961856 1307830080 13.766 100 18 36 0 1317440112 5193344 4416688
14:42:06 7527 8192000000 10211306816 10628554752 1318252576 13.861 100 11 23 0 1325438992 4872432 2313984
14:43:46 16450 8192000000 10254775296 10664722432 1346991584 14.120 100 46 92 0 1365853024 8459568 10401872
14:45:11 81747 8192000000 10270682752 10697687040 1365719088 14.289 100 31 62 0 1385428800 12553232 7156480
14:47:35 9948 8192000000 10283806064 10701443072 1370534368 14.332 100 54 108 0 1395745088 12922528 12288192
14:48:49 6294 8192000000 10295460080 10715262976 1382125616 14.436 100 52 104 0 1408168688 13953952 12089120
14:49:59 22303 8192000000 10294296016 10716930048 1380688960 14.423 100 43 86 1 1407403360 16180768 10533632
14:51:24 14347 8192000000 10306211216 10727546880 1385057840 14.462 100 42 83 2 1415318096 20093824 10166432
14:52:42 11718 8192000000 10320756592 10744958976 1396495776 14.564 100 43 80 0 1428740448 22128944 10115728
14:53:58 942 8192000000 10355341152 10768748544 1409938000 14.683 100 88 176 1 1460501920 29261424 21302496
14:55:05 1931 8192000000 10350840656 10772082688 1413526208 14.715 100 73 136 0 1457644576 27180112 16938256
14:56:11 1228 8192000000 10324060240 10727391232 1373731392 14.360 100 85 168 1 1426550128 31882736 20936000
14:57:15 839 8192000000 10326139600 10729218048 1371935888 14.344 100 87 175 1 1424273936 31850112 20487936
14:58:20 10306 8192000000 10304586000 10712330240 1340295600 14.060 98 66 130 1 1389990064 33792704 15901760
14:59:34 1346 8192000000 10315186960 10715480064 1351177296 14.158 99 82 163 1 1411271504 39571024 20523184
15:00:42 17714 8192000000 10300190992 10727878656 1338160880 14.041 98 35 70 1 1386727392 38970640 9595872
15:02:02 8413 8192000000 10283225072 10703351808 1342537488 14.080 99 35 70 0 1385813248 33867040 9408720
15:03:38 4127 8192000000 10287540736 10712502272 1358219040 14.221 100 32 64 0 1392049104 25348880 8481184
15:04:52 12229 8192000000 10305737200 10733961216 1372820848 14.352 100 27 52 1 1409647680 29796928 7029904
15:06:11 14397 8192000000 10301845168 10741420032 1371180480 14.338 100 25 50 0 1411787120 33674272 6932368
15:07:54 62276 8192000000 10287709472 10738094080 1351454176 14.161 99 17 34 0 1396366240 39823824 5088240
15:09:57 21856 8192000000 10292099248 10730369024 1375274576 14.374 100 17 34 0 1394621456 14163968 5182912
15:11:23 69993 8192000000 10277510160 10710605824 1322835408 13.902 96 16 33 0 1345347760 18638048 3874304
15:13:44 71323 8192000000 10277049088 10711928832 1348014992 14.130 98 15 30 0 1364438192 13084368 3338832
15:15:56 67963 8192000000 10313411840 10753990656 1386054496 14.471 100 39 77 0 1407344688 11971904 9318288
15:18:05 65167 8192000000 10284131920 10742194176 1349383472 14.142 97 9 18 0 1367704528 16476608 1844448
15:20:24 10998 8192000000 10278268192 10713755648 1365133600 14.283 99 52 102 1 1384542704 7589696 11819408
15:21:42 23179 8192000000 10298647616 10750070784 1386313776 14.473 100 28 56 0 1404313904 12111360 5888768
15:23:20 12325 8192000000 10287258432 10754887680 1390182672 14.507 100 13 26 0 1404995904 10960928 3852304
15:24:36 139742 8192000000 10296963536 10762985472 1391061392 14.515 100 16 32 0 1408499440 13939760 3498288
15:28:20 109707 8192000000 10293618400 10764005376 1402667024 14.619 100 18 36 0 1421537056 14603712 4266320
15:31:14 66858 8192000000 10303988224 10786271232 1406770400 14.655 100 12 24 0 1430346080 20399904 3175776
15:33:22 44269 8192000000 10303467888 10772357120 1403135536 14.623 100 6 12 0 1423107024 18312656 1658832
15:35:13 60834 8192000000 10305735488 10769432576 1396662048 14.565 99 20 40 0 1412308080 10942256 4703776
15:37:22 48318 8192000000 10310782480 10764423168 1385060256 14.462 99 26 52 0 1399482816 8840304 5582256
15:39:11 65955 8192000000 10309326864 10770296832 1359371488 14.232 97 25 50 1 1386014192 20294336 6348368
15:41:31 79383 8192000000 10301864640 10756198400 1344525968 14.098 96 12 22 1 1358857712 11547968 2783776
15:43:51 17511 8192000000 10303486368 10748690432 1381153968 14.427 99 33 65 0 1394883984 7241248 6488768
15:45:09 98368 8192000000 10281375200 10728894464 1306406064 13.753 95 6 12 0 1324779856 16784528 1589264
15:47:51 76242 8192000000 10274737456 10719047680 1336313984 14.024 98 20 40 1 1352256096 11103568 4838544
15:50:29 58428 8192000000 10251529792 10713821184 1348741280 14.136 99 14 28 1 1365261616 13499360 3020976
15:52:29 45470 8192000000 10258080384 10717102080 1353389456 14.178 98 19 38 0 1364476960 6802464 4285040
15:54:18 44421 8192000000 10261468144 10720567296 1348341168 14.133 98 14 29 0 1360075104 8058640 3675296
15:56:03 40072 8192000000 10259611408 10715414528 1360588256 14.243 99 18 36 2 1376878000 11767520 4522224
15:57:50 46159 8192000000 10270256944 10719895552 1332390976 13.989 97 19 38 0 1351610160 14501136 4718048
15:59:37 42458 8192000000 10284878688 10732658688 1356601680 14.207 99 22 44 0 1388541456 26251136 5688640
16:01:25 75009 8192000000 10294605104 10745712640 1347177360 14.122 98 19 38 1 1380053008 28052608 4823040
16:03:49 36769 8192000000 10272353232 10726305792 1341622848 14.072 98 16 32 0 1362545760 16824832 4098080
16:05:35 26859 8192000000 10269170176 10708844544 1349590448 14.144 99 40 80 0 1368556000 10000832 8964720
16:07:05 22506 8192000000 10272800048 10718703616 1348761200 14.136 99 37 74 0 1368640272 11717120 8161952
16:08:29 23104 8192000000 10277398912 10716995584 1357866000 14.218 100 28 56 0 1378443872 14595504 5982368
16:09:52 116815 8192000000 10271410400 10737721344 1362331296 14.258 99 31 62 0 1381263872 12062032 6870544
16:12:59 46334 8192000000 10265700784 10729721856 1359578384 14.234 99 12 24 0 1375431568 13773056 2080128
16:14:46 71968 8192000000 10280761936 10754973696 1364881632 14.281 99 6 13 0 1380844752 15234432 728688
16:16:59 54448 8192000000 10281194960 10734854144 1346460608 14.116 97 15 30 0 1362585168 12871312 3253248
16:19:01 35249 8192000000 10300993280 10752593920 1389152096 14.498 100 26 52 0 1404152992 8970288 6030608
16:20:48 93473 8192000000 10312079504 10780643328 1352847216 14.173 97 10 20 0 1371699552 16512864 2339472
16:23:40 90397 8192000000 10288062192 10738159616 1335231040 14.014 96 23 44 0 1348274016 8456752 4586224
16:26:12 24574 8192000000 10281349760 10730684416 1339372592 14.052 97 22 44 0 1356100448 12144032 4583824
16:27:46 56880 8192000000 10294763616 10750951424 1361987536 14.255 98 15 30 0 1378761808 12672368 4101904
16:29:46 27263 8192000000 10304448064 10745901056 1365137680 14.283 99 47 94 3 1384106048 8789968 10178400
16:31:21 67566 8192000000 10278136848 10725912576 1363504672 14.269 99 14 28 0 1379408016 12280624 3622720
16:33:29 60926 8192000000 10254419344 10707566592 1333299840 13.997 98 19 37 0 1349336240 12292480 3743920
16:35:30 41884 8192000000 10252659744 10710278144 1359999856 14.237 100 21 42 0 1376354064 11103264 5250944
16:37:24 75266 8192000000 10252195632 10709860352 1312671360 13.810 96 11 22 0 1330203200 14870656 2661184
16:39:40 59813 8192000000 10274685968 10727018496 1350152848 14.149 99 15 30 1 1365308032 11319968 3835216
16:41:43 47248 8192000000 9523107200 9862565888 660030560 7.456 48 15 30 1 674353840 10636272 3687008

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 21-10-2022 16:44:09 to 21-10-2022 16:44:24.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 124 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-10-2022 16:44:25 to 21-10-2022 16:44:27.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          243503
MON$OLDEST_ACTIVE               243807
MON$OLDEST_SNAPSHOT             243807
MON$NEXT_TRANSACTION            243807
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-03 16:41:55.3470
MON$PAGES                       2034464
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33637 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33637 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33637 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33637 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Fri Oct 21 16:42:48 2022
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Fri Oct 21 16:42:58 2022
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:13: 
--- end of diff output ---

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

21.10.22 16:44:29. There are 3 logs in /var/tmp/logs-oltp30 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-10-21 16:44:31 No severe PSQL-related problems occured
21.10.22 16:44:33. End of report.