Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.06.2022 16:44
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 16400024 kB
MemAvailable 31288612 kB
Buffers 0 kB
Cached 15349800 kB
SwapCached 2460 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-17 16:42:08
DTS_END                         2022-06-17 16:42:08.2250
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/41594
TRN_ID                          234598
ATT_ID                          613
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: 9216.36 22896 1105963
customer order: creation 172.68 340 20721
customer order: refuse 14.78 341 1773
order to supplier: creation 46.30 1280 5556
order to supplier: removal 3.45 104313 414
invoice (draft): creation 46.35 3563 5562
invoice (draft): removal 53.02 864 6362
invoice accept: apply 42.10 18226 5052
invoice accept: cancel 47.98 763 5758
customer reserve: creation 3823.93 183 458872
customer reserve: removal 4675.19 60 561023
realization accept: apply 82.40 167 9888
realization accept: cancel 76.82 114 9218
payment from customer: creation 60.78 96 7293
payment from customer: removal 5.53 65 663
payment to supplier: creation 49.34 102 5921
payment to supplier: removal 3.71 98 445
service: total inventory turnovers 4.63 1086 556
service: total monetary turnovers 6.60 46 792
service: aggregate perf. data 0.75 22430 90
service: refresh index statistics 0.03 303785 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-06-2022 16:44:57 to 17-06-2022 16:44:59.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3270
TEST_TIME 2 4155
TEST_TIME 3 4476
TEST_TIME 4 4043
TEST_TIME 5 3553
TEST_TIME 6 3542
TEST_TIME 7 3637
TEST_TIME 8 3969
TEST_TIME 9 4341
TEST_TIME 10 5367
TEST_TIME 11 5609
TEST_TIME 12 6108
TEST_TIME 13 6281
TEST_TIME 14 6482
TEST_TIME 15 6696
TEST_TIME 16 7053
TEST_TIME 17 7069
TEST_TIME 18 7234
TEST_TIME 19 8106
TEST_TIME 20 8327
TEST_TIME 21 8250
TEST_TIME 22 8912
TEST_TIME 23 9043
TEST_TIME 24 9200
TEST_TIME 25 9317
TEST_TIME 26 9420
TEST_TIME 27 9804
TEST_TIME 28 10540
TEST_TIME 29 11329
TEST_TIME 30 11181
TEST_TIME 31 11012
TEST_TIME 32 10925
TEST_TIME 33 10698
TEST_TIME 34 10541
TEST_TIME 35 10529
TEST_TIME 36 10374
TEST_TIME 37 10212
TEST_TIME 38 10171
TEST_TIME 39 10291
TEST_TIME 40 10189
TEST_TIME 41 10103
TEST_TIME 42 10024
TEST_TIME 43 9945
TEST_TIME 44 9797
TEST_TIME 45 9751
TEST_TIME 46 9625
TEST_TIME 47 9567
TEST_TIME 48 9504
TEST_TIME 49 9420
TEST_TIME 50 9440
TEST_TIME 51 9541
TEST_TIME 52 9587
TEST_TIME 53 9601
TEST_TIME 54 9718
TEST_TIME 55 9686
TEST_TIME 56 9656
TEST_TIME 57 9709
TEST_TIME 58 9775
TEST_TIME 59 9760
TEST_TIME 60 9877
TEST_TIME 61 9843
TEST_TIME 62 9984
TEST_TIME 63 9919
TEST_TIME 64 9823
TEST_TIME 65 9791
TEST_TIME 66 9711
TEST_TIME 67 9682
TEST_TIME 68 9600
TEST_TIME 69 9619
TEST_TIME 70 9654
TEST_TIME 71 9571
TEST_TIME 72 9568
TEST_TIME 73 9531
TEST_TIME 74 9486
TEST_TIME 75 9439
TEST_TIME 76 9393
TEST_TIME 77 9390
TEST_TIME 78 9401
TEST_TIME 79 9410
TEST_TIME 80 9416
TEST_TIME 81 9466
TEST_TIME 82 9509
TEST_TIME 83 9501
TEST_TIME 84 9529
TEST_TIME 85 9580
TEST_TIME 86 9547
TEST_TIME 87 9606
TEST_TIME 88 9643
TEST_TIME 89 9674
TEST_TIME 90 9581
TEST_TIME 91 9524
TEST_TIME 92 9460
TEST_TIME 93 9429
TEST_TIME 94 9442
TEST_TIME 95 9450
TEST_TIME 96 9389
TEST_TIME 97 9416
TEST_TIME 98 9364
TEST_TIME 99 9403
TEST_TIME 100 9485
TEST_TIME 101 9476
TEST_TIME 102 9489
TEST_TIME 103 9508
TEST_TIME 104 9495
TEST_TIME 105 9506
TEST_TIME 106 9523
TEST_TIME 107 9522
TEST_TIME 108 9567
TEST_TIME 109 9585
TEST_TIME 110 9518
TEST_TIME 111 9507
TEST_TIME 112 9494
TEST_TIME 113 9464
TEST_TIME 114 9464
TEST_TIME 115 9410
TEST_TIME 116 9417
TEST_TIME 117 9415
TEST_TIME 118 9362
TEST_TIME 119 9294
TEST_TIME 120 9220

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 17-06-2022 16:45:01 to 17-06-2022 16:45:04.

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 1530574 1530574 0 0.00 0 15982 6 0 0
doc_list_biud 1530574 1530574 0 0.00 0 27510 28 0 0
sp_add_invoice_to_stock 5144 5052 92 1.78 32 174492 18226 0 92
sp_cancel_adding_invoice 5758 5758 0 0.00 5 79674 764 0 0
sp_cancel_client_order 1773 1773 0 0.00 4 8717 341 0 0
sp_cancel_customer_reserve 561023 561023 0 0.00 1 22313 60 0 0
sp_cancel_pay_from_customer 663 663 0 0.00 3 2713 66 0 0
sp_cancel_pay_to_supplier 445 445 0 0.00 2 5916 98 0 0
sp_cancel_supplier_invoice 6370 6362 8 0.12 2 18669 864 0 8
sp_cancel_supplier_order 414 414 0 0.00 8 1237611 104314 0 0
sp_cancel_write_off 9218 9218 0 0.00 1 20404 115 0 0
sp_client_order 20721 20721 0 0.00 3 13257 340 0 0
sp_customer_reserve 458872 458872 0 0.00 0 48180 184 0 0
sp_fill_shopping_cart 31832 31832 0 0.00 1 16755 38 0 0
sp_fill_shopping_cart_clo_res 458867 458867 0 0.00 0 19427 15 0 0
sp_get_clo_for_invoice 5047 5047 0 0.00 0 23823 316 0 0
sp_kill_cost_storno 16084 16084 0 0.00 0 7545 58 0 0
sp_kill_qstorno_ret_qs2qd 1138040 1138040 0 0.00 0 22310 33 0 0
sp_kill_qty_storno 599483 599483 0 0.00 0 27509 71 0 0
sp_lock_selected_doc 20744 20744 0 0.00 0 6332 5 0 0
sp_make_cost_storno 28149 28149 0 0.00 0 13773 49 0 0
sp_multiply_rows_for_pdistr 28149 28149 0 0.00 0 9658 28 0 0
sp_multiply_rows_for_qdistr 20721 20721 0 0.00 0 12428 163 0 0
sp_pay_from_customer 7293 7293 0 0.00 4 11027 96 0 0
sp_pay_to_supplier 5921 5921 0 0.00 4 11298 103 0 0
sp_payment_common 13214 13214 0 0.00 2 11282 23 0 0
sp_qd_handle_on_cancel_clo 1773 1773 0 0.00 0 8709 310 0 0
sp_qd_handle_on_invoice_upd_sts 10805 10805 0 0.00 0 27509 338 0 0
sp_qd_handle_on_reserve_upd_sts 9888 9888 0 0.00 0 21881 51 0 0
sp_reserve_write_off 9888 9888 0 0.00 6 21927 168 0 0
sp_supplier_invoice 5562 5562 0 0.00 5 103871 3563 0 0
sp_supplier_order 5556 5556 0 0.00 5 43247 1280 0 0
srv_aggregate_perf_data 90 90 0 0.00 2848 322381 22430 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1522 151784 43519 0 0
srv_find_qd_qs_mism 1057872 1057872 0 0.00 0 15967 6 0 0
srv_make_invnt_saldo 556 556 0 0.00 50 71695 1086 0 0
srv_make_money_saldo 792 792 0 0.00 1 7715 46 0 0
srv_recalc_idx_stat 4 4 0 0.00 232846 356078 303785 0 0
t$perf-abend:sp_add_invoice_to_stock 92 92 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 5052 5052 0 0.00 0 16949 186 0 0
t$perf-norm:sp_cancel_adding_invoice 383 383 0 0.00 0 3070 103 0 0
t$perf-norm:sp_cancel_client_order 1773 1773 0 0.00 0 4053 11 0 0
t$perf-norm:sp_cancel_customer_reserve 706 706 0 0.00 0 1536 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 663 663 0 0.00 0 271 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 445 445 0 0.00 0 2146 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 400 400 0 0.00 0 1996 16 0 0
t$perf-norm:sp_cancel_supplier_order 414 414 0 0.00 0 30677 1598 0 0
t$perf-norm:sp_cancel_write_off 1025 1025 0 0.00 0 7553 17 0 0
t$perf-norm:sp_client_order 20721 20721 0 0.00 0 10872 20 0 0
t$perf-norm:sp_customer_reserve 980 980 0 0.00 0 4657 12 0 0
t$perf-norm:sp_pay_from_customer 7293 7293 0 0.00 0 7542 11 0 0
t$perf-norm:sp_pay_to_supplier 5921 5921 0 0.00 0 7103 13 0 0
t$perf-norm:sp_reserve_write_off 9888 9888 0 0.00 0 6977 12 0 0
t$perf-norm:sp_supplier_invoice 5562 5562 0 0.00 0 7630 20 0 0
t$perf-norm:sp_supplier_order 5556 5556 0 0.00 0 7587 18 0 0
t$perf-norm:srv_aggregate_perf_data 90 90 0 0.00 0 114 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 320 9 0 0
t$perf-norm:srv_make_invnt_saldo 556 556 0 0.00 0 1343 23 0 0
t$perf-norm:srv_make_money_saldo 792 792 0 0.00 0 770 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 6 3 0 0
v_add_invoice_to_stock 10278 10094 184 1.79 0 4160 17 0 184
v_all_customers 38482 38482 0 0.00 0 122 1 0 0
v_all_suppliers 30892 30892 0 0.00 0 20 1 0 0
v_all_wares 41442 41442 0 0.00 0 119 1 0 0
v_cancel_adding_invoice 766 766 0 0.00 0 125 5 0 0
v_cancel_client_order 3546 3546 0 0.00 0 3380 6 0 0
v_cancel_customer_prepayment 1326 1326 0 0.00 0 2069 8 0 0
v_cancel_customer_reserve 1412 1412 0 0.00 0 3379 5 0 0
v_cancel_payment_to_supplier 890 890 0 0.00 0 1835 9 0 0
v_cancel_supplier_invoice 816 800 16 1.96 0 2158 16 0 16
v_cancel_supplier_order 828 828 0 0.00 0 1096 5 0 0
v_cancel_write_off 2050 2050 0 0.00 0 3407 15 0 0
v_max_id_clo_ord 5556 5556 0 0.00 0 179 1 0 0
v_max_id_clo_res 980 980 0 0.00 0 1372 9 0 0
v_max_id_ord_sup 5555 5555 0 0.00 0 4270 2 0 0
v_max_non_paid_invoice 1586 1586 0 0.00 0 270 2 0 0
v_max_non_paid_realizn 3657 3657 0 0.00 0 3073 4 0 0
v_min_id_clo_ord 5556 5556 0 0.00 0 5344 10 0 0
v_min_id_clo_res 980 980 0 0.00 0 3436 9 0 0
v_min_id_ord_sup 5555 5555 0 0.00 0 5505 6 0 0
v_min_non_paid_invoice 5921 5921 0 0.00 0 5607 13 0 0
v_min_non_paid_realizn 7293 7293 0 0.00 0 8734 19 0 0
v_our_firm 10232 10232 0 0.00 0 28 1 0 0
v_reserve_write_off 19776 19776 0 0.00 0 3848 6 0 0
x_lock_depdocs_on_canc_invoice 11720 11720 0 0.00 0 11447 117 0 0
x_lock_depdocs_on_canc_sup_ord 414 414 0 0.00 0 5172 329 0 0
x_make_qstorno_1000_1200 5556 5556 0 0.00 1 43165 1187 0 0
x_make_qstorno_1200_2000 5562 5562 0 0.00 3 103854 3438 0 0
x_make_qstorno_2100_3300 458872 458872 0 0.00 0 45669 166 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-06-2022 16:45:05 to 17-06-2022 16:45:09.

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:12 32 8192000000 8813629632 8830038016 16194848 0.197 4 4 6 0 16431520 87904 148768
14:13:12 27455 8192000000 9892686400 10198618112 968923024 10.576 100 89 178 0 997977904 5417888 23636992
14:14:43 16915 8192000000 9930884496 10248060928 1009991536 10.975 100 91 182 0 1041771488 8166224 23613728
14:16:12 22430 8192000000 9970142928 10299981824 1052226336 11.382 100 85 170 0 1083290304 9798224 21265744
14:17:45 9016 8192000000 10027056048 10372276224 1110656528 11.939 100 78 156 0 1140581888 11035248 18890112
14:19:01 6206 8192000000 10071648848 10430599168 1158991072 12.394 100 73 144 2 1188685232 12364272 17329888
14:20:12 5878 8192000000 10116044624 10481979392 1204490832 12.818 100 79 156 1 1235449232 13616464 17341936
14:21:20 11655 8192000000 10137725152 10511556608 1232833376 13.080 100 78 157 3 1262820272 12550208 17436688
14:22:33 1654 8192000000 10183237648 10561142784 1266968128 13.394 100 89 178 1 1300565856 13334480 20263248
14:23:37 2715 8192000000 10200606416 10580451328 1288771040 13.593 100 93 185 1 1323360800 13750544 20839216
14:24:41 19233 8192000000 10211781568 10605154304 1304641424 13.737 100 63 128 0 1328361552 9107248 14612880
14:26:02 5709 8192000000 10202788192 10605150208 1306247872 13.752 100 37 75 1 1322299984 7669120 8382992
14:27:10 6427 8192000000 10196205072 10614329344 1314459696 13.827 100 34 68 2 1329107488 7092480 7555312
14:28:17 14321 8192000000 10205578736 10626793472 1327260688 13.942 100 16 33 0 1337026624 5814624 3951312
14:29:31 55451 8192000000 10239786880 10652721152 1333925728 14.003 100 50 99 3 1350611456 5187568 11498160
14:31:32 3129 8192000000 10235038544 10651860992 1339701024 14.055 100 27 52 0 1351339392 5712288 5926080
14:32:35 5914 8192000000 10225660864 10643984384 1332762448 13.992 100 20 40 0 1342953008 5775632 4414928
14:33:44 12907 8192000000 10196343056 10612572160 1306741600 13.756 100 8 16 0 1313955184 5521632 1691952
14:34:59 26207 8192000000 10184725408 10581065728 1251637648 13.253 97 56 114 3 1273745264 9089056 13018560
14:36:26 6789 8192000000 10167007216 10569236480 1252160656 13.258 98 21 42 1 1262380032 5517088 4702288
14:37:36 7771 8192000000 10130709776 10529697792 1219101520 12.953 98 16 31 3 1227375984 4959776 3314688
14:38:45 8615 8192000000 10169661328 10574372864 1274921856 13.467 100 27 53 0 1286962576 5731136 6309584
14:39:55 27455 8192000000 10163836432 10576670720 1260167536 13.332 98 28 56 0 1272830608 6854608 5808464
14:41:26 10982 8192000000 10188722816 10597801984 1292948368 13.631 100 21 42 2 1303292848 5429760 4914720
14:42:45 31436 8192000000 10221020480 10626101248 1317689712 13.856 100 42 84 0 1333309408 6066736 9552960
14:44:26 9292 8192000000 10248276832 10661511168 1340241936 14.060 100 47 94 0 1363296624 12226224 10828464
14:45:42 36826 8192000000 10272404304 10691780608 1357551936 14.215 100 36 70 0 1386485696 19400480 9533280
14:47:37 82665 8192000000 10289564192 10715389952 1374879232 14.371 100 38 76 0 1404984272 20972544 9132496
14:50:04 6210 8192000000 10305093344 10723422208 1384078864 14.453 100 54 108 3 1420549808 23334128 13136816
14:51:15 7990 8192000000 10318445520 10736205824 1386313232 14.473 100 52 104 1 1427334544 27722512 13298800
14:52:34 6451 8192000000 10337536688 10760454144 1403517744 14.626 100 49 97 0 1446518096 30557600 12442752
14:53:45 6371 8192000000 10362728784 10779877376 1415925008 14.737 100 73 147 1 1463358432 30138608 17294816
14:54:57 1922 8192000000 10376281200 10788954112 1426106496 14.827 100 93 186 0 1483528288 34907840 22513952
14:56:02 8399 8192000000 10364028880 10782900224 1413576784 14.716 99 69 138 0 1469056160 38536944 16942432
14:57:17 3403 8192000000 10375856272 10785419264 1402988720 14.622 99 88 176 3 1468622064 43863232 21770112
14:58:32 6094 8192000000 10386446960 10799165440 1405814928 14.647 99 88 176 1 1476213760 48230032 22168800
14:59:44 2967 8192000000 10371180400 10788859904 1389684032 14.503 98 75 148 1 1460078944 51250416 19144496
15:00:52 1518 8192000000 10402733600 10813325312 1415612736 14.734 100 88 175 3 1501772288 62924928 23234624
15:01:56 12860 8192000000 10358670656 10782294016 1400173872 14.597 100 51 99 3 1462793760 49058048 13561840
15:03:13 14170 8192000000 10331733952 10762137600 1365725104 14.289 98 35 70 0 1424099280 48325952 10048224
15:04:32 13162 8192000000 10342601920 10779787264 1390283904 14.508 100 35 70 0 1454753184 54450880 10018400
15:05:52 10268 8192000000 10346551728 10773389312 1379463552 14.412 99 43 86 1 1440055520 48554544 12037424
15:07:17 26536 8192000000 10342144144 10786111488 1388043104 14.488 100 31 62 0 1449173888 51962048 9168736
15:08:47 23469 8192000000 10324596816 10766307328 1371869776 14.344 100 28 56 0 1436121488 55944192 8307520
15:10:24 19308 8192000000 10317608448 10766467072 1369004368 14.318 100 22 44 0 1430245328 54562752 6678208
15:11:47 47360 8192000000 10255697968 10688143360 1311657728 13.801 97 6 12 0 1332966304 19822976 1485600
15:13:49 78082 8192000000 10261413952 10707902464 1338908144 14.048 98 14 28 0 1353181984 11193808 3080032
15:16:45 63523 8192000000 10283749200 10721189888 1352368224 14.169 99 44 87 1 1373958752 13051456 8539072
15:18:58 103004 8192000000 10286259600 10739511296 1378124192 14.400 100 31 62 1 1395409248 10597936 6687120
15:21:43 42876 8192000000 10277567808 10745864192 1368952720 14.318 99 8 16 0 1383222160 12432720 1836720
15:23:26 36427 8192000000 10261238720 10721947648 1350864272 14.155 98 3 6 0 1357996848 6705760 426816
15:25:03 54401 8192000000 10276631840 10729250816 1366860944 14.299 99 13 26 0 1376804016 7302832 2640240
15:27:05 83690 8192000000 10261149088 10723233792 1363484480 14.269 99 23 46 4 1374986976 6511792 4990704
15:29:46 24354 8192000000 10277183312 10727362560 1369695152 14.324 99 26 52 0 1389279056 13407760 6176144
15:31:11 57100 8192000000 10293891552 10749091840 1386075504 14.471 100 14 28 0 1407305744 17858240 3372000
15:33:22 51504 8192000000 10285750544 10754035712 1382430624 14.438 100 9 16 2 1396951200 12770592 1749984
15:35:25 24857 8192000000 10302752000 10748755968 1372590080 14.350 99 41 82 1 1393523264 12048624 8884560
15:37:02 59486 8192000000 10298174144 10746499072 1364964400 14.282 98 24 48 2 1383973696 13650640 5358656
15:39:02 40000 8192000000 10299412704 10756968448 1392539984 14.529 100 12 23 2 1405075552 9804128 2731440
15:40:44 69831 8192000000 10308025056 10756116480 1369813424 14.325 98 9 18 0 1389577968 17641616 2122928
15:43:04 56528 8192000000 10301833376 10748092416 1371173616 14.338 99 19 38 1 1390357536 14884304 4299616
15:45:01 48176 8192000000 10297187488 10739032064 1352366976 14.169 97 33 66 1 1368744720 9322336 7055408
15:46:51 48041 8192000000 10298011152 10736283648 1371099504 14.337 99 32 64 0 1386479840 8096864 7283472
15:48:45 38477 8192000000 10274358800 10723565568 1364524896 14.278 99 40 80 0 1389828592 15850096 9453600
15:50:33 67767 8192000000 10260037456 10726555648 1333672320 14.000 97 12 24 1 1352158960 15402784 3083856
15:53:17 79252 8192000000 10275168768 10731372544 1371808720 14.343 100 19 38 0 1385225104 8368400 5047984
15:56:02 62139 8192000000 10256323968 10718642176 1365318592 14.285 100 5 10 0 1374348928 8025504 1004832
15:58:10 83522 8192000000 10246394128 10704293888 1257918432 13.311 92 9 18 2 1266426944 6634784 1873728
16:00:35 48283 8192000000 10279322208 10725834752 1365224992 14.284 100 18 36 0 1383093536 13967760 3900784
16:02:25 57914 8192000000 10253994624 10712567808 1315286160 13.834 97 24 48 2 1339031712 18283424 5462128
16:04:29 40994 8192000000 10259215920 10708365312 1327702080 13.946 98 19 38 0 1347088064 15435808 3950176
16:06:10 98526 8192000000 10272285312 10733977600 1357900304 14.218 99 24 48 0 1380517952 17499680 5117968
16:08:51 15487 8192000000 10294790448 10730086400 1372807104 14.352 100 46 92 0 1393620784 10889568 9924112
16:10:09 151784 8192000000 10266535920 10713747456 1280506784 13.518 93 9 18 1 1302818144 20402592 1908768
16:14:22 38332 8192000000 10233078944 10680713216 1334061280 14.004 98 8 16 0 1344571024 8833008 1676736
16:16:19 64350 8192000000 10245664800 10701291520 1316885664 13.849 97 9 18 1 1325628992 6826384 1916944
16:18:23 20029 8192000000 10263491104 10699247616 1325621040 13.928 97 36 71 0 1340001568 6489392 7891136
16:19:48 47552 8192000000 10273646336 10717044736 1339898704 14.056 98 18 36 0 1352501904 8291808 4311392
16:21:40 71792 8192000000 10284216304 10748133376 1365765168 14.289 99 15 30 2 1391290640 22221584 3303888
16:23:58 65704 8192000000 10289261872 10743373824 1357593024 14.216 98 14 28 0 1374766928 14216720 2957184
16:27:18 16758 8192000000 10289314400 10725089280 1369638576 14.324 100 47 94 0 1389854672 9921424 10294672
16:28:42 54911 8192000000 10287759824 10739838976 1353889200 14.182 98 23 45 0 1371473712 12781632 4802880
16:30:46 72960 8192000000 10276823680 10723328000 1332707856 13.992 96 17 34 0 1346567232 10238096 3621280
16:34:05 55111 8192000000 10281162656 10734006272 1360011872 14.237 98 24 48 0 1377756224 12553488 5190864
16:36:02 13019 8192000000 10274668592 10727149568 1340993328 14.066 97 33 66 4 1360224112 10933136 8297648
16:37:20 76359 8192000000 10259779312 10722447360 1323008272 13.904 96 8 16 0 1336124704 11340320 1776112
16:39:51 79971 8192000000 10295931552 10749702144 1384843376 14.460 100 10 20 0 1397986992 10927168 2216448

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 17-06-2022 16:45:11 to 17-06-2022 16:45:26.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 92 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 17-06-2022 16:45:27 to 17-06-2022 16:45: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          232187
MON$OLDEST_ACTIVE               234725
MON$OLDEST_SNAPSHOT             234725
MON$NEXT_TRANSACTION            234725
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                       2020160
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.33603 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33603 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33603 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33603 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 Jun 17 16:42:34 2022
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Fri Jun 17 16:42:40 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'

17.06.22 16:45: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-17 16:45:32 No severe PSQL-related problems occured
17.06.22 16:45:33. End of report.