Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.06.2022 16:39
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 16478528 kB
MemAvailable 31314948 kB
Buffers 0 kB
Cached 15265864 kB
SwapCached 2440 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.10
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-06-12 16:38:22
DTS_END                         2022-06-12 16:38:22.4230
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/49396
TRN_ID                          241291
ATT_ID                          711
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: 9328.79 22122 1119455
customer order: creation 169.87 332 20384
customer order: refuse 14.88 330 1786
order to supplier: creation 44.66 1200 5359
order to supplier: removal 3.40 93451 408
invoice (draft): creation 45.98 2988 5518
invoice (draft): removal 52.95 743 6354
invoice accept: apply 42.42 17374 5090
invoice accept: cancel 47.75 764 5730
customer reserve: creation 3907.83 173 468940
customer reserve: removal 4707.93 52 564951
realization accept: apply 82.68 173 9921
realization accept: cancel 80.57 110 9668
payment from customer: creation 59.22 97 7106
payment from customer: removal 5.51 104 661
payment to supplier: creation 48.78 120 5853
payment to supplier: removal 3.20 82 384
service: total inventory turnovers 4.42 1195 530
service: total monetary turnovers 5.91 31 709
service: aggregate perf. data 0.83 17704 99
service: refresh index statistics 0.03 305414 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 12-06-2022 16:39:58 to 12-06-2022 16:40:00.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5867
TEST_TIME 2 5816
TEST_TIME 3 5762
TEST_TIME 4 6393
TEST_TIME 5 5931
TEST_TIME 6 6090
TEST_TIME 7 5892
TEST_TIME 8 5635
TEST_TIME 9 5953
TEST_TIME 10 5764
TEST_TIME 11 5695
TEST_TIME 12 5713
TEST_TIME 13 6294
TEST_TIME 14 6968
TEST_TIME 15 7280
TEST_TIME 16 7654
TEST_TIME 17 8101
TEST_TIME 18 8417
TEST_TIME 19 9417
TEST_TIME 20 9212
TEST_TIME 21 9053
TEST_TIME 22 9536
TEST_TIME 23 9570
TEST_TIME 24 9950
TEST_TIME 25 10390
TEST_TIME 26 10983
TEST_TIME 27 10715
TEST_TIME 28 10641
TEST_TIME 29 10539
TEST_TIME 30 10527
TEST_TIME 31 10576
TEST_TIME 32 10438
TEST_TIME 33 10418
TEST_TIME 34 10287
TEST_TIME 35 10159
TEST_TIME 36 9952
TEST_TIME 37 9792
TEST_TIME 38 9662
TEST_TIME 39 9632
TEST_TIME 40 9725
TEST_TIME 41 9683
TEST_TIME 42 9646
TEST_TIME 43 9592
TEST_TIME 44 9548
TEST_TIME 45 9518
TEST_TIME 46 9676
TEST_TIME 47 9838
TEST_TIME 48 9799
TEST_TIME 49 9869
TEST_TIME 50 10109
TEST_TIME 51 10047
TEST_TIME 52 9975
TEST_TIME 53 9902
TEST_TIME 54 9770
TEST_TIME 55 9810
TEST_TIME 56 9715
TEST_TIME 57 9660
TEST_TIME 58 9647
TEST_TIME 59 9571
TEST_TIME 60 9541
TEST_TIME 61 9559
TEST_TIME 62 9524
TEST_TIME 63 9507
TEST_TIME 64 9466
TEST_TIME 65 9552
TEST_TIME 66 9509
TEST_TIME 67 9493
TEST_TIME 68 9536
TEST_TIME 69 9597
TEST_TIME 70 9540
TEST_TIME 71 9557
TEST_TIME 72 9547
TEST_TIME 73 9581
TEST_TIME 74 9535
TEST_TIME 75 9539
TEST_TIME 76 9588
TEST_TIME 77 9585
TEST_TIME 78 9670
TEST_TIME 79 9680
TEST_TIME 80 9577
TEST_TIME 81 9525
TEST_TIME 82 9519
TEST_TIME 83 9521
TEST_TIME 84 9506
TEST_TIME 85 9473
TEST_TIME 86 9455
TEST_TIME 87 9414
TEST_TIME 88 9401
TEST_TIME 89 9390
TEST_TIME 90 9380
TEST_TIME 91 9381
TEST_TIME 92 9386
TEST_TIME 93 9449
TEST_TIME 94 9453
TEST_TIME 95 9421
TEST_TIME 96 9489
TEST_TIME 97 9466
TEST_TIME 98 9551
TEST_TIME 99 9599
TEST_TIME 100 9586
TEST_TIME 101 9611
TEST_TIME 102 9620
TEST_TIME 103 9633
TEST_TIME 104 9587
TEST_TIME 105 9674
TEST_TIME 106 9694
TEST_TIME 107 9614
TEST_TIME 108 9611
TEST_TIME 109 9602
TEST_TIME 110 9542
TEST_TIME 111 9568
TEST_TIME 112 9520
TEST_TIME 113 9474
TEST_TIME 114 9460
TEST_TIME 115 9438
TEST_TIME 116 9380
TEST_TIME 117 9357
TEST_TIME 118 9349
TEST_TIME 119 9341
TEST_TIME 120 9341

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 12-06-2022 16:40:01 to 12-06-2022 16:40:05.

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 1552700 1552700 0 0.00 0 28608 6 0 0
doc_list_biud 1552700 1552700 0 0.00 0 80248 25 0 0
sp_add_invoice_to_stock 5176 5090 86 1.66 37 268963 17375 0 86
sp_cancel_adding_invoice 5730 5730 0 0.00 4 97197 764 0 0
sp_cancel_client_order 1786 1786 0 0.00 4 14284 330 0 0
sp_cancel_customer_reserve 564951 564951 0 0.00 1 80249 53 0 0
sp_cancel_pay_from_customer 661 661 0 0.00 3 8064 104 0 0
sp_cancel_pay_to_supplier 384 384 0 0.00 2 4671 82 0 0
sp_cancel_supplier_invoice 6362 6354 8 0.12 2 11134 744 0 8
sp_cancel_supplier_order 408 408 0 0.00 6 945867 93451 0 0
sp_cancel_write_off 9668 9668 0 0.00 1 7954 111 0 0
sp_client_order 20384 20384 0 0.00 3 36870 332 0 0
sp_customer_reserve 468940 468940 0 0.00 0 60314 174 0 0
sp_fill_shopping_cart 31259 31259 0 0.00 1 14748 36 0 0
sp_fill_shopping_cart_clo_res 468918 468918 0 0.00 0 14742 12 0 0
sp_get_clo_for_invoice 5062 5062 0 0.00 0 26071 297 0 0
sp_kill_cost_storno 16443 16443 0 0.00 0 28599 54 0 0
sp_kill_qstorno_ret_qs2qd 1146332 1146332 0 0.00 0 80246 29 0 0
sp_kill_qty_storno 603880 603880 0 0.00 0 80248 63 0 0
sp_lock_selected_doc 20655 20655 0 0.00 0 6192 5 0 0
sp_make_cost_storno 27942 27942 0 0.00 0 25054 54 0 0
sp_multiply_rows_for_pdistr 27942 27942 0 0.00 0 6866 23 0 0
sp_multiply_rows_for_qdistr 20384 20384 0 0.00 0 18934 139 0 0
sp_pay_from_customer 7106 7106 0 0.00 4 19302 97 0 0
sp_pay_to_supplier 5853 5853 0 0.00 4 25063 121 0 0
sp_payment_common 12959 12959 0 0.00 1 10069 24 0 0
sp_qd_handle_on_cancel_clo 1786 1786 0 0.00 0 14279 304 0 0
sp_qd_handle_on_invoice_upd_sts 10792 10792 0 0.00 0 28254 311 0 0
sp_qd_handle_on_reserve_upd_sts 9921 9921 0 0.00 0 15579 61 0 0
sp_reserve_write_off 9921 9921 0 0.00 5 24370 174 0 0
sp_supplier_invoice 5518 5518 0 0.00 6 91445 2988 0 0
sp_supplier_order 5359 5359 0 0.00 5 33586 1200 0 0
srv_aggregate_perf_data 99 99 0 0.00 3082 135699 17705 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 3840 149618 46298 0 0
srv_find_qd_qs_mism 1071295 1071295 0 0.00 0 27238 6 0 0
srv_make_invnt_saldo 530 530 0 0.00 31 62319 1195 0 0
srv_make_money_saldo 709 709 0 0.00 1 7544 32 0 0
srv_recalc_idx_stat 4 4 0 0.00 104919 471535 305415 0 0
t$perf-abend:sp_add_invoice_to_stock 86 86 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5090 5090 0 0.00 0 13455 167 0 0
t$perf-norm:sp_cancel_adding_invoice 397 397 0 0.00 0 4632 106 0 0
t$perf-norm:sp_cancel_client_order 1786 1786 0 0.00 0 1911 10 0 0
t$perf-norm:sp_cancel_customer_reserve 669 669 0 0.00 0 1553 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 661 661 0 0.00 0 2375 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 384 384 0 0.00 0 682 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 372 372 0 0.00 0 1632 12 0 0
t$perf-norm:sp_cancel_supplier_order 408 408 0 0.00 0 25041 1552 0 0
t$perf-norm:sp_cancel_write_off 998 998 0 0.00 0 1543 8 0 0
t$perf-norm:sp_client_order 20384 20384 0 0.00 0 4560 15 0 0
t$perf-norm:sp_customer_reserve 1033 1033 0 0.00 0 4492 10 0 0
t$perf-norm:sp_pay_from_customer 7106 7106 0 0.00 0 9925 11 0 0
t$perf-norm:sp_pay_to_supplier 5853 5853 0 0.00 0 11182 14 0 0
t$perf-norm:sp_reserve_write_off 9921 9921 0 0.00 0 12050 14 0 0
t$perf-norm:sp_supplier_invoice 5518 5518 0 0.00 0 9250 18 0 0
t$perf-norm:sp_supplier_order 5359 5359 0 0.00 0 9637 15 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 288 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 16 1 0 0
t$perf-norm:srv_make_invnt_saldo 530 530 0 0.00 0 4379 27 0 0
t$perf-norm:srv_make_money_saldo 709 709 0 0.00 0 1722 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 2 0 0
v_add_invoice_to_stock 10290 10118 172 1.67 0 8444 18 0 172
v_all_customers 37088 37088 0 0.00 0 22 0 0 0
v_all_suppliers 30028 30028 0 0.00 0 14 0 0 0
v_all_wares 40766 40766 0 0.00 0 88 1 0 0
v_cancel_adding_invoice 794 794 0 0.00 0 3659 10 0 0
v_cancel_client_order 3572 3572 0 0.00 0 2277 5 0 0
v_cancel_customer_prepayment 1322 1322 0 0.00 0 8040 19 0 0
v_cancel_customer_reserve 1338 1338 0 0.00 0 3136 5 0 0
v_cancel_payment_to_supplier 768 768 0 0.00 0 393 3 0 0
v_cancel_supplier_invoice 760 744 16 2.10 0 1309 14 0 16
v_cancel_supplier_order 816 816 0 0.00 0 1622 6 0 0
v_cancel_write_off 1996 1996 0 0.00 0 2342 12 0 0
v_max_id_clo_ord 5359 5359 0 0.00 0 433 1 0 0
v_max_id_clo_res 1033 1033 0 0.00 0 2240 10 0 0
v_max_id_ord_sup 5517 5517 0 0.00 0 6310 4 0 0
v_max_non_paid_invoice 1715 1715 0 0.00 0 3624 4 0 0
v_max_non_paid_realizn 3784 3784 0 0.00 0 1329 3 0 0
v_min_id_clo_ord 5359 5359 0 0.00 0 3420 5 0 0
v_min_id_clo_res 1033 1033 0 0.00 0 688 2 0 0
v_min_id_ord_sup 5517 5517 0 0.00 0 6324 5 0 0
v_min_non_paid_invoice 5853 5853 0 0.00 0 7057 13 0 0
v_min_non_paid_realizn 7106 7106 0 0.00 0 7897 21 0 0
v_our_firm 10322 10322 0 0.00 0 11 1 0 0
v_reserve_write_off 19842 19842 0 0.00 0 3779 8 0 0
x_lock_depdocs_on_canc_invoice 11712 11712 0 0.00 0 24521 106 0 0
x_lock_depdocs_on_canc_sup_ord 408 408 0 0.00 0 12180 373 0 0
x_make_qstorno_1000_1200 5359 5359 0 0.00 1 33581 1136 0 0
x_make_qstorno_1200_2000 5518 5518 0 0.00 2 85757 2851 0 0
x_make_qstorno_2100_3300 468940 468940 0 0.00 0 60313 159 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-06-2022 16:40:06 to 12-06-2022 16:40:10.

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:08:26 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:26 14567 8192000000 9874569840 10176118784 961360176 10.502 100 92 184 0 992020880 5702144 24958560
14:10:44 25683 8192000000 9930993296 10246336512 1003788688 10.915 100 86 172 0 1034239440 7810336 22640416
14:12:18 16358 8192000000 9970614992 10300903424 1060463248 11.461 100 83 166 0 1091074944 9346688 21265008
14:13:49 13515 8192000000 10044581408 10394001408 1124955296 12.074 100 68 134 1 1151476384 10456208 16064880
14:15:05 7775 8192000000 10088708144 10452062208 1177516992 12.567 100 79 156 2 1207941552 12177536 18247024
14:16:17 4129 8192000000 10148032768 10516787200 1231473552 13.068 100 82 162 3 1262796896 13415712 17907632
14:17:24 6720 8192000000 10187324480 10563821568 1268539888 13.408 100 85 171 1 1300895056 14636288 17718880
14:18:33 1679 8192000000 10216404352 10599194624 1300504608 13.700 100 93 186 3 1335284064 12638640 22140816
14:19:35 19392 8192000000 10215156672 10614591488 1314229136 13.824 100 49 94 2 1332030288 6503904 11297248
14:20:57 6471 8192000000 10211499824 10619768832 1320319872 13.880 100 38 77 6 1336941952 8336144 8285936
14:22:04 4672 8192000000 10218200128 10633998336 1330895328 13.975 100 21 42 2 1341124112 5739648 4489136
14:23:15 82160 8192000000 10232643680 10657193984 1343882384 14.092 100 36 72 3 1357379280 5515904 7980992
14:25:40 5022 8192000000 10246449936 10668400640 1348709904 14.136 100 40 80 0 1365686784 8318528 8658352
14:26:46 8158 8192000000 10240561936 10658902016 1332998960 13.994 99 25 50 1 1345584960 6978912 5607088
14:27:55 65585 8192000000 10200661104 10616864768 1242046768 13.165 93 32 64 2 1254958064 5516224 7395072
14:30:04 5676 8192000000 10163840064 10562371584 1227321504 13.029 96 26 52 1 1239381456 5945136 6114816
14:31:11 8235 8192000000 10164394960 10565283840 1257010576 13.303 99 22 43 1 1268150064 6076832 5062656
14:32:22 57312 8192000000 10174536192 10578075648 1248276448 13.222 97 21 40 3 1258469872 5581776 4611648
14:34:21 5805 8192000000 10190295808 10605064192 1301154256 13.706 100 30 60 1 1314716912 6514304 7048352
14:35:28 9363 8192000000 10194366320 10615021568 1310333520 13.789 100 20 40 0 1320416032 5564528 4517984
14:36:37 10470 8192000000 10208122736 10627133440 1317539904 13.854 100 19 38 0 1327617440 5404352 4673184
14:37:50 27267 8192000000 10224233216 10645864448 1326859184 13.939 100 27 54 1 1337517504 4864240 5794080
14:39:26 12732 8192000000 10263507280 10675204096 1355994048 14.201 100 60 119 3 1380437680 10629472 13814160
14:40:44 7104 8192000000 10285829152 10696003584 1368060256 14.310 100 61 120 1 1394624848 13359296 13205296
14:41:53 8903 8192000000 10289622688 10709688320 1376267456 14.383 100 46 92 1 1402925632 15725440 10932736
14:43:17 3840 8192000000 10307930672 10728534016 1389258800 14.499 100 54 108 2 1420162784 17797152 13106832
14:44:27 18191 8192000000 10315201472 10741059584 1396618496 14.565 100 43 86 1 1430927504 23480272 10828736
14:45:57 11188 8192000000 10327090832 10750926848 1401842368 14.611 100 48 96 1 1436307632 22662176 11803088
14:47:12 7931 8192000000 10357818912 10778513408 1419304976 14.767 100 64 128 1 1465398976 29704464 16389536
14:48:24 7440 8192000000 10345618880 10769580032 1408136176 14.667 99 53 105 0 1448730288 27096656 13497456
14:49:35 4820 8192000000 10333992976 10747494400 1389322480 14.500 99 64 127 1 1436699024 31382256 15994288
14:50:46 5296 8192000000 10351749296 10768912384 1396246288 14.562 99 71 142 2 1453169728 39044080 17879360
14:52:08 7731 8192000000 10342096432 10765426688 1395055680 14.551 100 65 130 1 1450730144 38411280 17263184
14:53:24 15974 8192000000 10348113632 10778767360 1404656144 14.636 100 52 104 1 1463372368 45044368 13671856
14:54:43 15098 8192000000 10329789040 10760388608 1369584896 14.323 99 44 89 1 1422133408 40714944 11833568
14:56:02 6587 8192000000 10320807952 10750648320 1367497472 14.305 99 44 88 2 1418927616 40192064 11238080
14:57:13 15219 8192000000 10292997504 10723233792 1337021168 14.031 98 42 84 2 1394490896 46226864 11242864
14:58:51 14020 8192000000 10301379360 10726100992 1347410704 14.124 98 33 62 0 1384392608 29501920 7479984
15:00:08 27669 8192000000 10295176112 10727104512 1336319488 14.024 98 28 56 0 1384978288 39927136 8731664
15:01:50 6667 8192000000 10301625104 10722336768 1354001056 14.183 100 44 88 1 1396776800 31709216 11066528
15:02:59 28323 8192000000 10268421568 10713546752 1348659568 14.135 99 26 52 0 1389474704 33487808 7327328
15:04:30 39961 8192000000 10250217472 10674860032 1314887872 13.830 98 9 18 1 1334269472 16624128 2757472
15:06:11 31042 8192000000 10250589808 10692583424 1326507776 13.936 99 15 30 0 1345110128 14456064 4146288
15:07:44 44113 8192000000 10274472144 10716725248 1355557376 14.197 100 33 66 3 1379795088 16188848 8048864
15:09:31 31886 8192000000 10277642160 10735386624 1374760272 14.370 100 9 18 0 1389897568 13520752 1616544
15:11:03 53870 8192000000 10262747776 10713681920 1356011392 14.202 99 9 18 0 1367197184 9228672 1957120
15:13:00 67851 8192000000 10271825936 10728046592 1378368080 14.402 100 13 26 1 1387572272 6587040 2617152
15:15:12 74081 8192000000 10303507136 10744070144 1371541456 14.341 99 29 57 1 1388283664 9462816 7279392
15:17:27 72693 8192000000 10280320160 10748825600 1362368016 14.259 98 8 17 0 1376555360 12490528 1696816
15:19:41 60360 8192000000 10299262144 10754863104 1391848016 14.522 100 14 28 0 1406435184 11127312 3459856
15:21:48 63617 8192000000 10325725648 10781007872 1410767424 14.691 100 26 52 1 1436065328 19763808 5534096
15:23:55 64375 8192000000 10326767968 10793062400 1414478912 14.724 100 16 32 0 1437443792 18931616 4033264
15:26:29 42990 8192000000 10337897760 10786508800 1393531216 14.537 98 27 54 2 1415253888 15171200 6551472
15:28:21 149617 8192000000 10323983984 10767507456 1393117184 14.534 99 9 18 0 1416566368 21249984 2199200
15:31:53 92316 8192000000 10310222048 10767814656 1382987712 14.443 98 18 36 1 1395470784 8472160 4010912
15:34:28 18894 8192000000 10307325696 10752577536 1381633472 14.431 99 48 96 2 1403102016 10919264 10549280
15:35:52 31027 8192000000 10295958720 10750480384 1380858304 14.424 99 31 62 0 1396814080 8346512 7609264
15:37:23 56081 8192000000 10276441936 10733076480 1364561504 14.278 98 19 37 0 1382039472 13285424 4192544
15:39:25 55642 8192000000 10249858624 10719649792 1340155936 14.059 98 12 24 0 1359626832 16650480 2820416
15:41:22 25510 8192000000 10254334560 10707828736 1358043440 14.220 100 26 52 0 1369368880 5040272 6285168
15:42:47 84443 8192000000 10259216464 10719334400 1304549056 13.737 95 9 18 0 1324715104 17884192 2281856
15:45:12 28402 8192000000 10256719648 10697318400 1306527584 13.755 96 38 76 1 1324815488 9906288 8381616
15:46:46 72196 8192000000 10244006992 10705960960 1308729936 13.775 96 8 16 0 1322422304 12162304 1530064
15:48:59 19112 8192000000 10267366272 10700541952 1327663344 13.946 98 59 117 2 1352045664 10679472 13702848
15:50:25 54250 8192000000 10251757024 10699407360 1320906352 13.885 98 33 66 0 1344851920 16654480 7291088
15:52:20 25272 8192000000 10260708752 10684542976 1312388768 13.808 98 38 76 2 1333987712 13286736 8312208
15:53:48 75919 8192000000 10248807408 10699157504 1298722416 13.684 96 33 65 0 1318585632 13348624 6514592
15:56:06 77748 8192000000 10264223408 10716987392 1309869632 13.785 96 13 26 0 1323588704 10785456 2933616
15:58:24 113013 8192000000 10278069280 10730344448 1366406208 14.295 100 19 38 0 1390589680 19679392 4504080
16:01:18 27397 8192000000 10280518064 10733768704 1376773648 14.388 100 24 48 0 1396278592 13619872 5885072
16:02:52 51181 8192000000 10269158048 10732302336 1367136960 14.301 99 8 16 0 1382660128 14143872 1379296
16:04:44 78984 8192000000 10280933600 10734764032 1357875504 14.218 98 18 36 0 1372701552 10497712 4328336
16:07:42 50339 8192000000 10281986512 10743930880 1373416128 14.358 99 11 22 1 1391292000 15322192 2553680
16:09:54 74558 8192000000 10298325824 10756501504 1399803088 14.593 100 16 32 0 1411555456 8475120 3277248
16:12:09 92037 8192000000 10327283280 10784112640 1411146864 14.694 100 20 40 0 1427217376 11299824 4770688
16:14:41 86139 8192000000 10336934240 10800173056 1410324832 14.687 99 20 40 2 1430863120 16415872 4122416
16:17:08 68093 8192000000 10321255888 10773901312 1385379744 14.465 98 10 21 0 1403576048 16239248 1957056
16:19:24 74932 8192000000 10330172224 10778910720 1393366512 14.536 99 31 62 2 1416318656 16050576 6901568
16:21:43 45543 8192000000 10325465312 10775003136 1397075584 14.569 99 29 58 0 1416712096 13640880 5995632
16:24:25 146801 8192000000 10291238864 10748219392 1344288128 14.096 96 18 36 0 1363811312 15141872 4381312
16:28:09 27730 8192000000 10275919248 10733170688 1343050400 14.085 97 13 26 0 1360504800 14748192 2706208
16:29:37 61834 8192000000 10248660096 10713960448 1307521600 13.764 95 5 10 0 1317384336 8983120 879616
16:31:39 77736 8192000000 10262618816 10717552640 1326513312 13.936 97 17 35 1 1337472688 7483952 3475424
16:35:04 48269 8192000000 10271658736 10726072320 1357702640 14.217 99 18 36 0 1375592608 13642896 4247072
16:36:52 54074 8192000000 10256993328 10715021312 1337981904 14.039 98 17 34 0 1355204224 13006816 4215504

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 12-06-2022 16:40:12 to 12-06-2022 16:40:26.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 86 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 12-06-2022 16:40:27 to 12-06-2022 16:40:29.

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          240299
MON$OLDEST_ACTIVE               241441
MON$OLDEST_SNAPSHOT             241441
MON$NEXT_TRANSACTION            241441
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                       2013568
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33602 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33602 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33602 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33602 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 ---
:430: 
:431: mscs-test-161.budzdorov.ru    Sun Jun 12 16:38:30 2022
:432:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:433: 
:434: 
:435: mscs-test-161.budzdorov.ru    Sun Jun 12 16:38:33 2022
:436:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:437: 
:438: 
:439: mscs-test-161.budzdorov.ru    Sun Jun 12 16:38:36 2022
:440:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:441: 
--- end of diff output ---

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

12.06.22 16:40:30. 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-06-12 16:40:33 No severe PSQL-related problems occured
12.06.22 16:40:34. End of report.