Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 02.12.2021 01:38
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 16559612 kB
MemAvailable 31476900 kB
Buffers 0 kB
Cached 15079204 kB
SwapCached 0 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.8
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-02 01:37:54
DTS_END                         2021-12-02 01:37:54.5490
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/48990
TRN_ID                          244909
ATT_ID                          658
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: 9549.96 21642 1145995
customer order: creation 178.31 356 21397
customer order: refuse 15.48 358 1858
order to supplier: creation 48.77 1242 5852
order to supplier: removal 3.58 94375 429
invoice (draft): creation 47.61 3223 5713
invoice (draft): removal 53.32 765 6398
invoice accept: apply 43.11 17807 5173
invoice accept: cancel 48.78 774 5854
customer reserve: creation 3990.08 176 478810
customer reserve: removal 4803.58 54 576429
realization accept: apply 88.32 188 10598
realization accept: cancel 92.96 95 11155
payment from customer: creation 62.08 108 7449
payment from customer: removal 6.02 86 722
payment to supplier: creation 51.23 132 6148
payment to supplier: removal 3.78 47 454
service: total inventory turnovers 5.14 1014 617
service: total monetary turnovers 6.98 22 838
service: aggregate perf. data 0.81 25634 97
service: refresh index statistics 0.03 286386 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 02-12-2021 01:38:42 to 02-12-2021 01:38:44.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2191
TEST_TIME 2 3913
TEST_TIME 3 4941
TEST_TIME 4 5076
TEST_TIME 5 4910
TEST_TIME 6 5031
TEST_TIME 7 4967
TEST_TIME 8 5083
TEST_TIME 9 5219
TEST_TIME 10 5368
TEST_TIME 11 5587
TEST_TIME 12 5677
TEST_TIME 13 5601
TEST_TIME 14 5802
TEST_TIME 15 5880
TEST_TIME 16 6182
TEST_TIME 17 6401
TEST_TIME 18 7055
TEST_TIME 19 7930
TEST_TIME 20 7911
TEST_TIME 21 8110
TEST_TIME 22 8365
TEST_TIME 23 8976
TEST_TIME 24 9352
TEST_TIME 25 9874
TEST_TIME 26 9778
TEST_TIME 27 9941
TEST_TIME 28 10212
TEST_TIME 29 10055
TEST_TIME 30 9948
TEST_TIME 31 9825
TEST_TIME 32 10164
TEST_TIME 33 10147
TEST_TIME 34 10001
TEST_TIME 35 10136
TEST_TIME 36 10129
TEST_TIME 37 10062
TEST_TIME 38 10030
TEST_TIME 39 10130
TEST_TIME 40 10093
TEST_TIME 41 10130
TEST_TIME 42 9936
TEST_TIME 43 10027
TEST_TIME 44 10067
TEST_TIME 45 9886
TEST_TIME 46 9850
TEST_TIME 47 9777
TEST_TIME 48 9803
TEST_TIME 49 9852
TEST_TIME 50 9964
TEST_TIME 51 9867
TEST_TIME 52 9820
TEST_TIME 53 9766
TEST_TIME 54 9673
TEST_TIME 55 9645
TEST_TIME 56 9597
TEST_TIME 57 9542
TEST_TIME 58 9472
TEST_TIME 59 9462
TEST_TIME 60 9493
TEST_TIME 61 9479
TEST_TIME 62 9536
TEST_TIME 63 9578
TEST_TIME 64 9572
TEST_TIME 65 9617
TEST_TIME 66 9591
TEST_TIME 67 9685
TEST_TIME 68 9804
TEST_TIME 69 9825
TEST_TIME 70 9781
TEST_TIME 71 9810
TEST_TIME 72 9898
TEST_TIME 73 9856
TEST_TIME 74 9783
TEST_TIME 75 9727
TEST_TIME 76 9656
TEST_TIME 77 9660
TEST_TIME 78 9599
TEST_TIME 79 9589
TEST_TIME 80 9554
TEST_TIME 81 9504
TEST_TIME 82 9489
TEST_TIME 83 9458
TEST_TIME 84 9424
TEST_TIME 85 9447
TEST_TIME 86 9425
TEST_TIME 87 9462
TEST_TIME 88 9492
TEST_TIME 89 9580
TEST_TIME 90 9529
TEST_TIME 91 9642
TEST_TIME 92 9665
TEST_TIME 93 9768
TEST_TIME 94 9736
TEST_TIME 95 9727
TEST_TIME 96 9774
TEST_TIME 97 9750
TEST_TIME 98 9687
TEST_TIME 99 9684
TEST_TIME 100 9643
TEST_TIME 101 9639
TEST_TIME 102 9659
TEST_TIME 103 9606
TEST_TIME 104 9564
TEST_TIME 105 9561
TEST_TIME 106 9549
TEST_TIME 107 9500
TEST_TIME 108 9443
TEST_TIME 109 9456
TEST_TIME 110 9460
TEST_TIME 111 9500
TEST_TIME 112 9489
TEST_TIME 113 9481
TEST_TIME 114 9471
TEST_TIME 115 9510
TEST_TIME 116 9476
TEST_TIME 117 9520
TEST_TIME 118 9568
TEST_TIME 119 9550
TEST_TIME 120 9553

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 02-12-2021 01:38:45 to 02-12-2021 01:38:49.

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 1589780 1589780 0 0.00 0 20133 6 0 0
doc_list_biud 1589779 1589779 0 0.00 0 50920 25 0 0
sp_add_invoice_to_stock 5266 5173 93 1.76 35 227200 17807 0 93
sp_cancel_adding_invoice 5854 5854 0 0.00 5 95541 774 0 0
sp_cancel_client_order 1858 1858 0 0.00 4 21069 359 0 0
sp_cancel_customer_reserve 576429 576429 0 0.00 1 23188 55 0 0
sp_cancel_pay_from_customer 722 722 0 0.00 2 5053 87 0 0
sp_cancel_pay_to_supplier 454 454 0 0.00 3 1062 48 0 0
sp_cancel_supplier_invoice 6406 6398 8 0.12 2 50939 765 0 8
sp_cancel_supplier_order 429 429 0 0.00 6 1182398 94375 0 0
sp_cancel_write_off 11155 11155 0 0.00 1 10200 96 0 0
sp_client_order 21397 21397 0 0.00 3 33308 356 0 0
sp_customer_reserve 478810 478810 0 0.00 0 84743 176 0 0
sp_fill_shopping_cart 32944 32944 0 0.00 1 9556 35 0 0
sp_fill_shopping_cart_clo_res 478801 478801 0 0.00 0 12793 13 0 0
sp_get_clo_for_invoice 5164 5164 0 0.00 0 28861 302 0 0
sp_kill_cost_storno 18185 18185 0 0.00 0 7299 50 0 0
sp_kill_qstorno_ret_qs2qd 1170840 1170840 0 0.00 0 50920 30 0 0
sp_kill_qty_storno 617885 617885 0 0.00 0 50920 65 0 0
sp_lock_selected_doc 21790 21790 0 0.00 0 11306 5 0 0
sp_make_cost_storno 29359 29359 0 0.00 0 20119 56 0 0
sp_multiply_rows_for_pdistr 29359 29359 0 0.00 0 9019 29 0 0
sp_multiply_rows_for_qdistr 21397 21397 0 0.00 0 11679 162 0 0
sp_pay_from_customer 7449 7449 0 0.00 4 17070 108 0 0
sp_pay_to_supplier 6148 6148 0 0.00 5 14560 133 0 0
sp_payment_common 13597 13597 0 0.00 2 17065 30 0 0
sp_qd_handle_on_cancel_clo 1858 1858 0 0.00 0 21061 324 0 0
sp_qd_handle_on_invoice_upd_sts 11018 11018 0 0.00 0 19648 330 0 0
sp_qd_handle_on_reserve_upd_sts 10598 10598 0 0.00 0 29755 62 0 0
sp_reserve_write_off 10598 10598 0 0.00 6 29953 189 0 0
sp_supplier_invoice 5713 5713 0 0.00 6 67308 3223 0 0
sp_supplier_order 5852 5852 0 0.00 5 105885 1243 0 0
srv_aggregate_perf_data 97 97 0 0.00 2499 617090 25634 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1748 152313 48236 0 0
srv_find_qd_qs_mism 1096455 1096455 0 0.00 0 15106 5 0 0
srv_make_invnt_saldo 617 617 0 0.00 43 67298 1014 0 0
srv_make_money_saldo 838 838 0 0.00 1 808 23 0 0
srv_recalc_idx_stat 4 4 0 0.00 167767 490777 286387 0 0
t$perf-abend:sp_add_invoice_to_stock 93 93 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 5173 5173 0 0.00 0 22233 168 0 0
t$perf-norm:sp_cancel_adding_invoice 420 420 0 0.00 0 9290 102 0 0
t$perf-norm:sp_cancel_client_order 1858 1858 0 0.00 0 4839 15 0 0
t$perf-norm:sp_cancel_customer_reserve 721 721 0 0.00 0 10600 33 0 0
t$perf-norm:sp_cancel_pay_from_customer 722 722 0 0.00 0 3437 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 454 454 0 0.00 0 279 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 417 417 0 0.00 0 909 12 0 0
t$perf-norm:sp_cancel_supplier_order 429 429 0 0.00 0 25270 1527 0 0
t$perf-norm:sp_cancel_write_off 1007 1007 0 0.00 0 935 5 0 0
t$perf-norm:sp_client_order 21397 21397 0 0.00 0 12835 18 0 0
t$perf-norm:sp_customer_reserve 1012 1012 0 0.00 0 4816 11 0 0
t$perf-norm:sp_pay_from_customer 7449 7449 0 0.00 0 11682 12 0 0
t$perf-norm:sp_pay_to_supplier 6148 6148 0 0.00 0 15626 13 0 0
t$perf-norm:sp_reserve_write_off 10598 10598 0 0.00 0 16868 11 0 0
t$perf-norm:sp_supplier_invoice 5713 5713 0 0.00 0 8942 20 0 0
t$perf-norm:sp_supplier_order 5852 5852 0 0.00 0 4881 15 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 1386 18 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 573 11 0 0
t$perf-norm:srv_make_invnt_saldo 617 617 0 0.00 0 1825 19 0 0
t$perf-norm:srv_make_money_saldo 838 838 0 0.00 0 4524 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 340 86 0 0
v_add_invoice_to_stock 10514 10328 186 1.76 0 8573 20 0 186
v_all_customers 38874 38874 0 0.00 0 93 0 0 0
v_all_suppliers 31800 31800 0 0.00 0 14 1 0 0
v_all_wares 42794 42794 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 840 840 0 0.00 0 143 5 0 0
v_cancel_client_order 3716 3716 0 0.00 0 4947 7 0 0
v_cancel_customer_prepayment 1444 1444 0 0.00 0 5020 14 0 0
v_cancel_customer_reserve 1442 1442 0 0.00 0 1579 5 0 0
v_cancel_payment_to_supplier 908 908 0 0.00 0 839 4 0 0
v_cancel_supplier_invoice 850 834 16 1.88 0 5461 22 0 16
v_cancel_supplier_order 858 858 0 0.00 0 4364 17 0 0
v_cancel_write_off 2014 2014 0 0.00 0 6735 16 0 0
v_max_id_clo_ord 5851 5851 0 0.00 0 438 1 0 0
v_max_id_clo_res 1012 1012 0 0.00 0 5397 25 0 0
v_max_id_ord_sup 5696 5696 0 0.00 0 4112 3 0 0
v_max_non_paid_invoice 1795 1795 0 0.00 0 372 2 0 0
v_max_non_paid_realizn 4042 4042 0 0.00 0 4729 6 0 0
v_min_id_clo_ord 5851 5851 0 0.00 0 2592 5 0 0
v_min_id_clo_res 1012 1012 0 0.00 0 1925 9 0 0
v_min_id_ord_sup 5696 5696 0 0.00 0 6567 7 0 0
v_min_non_paid_invoice 6148 6148 0 0.00 0 13999 23 0 0
v_min_non_paid_realizn 7449 7449 0 0.00 0 9353 20 0 0
v_our_firm 10734 10734 0 0.00 0 14 1 0 0
v_reserve_write_off 21196 21196 0 0.00 0 9113 8 0 0
x_lock_depdocs_on_canc_invoice 11835 11835 0 0.00 0 50344 117 0 0
x_lock_depdocs_on_canc_sup_ord 429 429 0 0.00 0 13664 314 0 0
x_make_qstorno_1000_1200 5852 5852 0 0.00 1 105871 1170 0 0
x_make_qstorno_1200_2000 5713 5713 0 0.00 3 59469 3095 0 0
x_make_qstorno_2100_3300 478810 478810 0 0.00 0 84706 161 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-12-2021 01:38:50 to 02-12-2021 01:38:54.

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
23:07:58 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:08:59 33828 8192000000 9913287792 10227093504 992430656 10.805 100 88 176 0 1022482128 7233664 22817808
23:10:38 26317 8192000000 9953901552 10279739392 1033685456 11.204 100 87 174 2 1065285616 9462416 22137744
23:12:09 19922 8192000000 9989228352 10328780800 1075925328 11.609 100 82 164 3 1107233136 11333936 19973872
23:13:36 15040 8192000000 10036633808 10383638528 1120284832 12.030 100 77 154 1 1151096304 12383520 18427952
23:14:57 7779 8192000000 10083359200 10444259328 1173673664 12.531 100 82 164 1 1204184480 11970432 18540384
23:16:09 4113 8192000000 10129406640 10496618496 1213106016 12.898 100 87 173 0 1246108992 13724032 19278944
23:17:16 2333 8192000000 10168931920 10545246208 1256536256 13.298 100 88 177 1 1290039488 13568368 19934864
23:18:21 2578 8192000000 10196487072 10575708160 1282515488 13.536 100 91 184 0 1316309872 13406720 20387664
23:19:24 32641 8192000000 10210173344 10599579648 1295915472 13.658 100 63 127 0 1318636464 8477680 14243312
23:20:57 8477 8192000000 10195887600 10606428160 1304084768 13.732 100 16 32 0 1313332848 5514112 3733968
23:22:06 26408 8192000000 10195176608 10617638912 1311999488 13.804 100 14 28 0 1321540560 5485952 4055120
23:23:58 5799 8192000000 10217509712 10633064448 1321457040 13.890 100 43 86 2 1338782608 7075328 10250240
23:25:06 10664 8192000000 10213177920 10633912320 1323793680 13.911 100 19 38 2 1333918464 5854832 4269952
23:26:17 12377 8192000000 10223103072 10642685952 1331320144 13.979 100 15 30 1 1340717328 5444736 3952448
23:27:31 56365 8192000000 10210686288 10625060864 1283390704 13.544 97 27 54 0 1298831728 9957248 5483776
23:29:29 7704 8192000000 10162021136 10562424832 1257355136 13.306 98 27 54 1 1274250304 11026432 5868736
23:30:39 11607 8192000000 10146468960 10552885248 1225907968 13.016 96 19 37 0 1236231040 5901664 4421408
23:31:51 8059 8192000000 10160076480 10563735552 1255398496 13.288 99 26 51 0 1271204368 9822080 5983792
23:33:03 16456 8192000000 10136047408 10538242048 1206134176 12.833 95 24 47 2 1217252832 5506080 5612576
23:34:21 5033 8192000000 10158528160 10564145152 1268416240 13.407 100 20 40 0 1278756480 5359552 4980688
23:35:29 6806 8192000000 10179269728 10593374208 1291499568 13.618 100 21 42 0 1302121520 5666880 4955072
23:36:36 9676 8192000000 10194661424 10609807360 1300631792 13.701 100 19 38 0 1311054032 5780400 4641840
23:37:49 30817 8192000000 10224946864 10634903552 1322743680 13.902 100 48 91 2 1339517648 5935792 10838176
23:39:25 19164 8192000000 10258018480 10666815488 1345213936 14.104 100 56 112 0 1370329632 12473168 12642528
23:40:52 8291 8192000000 10270772880 10689867776 1359142256 14.230 100 46 94 1 1383168992 13459056 10567680
23:42:05 17158 8192000000 10294504032 10711179264 1373289792 14.357 100 54 107 0 1404120512 19043376 11787344
23:43:31 8035 8192000000 10299866128 10722086912 1384491488 14.457 100 57 114 2 1418499744 20531536 13476720
23:44:45 10163 8192000000 10322464752 10744045568 1397484384 14.573 100 54 106 2 1435805296 25568160 12752752
23:46:16 8754 8192000000 10337813328 10763640832 1410918352 14.692 100 53 106 1 1452812704 28940128 12954224
23:47:29 7860 8192000000 10327019392 10755346432 1391634880 14.520 99 46 92 1 1435288272 32201840 11451552
23:48:47 8699 8192000000 10332421184 10756632576 1390295200 14.508 99 46 91 1 1435124080 33591008 11237872
23:49:59 15876 8192000000 10367043088 10784010240 1416918032 14.745 100 75 150 1 1475114960 39762256 18434672
23:51:25 4789 8192000000 10374241648 10789011456 1415684080 14.734 100 73 146 1 1480041392 46139456 18217856
23:52:38 1741 8192000000 10376515296 10786844672 1404219648 14.633 99 82 160 1 1476761920 52076000 20466272
23:53:47 4609 8192000000 10399041456 10816958464 1429500816 14.857 100 77 153 1 1507238080 57722448 20014816
23:54:57 2014 8192000000 10379887072 10797199360 1407819472 14.665 100 70 139 2 1485334912 59463088 18052352
23:56:05 11317 8192000000 10355321792 10781941760 1384045456 14.453 99 52 103 0 1456281776 58578096 13658224
23:57:32 11368 8192000000 10311884032 10745995264 1361149552 14.248 98 31 62 1 1407511584 37499264 8862768
23:59:08 20424 8192000000 10322511680 10754891776 1375926352 14.380 100 32 64 0 1432373584 46823104 9624128
00:00:35 35923 8192000000 10319812848 10761125888 1380744384 14.423 100 19 38 0 1434304864 47288592 6271888
00:02:13 21579 8192000000 10275219744 10708824064 1329418624 13.962 97 19 38 0 1353626800 19272560 4935616
00:03:37 13773 8192000000 10264329536 10696953856 1340239664 14.060 99 31 62 4 1366639584 18161648 8238272
00:04:53 76066 8192000000 10258037792 10705047552 1284124592 13.551 95 12 24 0 1305130720 18145376 2860752
00:07:14 74304 8192000000 10273293440 10699833344 1316081104 13.841 97 24 49 2 1337153328 15367296 5704928
00:09:38 71989 8192000000 10264060640 10711171072 1324264144 13.915 97 16 32 0 1341463584 14185296 3014144
00:12:12 131937 8192000000 10290858144 10737045504 1339277632 14.051 98 10 20 0 1357353376 16275632 1800112
00:15:26 55596 8192000000 10297606592 10750746624 1346826256 14.119 98 18 36 0 1371405888 20529024 4050608
00:17:28 57945 8192000000 10317041776 10756575232 1387059072 14.480 100 31 62 1 1414322256 19701312 7561872
00:19:30 51522 8192000000 10295222416 10744193024 1379348816 14.411 99 41 82 0 1401968592 13183056 9436720
00:21:23 124427 8192000000 10335669168 10772389888 1386846512 14.478 99 32 65 0 1406788864 11945952 7996400
00:24:30 10630 8192000000 10322198016 10773733376 1408453120 14.670 100 35 70 2 1431549376 14577168 8519088
00:25:40 13346 8192000000 10338336928 10779262976 1397173728 14.570 99 60 119 3 1427233536 15037184 15022624
00:26:56 34423 8192000000 10311816048 10789457920 1403503376 14.626 100 10 20 1 1428971088 22933120 2534592
00:28:44 108013 8192000000 10300704816 10756911104 1381865792 14.433 98 11 22 0 1392885824 8529376 2490656
00:31:33 69308 8192000000 10283985120 10736177152 1348368368 14.133 96 19 38 0 1360019968 7590544 4061056
00:33:44 30945 8192000000 10302079392 10751299584 1387753520 14.486 99 29 57 1 1401093056 7406816 5932720
00:35:16 64458 8192000000 10291526912 10761895936 1391809024 14.522 99 19 38 0 1406520576 9917456 4794096
00:38:43 45969 8192000000 10283241616 10742378496 1345281056 14.105 96 13 26 0 1355754944 7909504 2564384
00:40:33 53272 8192000000 10292803600 10742665216 1332473760 13.989 96 18 36 0 1347262720 10780160 4008800
00:42:30 49063 8192000000 10288866208 10743201792 1329742304 13.965 96 17 34 1 1349826208 16248880 3835024
00:44:31 41036 8192000000 10279201840 10728136704 1354000576 14.183 98 20 40 0 1376675424 18248768 4426080
00:46:30 78305 8192000000 10295905552 10722181120 1323721888 13.910 96 36 72 3 1340654240 9256416 7675936
00:48:49 73783 8192000000 10263848960 10723045376 1298216144 13.679 95 19 38 0 1317555616 15099088 4240384
00:51:06 152313 8192000000 10243480848 10679840768 1312223792 13.806 97 33 66 0 1329402208 9284032 7894384
00:54:42 61282 8192000000 10241599888 10694651904 1338075232 14.040 99 19 38 1 1350257632 7792416 4389984
00:56:48 48318 8192000000 10237553760 10690080768 1317188880 13.851 97 10 20 0 1325301696 6071888 2040928
00:58:37 98473 8192000000 10242631216 10702344192 1301847536 13.712 96 20 39 2 1312297888 6490224 3960128
01:01:15 16945 8192000000 10265406016 10704830464 1350129472 14.149 99 36 71 0 1366272848 8096352 8047024
01:02:33 45980 8192000000 10287977776 10739912704 1368243888 14.311 99 25 50 0 1392908848 18482816 6182144
01:04:19 42732 8192000000 10302046880 10752987136 1390040720 14.506 100 19 37 1 1410690864 16409216 4240928
01:06:11 43014 8192000000 10303250960 10757586944 1357804672 14.218 98 22 44 2 1388277104 25338656 5133776
01:08:00 63296 8192000000 10312846704 10756276224 1368896816 14.317 98 13 26 0 1394940528 23142592 2901120
01:10:06 79137 8192000000 10304081456 10756034560 1383080080 14.444 99 8 16 2 1406041056 21348528 1612448
01:12:25 50193 8192000000 10330224256 10782576640 1413928240 14.719 100 25 50 0 1440265520 20616560 5720720
01:14:24 78676 8192000000 10321900448 10771558400 1385534624 14.466 98 30 60 0 1404021280 10772992 7713664
01:16:43 55454 8192000000 10288685392 10750492672 1339401680 14.052 95 11 22 1 1354947616 13240416 2305520
01:18:40 62546 8192000000 10305934912 10762604544 1397608352 14.574 100 11 22 0 1413545344 13226464 2710528
01:20:55 125289 8192000000 10298590448 10758340608 1325680128 13.928 95 14 28 1 1347215344 17953024 3582192
01:24:05 86717 8192000000 10306205872 10766143488 1400599136 14.600 100 20 40 0 1413256240 8109888 4547216
01:27:41 91025 8192000000 10304541728 10772054016 1354535888 14.188 96 21 42 0 1373290800 14200912 4554000
01:30:12 34464 8192000000 10292669088 10742091776 1332521936 13.990 95 26 51 0 1346929856 9212368 5195552
01:31:52 62950 8192000000 10279651312 10727239680 1299949184 13.695 94 18 36 1 1316965120 12873472 4142464
01:33:55 50528 8192000000 10295943216 10745229312 1349597120 14.144 98 32 64 2 1373909520 17176992 7135408
01:35:50 62451 8192000000 10276778784 10719207424 1302043456 13.714 95 12 24 0 1319493168 14859872 2589840

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 02-12-2021 01:38:55 to 02-12-2021 01:39:10.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 93 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 02-12-2021 01:39:11 to 02-12-2021 01:39:13.

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          242385
MON$OLDEST_ACTIVE               245033
MON$OLDEST_SNAPSHOT             245033
MON$NEXT_TRANSACTION            245033
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                       2044832
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33540 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33540 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33540 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33540 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 ---
:669: 
:670: mscs-test-161.budzdorov.ru    Thu Dec  2 01:37:56 2021
:671:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:672: 
:673: 
:674: mscs-test-161.budzdorov.ru    Thu Dec  2 01:38:01 2021
:675:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:676: 
--- end of diff output ---

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

02.12.21 01:39:14. 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-02 01:39:16 No severe PSQL-related problems occured
02.12.21 01:39:18. End of report.