Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 31.12.2021 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 16527048 kB
MemAvailable 31363480 kB
Buffers 0 kB
Cached 15172660 kB
SwapCached 1664 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.9
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 2021-12-31 16:38:01
DTS_END                         2021-12-31 16:38:01.4310
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/60310
TRN_ID                          241035
ATT_ID                          632
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: 9286.98 20940 1114437
customer order: creation 178.77 352 21452
customer order: refuse 15.14 346 1817
order to supplier: creation 46.66 1216 5599
order to supplier: removal 3.60 98006 432
invoice (draft): creation 47.43 3303 5692
invoice (draft): removal 50.55 844 6066
invoice accept: apply 43.53 17700 5223
invoice accept: cancel 46.13 800 5535
customer reserve: creation 4014.02 175 481682
customer reserve: removal 4536.98 62 544437
realization accept: apply 87.53 181 10504
realization accept: cancel 81.90 118 9828
payment from customer: creation 61.18 111 7341
payment from customer: removal 6.13 80 735
payment to supplier: creation 51.19 157 6143
payment to supplier: removal 3.55 76 426
service: total inventory turnovers 5.31 1002 637
service: total monetary turnovers 6.54 22 785
service: aggregate perf. data 0.83 20091 99
service: refresh index statistics 0.03 274164 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 31-12-2021 16:39:29 to 31-12-2021 16:39:31.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6056
TEST_TIME 2 5470
TEST_TIME 3 4986
TEST_TIME 4 4747
TEST_TIME 5 5214
TEST_TIME 6 5030
TEST_TIME 7 5581
TEST_TIME 8 5539
TEST_TIME 9 5710
TEST_TIME 10 6283
TEST_TIME 11 6919
TEST_TIME 12 7539
TEST_TIME 13 8038
TEST_TIME 14 8349
TEST_TIME 15 8709
TEST_TIME 16 8740
TEST_TIME 17 8838
TEST_TIME 18 9192
TEST_TIME 19 9291
TEST_TIME 20 9184
TEST_TIME 21 9459
TEST_TIME 22 9505
TEST_TIME 23 9460
TEST_TIME 24 9598
TEST_TIME 25 9828
TEST_TIME 26 10409
TEST_TIME 27 10441
TEST_TIME 28 11665
TEST_TIME 29 11386
TEST_TIME 30 11296
TEST_TIME 31 11270
TEST_TIME 32 11143
TEST_TIME 33 10994
TEST_TIME 34 10759
TEST_TIME 35 10652
TEST_TIME 36 10649
TEST_TIME 37 10561
TEST_TIME 38 10385
TEST_TIME 39 10346
TEST_TIME 40 10244
TEST_TIME 41 10255
TEST_TIME 42 10240
TEST_TIME 43 10300
TEST_TIME 44 10239
TEST_TIME 45 10120
TEST_TIME 46 10034
TEST_TIME 47 9861
TEST_TIME 48 9834
TEST_TIME 49 9971
TEST_TIME 50 9987
TEST_TIME 51 9909
TEST_TIME 52 9940
TEST_TIME 53 10006
TEST_TIME 54 9964
TEST_TIME 55 9972
TEST_TIME 56 10063
TEST_TIME 57 10137
TEST_TIME 58 10144
TEST_TIME 59 10220
TEST_TIME 60 10161
TEST_TIME 61 10228
TEST_TIME 62 10101
TEST_TIME 63 10010
TEST_TIME 64 9964
TEST_TIME 65 9945
TEST_TIME 66 9884
TEST_TIME 67 9815
TEST_TIME 68 9742
TEST_TIME 69 9673
TEST_TIME 70 9574
TEST_TIME 71 9594
TEST_TIME 72 9638
TEST_TIME 73 9618
TEST_TIME 74 9647
TEST_TIME 75 9601
TEST_TIME 76 9594
TEST_TIME 77 9580
TEST_TIME 78 9641
TEST_TIME 79 9610
TEST_TIME 80 9559
TEST_TIME 81 9618
TEST_TIME 82 9664
TEST_TIME 83 9707
TEST_TIME 84 9707
TEST_TIME 85 9757
TEST_TIME 86 9660
TEST_TIME 87 9592
TEST_TIME 88 9542
TEST_TIME 89 9512
TEST_TIME 90 9557
TEST_TIME 91 9515
TEST_TIME 92 9482
TEST_TIME 93 9495
TEST_TIME 94 9453
TEST_TIME 95 9437
TEST_TIME 96 9464
TEST_TIME 97 9550
TEST_TIME 98 9555
TEST_TIME 99 9533
TEST_TIME 100 9525
TEST_TIME 101 9531
TEST_TIME 102 9533
TEST_TIME 103 9535
TEST_TIME 104 9518
TEST_TIME 105 9570
TEST_TIME 106 9591
TEST_TIME 107 9643
TEST_TIME 108 9567
TEST_TIME 109 9522
TEST_TIME 110 9495
TEST_TIME 111 9492
TEST_TIME 112 9474
TEST_TIME 113 9425
TEST_TIME 114 9385
TEST_TIME 115 9374
TEST_TIME 116 9392
TEST_TIME 117 9359
TEST_TIME 118 9352
TEST_TIME 119 9369
TEST_TIME 120 9292

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 31-12-2021 16:39:33 to 31-12-2021 16:39:36.

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 1559913 1559913 0 0.00 0 34509 6 0 0
doc_list_biud 1559913 1559913 0 0.00 0 25853 27 0 0
sp_add_invoice_to_stock 5299 5223 76 1.43 42 154195 17701 0 76
sp_cancel_adding_invoice 5535 5535 0 0.00 4 71719 800 0 0
sp_cancel_client_order 1817 1817 0 0.00 4 8471 347 0 0
sp_cancel_customer_reserve 544437 544437 0 0.00 1 25858 62 0 0
sp_cancel_pay_from_customer 735 735 0 0.00 3 7856 80 0 0
sp_cancel_pay_to_supplier 426 426 0 0.00 3 3634 77 0 0
sp_cancel_supplier_invoice 6076 6066 10 0.16 2 32228 845 0 10
sp_cancel_supplier_order 432 432 0 0.00 5 1040059 98006 0 0
sp_cancel_write_off 9828 9828 0 0.00 1 21860 118 0 0
sp_client_order 21452 21452 0 0.00 3 21251 352 0 0
sp_customer_reserve 481682 481682 0 0.00 0 81055 175 0 0
sp_fill_shopping_cart 32733 32733 0 0.00 1 11487 37 0 0
sp_fill_shopping_cart_clo_res 481673 481673 0 0.00 0 15509 14 0 0
sp_get_clo_for_invoice 5214 5214 0 0.00 0 28812 288 0 0
sp_kill_cost_storno 16524 16524 0 0.00 0 9337 54 0 0
sp_kill_qstorno_ret_qs2qd 1105200 1105200 0 0.00 0 25841 34 0 0
sp_kill_qty_storno 583833 583833 0 0.00 0 25851 72 0 0
sp_lock_selected_doc 21710 21710 0 0.00 0 7361 3 0 0
sp_make_cost_storno 29202 29202 0 0.00 0 34468 55 0 0
sp_multiply_rows_for_pdistr 29202 29202 0 0.00 0 9123 31 0 0
sp_multiply_rows_for_qdistr 21452 21452 0 0.00 0 16814 156 0 0
sp_pay_from_customer 7341 7341 0 0.00 4 19172 112 0 0
sp_pay_to_supplier 6143 6143 0 0.00 4 18973 157 0 0
sp_payment_common 13484 13484 0 0.00 1 16189 38 0 0
sp_qd_handle_on_cancel_clo 1817 1817 0 0.00 0 8463 315 0 0
sp_qd_handle_on_invoice_upd_sts 10749 10749 0 0.00 0 23990 305 0 0
sp_qd_handle_on_reserve_upd_sts 10504 10504 0 0.00 0 14662 52 0 0
sp_reserve_write_off 10504 10504 0 0.00 6 19224 181 0 0
sp_supplier_invoice 5692 5692 0 0.00 6 93914 3304 0 0
sp_supplier_order 5599 5599 0 0.00 4 28377 1216 0 0
srv_aggregate_perf_data 99 99 0 0.00 2831 435012 20091 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1989 141242 46865 0 0
srv_find_qd_qs_mism 1064560 1064560 0 0.00 0 25763 6 0 0
srv_make_invnt_saldo 637 637 0 0.00 24 82781 1003 0 0
srv_make_money_saldo 785 785 0 0.00 1 311 22 0 0
srv_recalc_idx_stat 4 4 0 0.00 137408 372403 274165 0 0
t$perf-abend:sp_add_invoice_to_stock 76 76 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5223 5223 0 0.00 0 9667 171 0 0
t$perf-norm:sp_cancel_adding_invoice 419 419 0 0.00 0 1844 68 0 0
t$perf-norm:sp_cancel_client_order 1817 1817 0 0.00 0 3877 13 0 0
t$perf-norm:sp_cancel_customer_reserve 750 750 0 0.00 0 706 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 735 735 0 0.00 0 3970 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 426 426 0 0.00 0 1129 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 367 367 0 0.00 0 2399 28 0 0
t$perf-norm:sp_cancel_supplier_order 432 432 0 0.00 0 29588 1339 0 0
t$perf-norm:sp_cancel_write_off 1049 1049 0 0.00 0 1789 6 0 0
t$perf-norm:sp_client_order 21452 21452 0 0.00 0 5905 18 0 0
t$perf-norm:sp_customer_reserve 1056 1056 0 0.00 0 3005 6 0 0
t$perf-norm:sp_pay_from_customer 7341 7341 0 0.00 0 6146 9 0 0
t$perf-norm:sp_pay_to_supplier 6143 6143 0 0.00 0 7816 14 0 0
t$perf-norm:sp_reserve_write_off 10504 10504 0 0.00 0 5555 11 0 0
t$perf-norm:sp_supplier_invoice 5692 5692 0 0.00 0 4822 18 0 0
t$perf-norm:sp_supplier_order 5599 5599 0 0.00 0 5163 14 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 98 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 197 4 0 0
t$perf-norm:srv_make_invnt_saldo 637 637 0 0.00 0 1132 17 0 0
t$perf-norm:srv_make_money_saldo 785 785 0 0.00 0 735 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 10576 10424 152 1.43 0 19435 24 0 152
v_all_customers 38686 38686 0 0.00 0 20 1 0 0
v_all_suppliers 31658 31658 0 0.00 0 13 1 0 0
v_all_wares 42898 42898 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 838 838 0 0.00 0 17456 27 0 0
v_cancel_client_order 3634 3634 0 0.00 0 3732 6 0 0
v_cancel_customer_prepayment 1470 1470 0 0.00 0 7808 8 0 0
v_cancel_customer_reserve 1500 1500 0 0.00 0 9254 9 0 0
v_cancel_payment_to_supplier 852 852 0 0.00 0 3583 13 0 0
v_cancel_supplier_invoice 754 734 20 2.65 0 1907 21 0 20
v_cancel_supplier_order 864 864 0 0.00 0 848 4 0 0
v_cancel_write_off 2098 2098 0 0.00 0 15519 25 0 0
v_max_id_clo_ord 5599 5599 0 0.00 0 2819 3 0 0
v_max_id_clo_res 1056 1056 0 0.00 0 3199 18 0 0
v_max_id_ord_sup 5685 5685 0 0.00 0 5696 4 0 0
v_max_non_paid_invoice 1598 1598 0 0.00 0 3548 5 0 0
v_max_non_paid_realizn 3952 3952 0 0.00 0 5071 6 0 0
v_min_id_clo_ord 5599 5599 0 0.00 0 2714 7 0 0
v_min_id_clo_res 1056 1056 0 0.00 0 6099 12 0 0
v_min_id_ord_sup 5685 5685 0 0.00 0 4256 6 0 0
v_min_non_paid_invoice 6143 6143 0 0.00 0 11828 31 0 0
v_min_non_paid_realizn 7341 7341 0 0.00 0 16185 27 0 0
v_our_firm 10990 10990 0 0.00 0 15 1 0 0
v_reserve_write_off 21004 21004 0 0.00 0 19128 11 0 0
x_lock_depdocs_on_canc_invoice 11234 11234 0 0.00 0 14869 124 0 0
x_lock_depdocs_on_canc_sup_ord 432 432 0 0.00 0 8200 298 0 0
x_make_qstorno_1000_1200 5599 5599 0 0.00 1 28369 1132 0 0
x_make_qstorno_1200_2000 5692 5692 0 0.00 3 93865 3180 0 0
x_make_qstorno_2100_3300 481682 481682 0 0.00 0 81054 158 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 31-12-2021 16:39:37 to 31-12-2021 16:39:41.

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:05 14 8192000000 8811059312 8826687488 14069120 0.171 5 3 4 0 14257120 80896 107104
14:09:07 30664 8192000000 9894286512 10202206208 972542384 10.612 100 85 170 0 1000881088 5811504 22527200
14:10:43 22212 8192000000 9933927216 10258055168 1017743952 11.050 100 89 178 0 1048818448 7796848 23277648
14:12:10 16132 8192000000 9961724224 10294702080 1053259264 11.392 100 83 166 0 1084039936 9435520 21345152
14:13:34 15924 8192000000 10026405456 10377428992 1120414864 12.031 100 75 151 0 1148960064 10625664 17919536
14:14:53 12265 8192000000 10081159712 10444013568 1172476832 12.520 100 78 156 0 1201765936 11311312 17977792
14:16:10 5338 8192000000 10133832064 10503561216 1221566288 12.976 100 82 162 2 1252780416 12231616 18982512
14:17:17 3583 8192000000 10169642816 10545250304 1255358832 13.287 100 90 180 2 1288960240 13741536 19859872
14:18:22 1538 8192000000 10204394944 10589573120 1288262352 13.588 100 91 182 2 1322829600 14103408 20463840
14:19:25 1781 8192000000 10222632256 10613592064 1312176272 13.806 100 88 176 0 1344728528 11692464 20859792
14:20:27 5697 8192000000 10228031072 10619682816 1313772096 13.820 100 83 164 4 1341311824 8005840 19533888
14:21:33 4991 8192000000 10209199104 10622541824 1318890080 13.867 100 37 75 1 1333932272 6721424 8320768
14:22:39 4195 8192000000 10222426816 10636075008 1328209712 13.951 100 29 59 0 1341025536 6588544 6227280
14:23:45 11374 8192000000 10220275952 10643640320 1334961120 14.012 100 18 36 1 1345004448 5931504 4111824
14:24:57 58497 8192000000 10260095440 10663362560 1343753824 14.091 100 53 110 1 1362517408 4769776 13993808
14:26:56 6965 8192000000 10233197200 10659385344 1334737008 14.010 99 34 67 3 1348904112 6183936 7983168
14:28:05 33355 8192000000 10256773184 10661494784 1333058400 13.995 99 66 135 3 1355091280 7159408 14873472
14:29:39 7493 8192000000 10196755232 10598666240 1263105792 13.358 96 42 84 1 1278554448 6840048 8608608
14:30:48 4443 8192000000 10121538448 10520576000 1192066528 12.703 95 24 48 0 1203890208 5196464 6627216
14:31:52 52119 8192000000 10123164352 10526375936 1192305312 12.705 95 32 64 0 1205850016 6598336 6946368
14:33:46 7407 8192000000 10160501728 10562207744 1260840656 13.338 100 32 63 2 1274216656 5307456 8068544
14:34:57 11281 8192000000 10176041264 10579640320 1279305536 13.507 100 30 59 4 1292257376 6047600 6904240
14:36:13 53432 8192000000 10181008448 10594492416 1291973440 13.622 100 20 41 0 1302206144 5553072 4679632
14:38:08 3536 8192000000 10227119632 10628186112 1319145968 13.869 100 51 100 0 1337005104 6886032 10973104
14:39:19 18024 8192000000 10253878752 10660528128 1341790896 14.074 100 56 114 0 1363893936 9687168 12415872
14:40:41 19809 8192000000 10280766512 10692034560 1366743552 14.298 100 59 116 2 1393145584 13002592 13399440
14:42:05 41664 8192000000 10297019440 10713534464 1377540256 14.395 100 54 108 2 1408680480 19146352 11993872
14:43:54 10758 8192000000 10295571712 10719150080 1387825616 14.486 100 30 60 1 1410063920 15095952 7142352
14:45:09 21556 8192000000 10317469472 10737774592 1393985824 14.541 100 42 84 0 1425466960 20838368 10642768
14:46:35 10378 8192000000 10321395488 10739130368 1398644816 14.583 100 56 111 0 1431021312 19254752 13121744
14:47:52 1989 8192000000 10343091648 10759192576 1402223296 14.615 100 81 159 0 1446916464 25003248 19689920
14:49:00 28579 8192000000 10347574400 10781028352 1420266352 14.775 100 60 120 0 1460076080 24995776 14813952
14:50:34 2273 8192000000 10368725152 10791620608 1423017504 14.799 100 91 182 1 1476482368 32255344 21209520
14:51:40 2743 8192000000 10354009216 10779283456 1397599312 14.574 98 80 160 2 1449236880 30986608 20650960
14:52:48 6516 8192000000 10341377440 10769047552 1399910976 14.594 100 61 121 0 1448063200 33478672 14673552
14:53:59 10679 8192000000 10331078128 10750550016 1383551072 14.448 99 58 116 1 1429439184 31466432 14421680
14:55:15 6577 8192000000 10349186240 10767007744 1391838928 14.522 99 64 124 2 1440371088 34035808 14496352
14:56:29 6352 8192000000 10339867888 10760192000 1381319408 14.428 99 55 112 2 1431684960 36219264 14146288
14:57:44 3171 8192000000 10346061584 10764288000 1396782048 14.566 100 64 128 1 1451687232 38655088 16250096
14:58:55 6786 8192000000 10355869088 10780950528 1392437456 14.528 99 57 114 2 1456628768 49327360 14863952
15:00:06 9490 8192000000 10355876608 10781876224 1405206240 14.641 100 47 94 0 1464681264 46864064 12610960
15:01:22 9780 8192000000 10358026976 10784325632 1396415152 14.563 100 37 72 2 1452966784 46522576 10029056
15:02:35 10494 8192000000 10340048864 10776199168 1390877760 14.514 100 32 64 2 1450003200 49546688 9578752
15:03:49 22453 8192000000 10320216192 10766213120 1373544528 14.359 99 27 55 1 1439731744 57981344 8205872
15:05:14 21326 8192000000 10308774064 10748153856 1344381648 14.097 99 19 38 2 1401636480 51632800 5622032
15:06:36 47074 8192000000 10256817520 10695413760 1291169296 13.615 95 7 15 0 1313792624 20902400 1720928
15:08:29 24975 8192000000 10279317200 10716319744 1355562848 14.197 99 31 60 1 1370142672 7984784 6595040
15:09:58 133123 8192000000 10272548416 10709590016 1279566016 13.509 93 10 18 1 1291561648 9355184 2640448
15:13:11 117869 8192000000 10291574336 10739896320 1342106832 14.076 97 26 52 3 1358354336 9967344 6280160
15:16:10 34175 8192000000 10290745072 10725568512 1347925280 14.129 98 35 68 3 1363288512 6309680 9053552
15:17:45 31652 8192000000 10296901296 10736062464 1362963280 14.264 99 16 32 0 1375686752 8990848 3732624
15:19:23 9611 8192000000 10312215952 10754621440 1383610448 14.449 100 53 105 1 1406444032 10209296 12624288
15:20:36 51774 8192000000 10300023184 10760855552 1381868000 14.433 99 14 28 0 1398954960 13665088 3421872
15:23:10 23352 8192000000 10297292832 10758606848 1391219888 14.517 100 8 16 0 1413307280 20029216 2058176
15:25:23 66552 8192000000 10314783264 10773491712 1392993584 14.533 99 21 42 0 1416168128 18265920 4908624
15:27:30 73459 8192000000 10283615488 10745180160 1324765120 13.920 95 7 14 0 1338119952 11907824 1447008
15:29:45 31831 8192000000 10303591648 10758987776 1383056256 14.444 99 33 66 1 1405465040 15272896 7135888
15:31:23 72469 8192000000 10308977760 10757459968 1367957376 14.309 98 16 32 0 1386815504 14935328 3922800
15:33:35 98692 8192000000 10307256256 10782896128 1377625088 14.395 98 12 24 0 1396726784 16063696 3038000
15:36:27 48313 8192000000 10292138560 10743877632 1342659408 14.081 97 7 14 1 1356262240 12021280 1581552
15:38:15 56245 8192000000 10274077072 10714820608 1287658384 13.583 93 28 56 2 1303938896 10187344 6093168
15:40:12 95592 8192000000 10253756128 10702602240 1279058576 13.504 94 22 44 0 1291547904 7548496 4940832
15:43:02 50981 8192000000 10264230736 10719653888 1352406768 14.169 99 13 26 1 1366583904 11220608 2956528
15:44:56 78292 8192000000 10298898176 10744803328 1355811024 14.200 98 18 36 2 1372265248 12626304 3827920
15:48:30 75103 8192000000 10271392560 10734084096 1355791888 14.200 99 14 28 0 1381759936 22541376 3426672
15:50:46 83377 8192000000 10279315936 10736525312 1351152896 14.158 98 17 34 1 1365365120 10035600 4176624
15:53:09 48996 8192000000 10259293696 10703962112 1305438112 13.745 95 27 54 2 1321713040 10588464 5686464
15:55:06 58178 8192000000 10264661904 10710675456 1325190448 13.924 97 30 60 0 1343170944 11331920 6648576
15:57:07 24072 8192000000 10264014096 10707046400 1342260720 14.078 99 25 50 1 1355081008 7325936 5494352
15:58:59 45634 8192000000 10274195664 10732236800 1355025728 14.193 99 28 56 1 1379778368 18479200 6273440
16:00:51 80686 8192000000 10285427712 10741329920 1330573584 13.972 97 15 30 1 1351976336 17876288 3526464
16:03:19 129679 8192000000 10269750800 10716852224 1329128672 13.959 97 15 30 0 1343577744 11432768 3016304
16:06:38 43510 8192000000 10269871328 10713849856 1338518544 14.044 98 28 56 2 1354399376 9282816 6598016
16:08:24 58018 8192000000 10268059744 10729439232 1374371776 14.366 100 10 21 0 1385945872 9432560 2141536
16:10:35 60816 8192000000 10280134416 10732650496 1353134928 14.176 98 7 14 1 1360076816 5424544 1517344
16:12:36 54473 8192000000 10277586976 10725634048 1353965040 14.183 97 36 71 0 1370133552 8113120 8055392
16:14:37 97609 8192000000 10296524352 10762608640 1367244832 14.302 98 17 34 0 1390018560 18896992 3876736
16:17:22 28547 8192000000 10294255104 10748551168 1379637088 14.413 99 12 24 0 1391613200 9399328 2576784
16:18:50 53986 8192000000 10305210128 10756259840 1358193344 14.221 97 26 50 0 1376212528 12560944 5458240
16:20:53 78648 8192000000 10297429984 10743508992 1339707504 14.055 96 26 52 0 1358100032 13010816 5381712
16:23:12 90195 8192000000 10288185392 10740269056 1330267632 13.970 97 23 46 0 1349167904 13847088 5053184
16:25:48 141242 8192000000 10256799456 10708451328 1310295424 13.789 95 20 40 0 1331224608 16303520 4625664
16:29:45 80826 8192000000 10250391792 10711760896 1344117120 14.095 98 10 20 1 1356447872 10084224 2246528
16:32:06 95338 8192000000 10267806992 10735095808 1365657904 14.288 99 14 28 0 1386991824 17976032 3357888
16:34:44 42814 8192000000 10267907792 10718261248 1315863264 13.839 96 19 38 1 1330224544 9925104 4436176
16:36:39 59715 8192000000 10273364384 10734338048 1350803984 14.155 98 25 50 0 1373454720 16244320 6406416

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 31-12-2021 16:39:43 to 31-12-2021 16:39:58.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 76 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 31-12-2021 16:39:59 to 31-12-2021 16:40:01.

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          236450
MON$OLDEST_ACTIVE               241188
MON$OLDEST_SNAPSHOT             241188
MON$NEXT_TRANSACTION            241188
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                       2031392
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33548 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33548 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33548 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33548 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 ---
:170: 
:171: mscs-test-161.budzdorov.ru    Fri Dec 31 16:38:05 2021
:172:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:173: 
:174: 
:175: mscs-test-161.budzdorov.ru    Fri Dec 31 16:38:07 2021
:176:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:177: 
:178: 
:179: mscs-test-161.budzdorov.ru    Fri Dec 31 16:38:08 2021
:180:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:181: 
:182: 
:183: mscs-test-161.budzdorov.ru    Fri Dec 31 16:38:08 2021
:184:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:185: 
:186: 
:187: mscs-test-161.budzdorov.ru    Fri Dec 31 16:38:08 2021
:188:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:189: 
--- end of diff output ---

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

31.12.21 16:40:02. 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
2021-12-31 16:40:04 No severe PSQL-related problems occured
31.12.21 16:40:06. End of report.