Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 26.12.2021 16: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 16235336 kB
MemAvailable 31331152 kB
Buffers 0 kB
Cached 15391092 kB
SwapCached 440 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-26 16:38:02
DTS_END                         2021-12-26 16:38:02.1700
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/38244
TRN_ID                          251830
ATT_ID                          678
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: 9468.45 21545 1136214
customer order: creation 186.56 327 22387
customer order: refuse 15.47 353 1856
order to supplier: creation 49.35 1282 5922
order to supplier: removal 3.60 110281 432
invoice (draft): creation 49.25 3412 5910
invoice (draft): removal 51.75 940 6210
invoice accept: apply 44.61 18194 5353
invoice accept: cancel 47.04 827 5645
customer reserve: creation 4061.67 183 487400
customer reserve: removal 4647.21 66 557665
realization accept: apply 88.93 173 10671
realization accept: cancel 83.88 124 10065
payment from customer: creation 63.31 133 7597
payment from customer: removal 6.31 76 757
payment to supplier: creation 53.04 147 6365
payment to supplier: removal 3.43 63 412
service: total inventory turnovers 5.21 1111 625
service: total monetary turnovers 6.88 67 826
service: aggregate perf. data 0.93 16569 112
service: refresh index statistics 0.03 276581 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 26-12-2021 16:39:07 to 26-12-2021 16:39:09.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8048
TEST_TIME 2 6937
TEST_TIME 3 8054
TEST_TIME 4 6853
TEST_TIME 5 6867
TEST_TIME 6 6434
TEST_TIME 7 6222
TEST_TIME 8 5889
TEST_TIME 9 5801
TEST_TIME 10 5777
TEST_TIME 11 6079
TEST_TIME 12 6084
TEST_TIME 13 6261
TEST_TIME 14 6421
TEST_TIME 15 6493
TEST_TIME 16 7003
TEST_TIME 17 7213
TEST_TIME 18 7604
TEST_TIME 19 7568
TEST_TIME 20 7511
TEST_TIME 21 7705
TEST_TIME 22 8862
TEST_TIME 23 8877
TEST_TIME 24 9096
TEST_TIME 25 9035
TEST_TIME 26 9746
TEST_TIME 27 10122
TEST_TIME 28 10857
TEST_TIME 29 11614
TEST_TIME 30 11323
TEST_TIME 31 11264
TEST_TIME 32 11280
TEST_TIME 33 11208
TEST_TIME 34 10951
TEST_TIME 35 11098
TEST_TIME 36 10908
TEST_TIME 37 10763
TEST_TIME 38 10802
TEST_TIME 39 10713
TEST_TIME 40 10593
TEST_TIME 41 10438
TEST_TIME 42 10544
TEST_TIME 43 10471
TEST_TIME 44 10579
TEST_TIME 45 10596
TEST_TIME 46 10539
TEST_TIME 47 10513
TEST_TIME 48 10381
TEST_TIME 49 10206
TEST_TIME 50 10150
TEST_TIME 51 10156
TEST_TIME 52 10071
TEST_TIME 53 10004
TEST_TIME 54 10007
TEST_TIME 55 10035
TEST_TIME 56 9951
TEST_TIME 57 9992
TEST_TIME 58 9934
TEST_TIME 59 9867
TEST_TIME 60 9945
TEST_TIME 61 9822
TEST_TIME 62 9787
TEST_TIME 63 9745
TEST_TIME 64 9702
TEST_TIME 65 9679
TEST_TIME 66 9738
TEST_TIME 67 9682
TEST_TIME 68 9767
TEST_TIME 69 9784
TEST_TIME 70 9749
TEST_TIME 71 9784
TEST_TIME 72 9796
TEST_TIME 73 9855
TEST_TIME 74 9937
TEST_TIME 75 9917
TEST_TIME 76 9949
TEST_TIME 77 10007
TEST_TIME 78 9968
TEST_TIME 79 9922
TEST_TIME 80 9872
TEST_TIME 81 9851
TEST_TIME 82 9804
TEST_TIME 83 9793
TEST_TIME 84 9748
TEST_TIME 85 9694
TEST_TIME 86 9666
TEST_TIME 87 9608
TEST_TIME 88 9608
TEST_TIME 89 9546
TEST_TIME 90 9549
TEST_TIME 91 9586
TEST_TIME 92 9569
TEST_TIME 93 9575
TEST_TIME 94 9589
TEST_TIME 95 9617
TEST_TIME 96 9651
TEST_TIME 97 9656
TEST_TIME 98 9639
TEST_TIME 99 9677
TEST_TIME 100 9716
TEST_TIME 101 9700
TEST_TIME 102 9756
TEST_TIME 103 9733
TEST_TIME 104 9813
TEST_TIME 105 9789
TEST_TIME 106 9747
TEST_TIME 107 9701
TEST_TIME 108 9681
TEST_TIME 109 9668
TEST_TIME 110 9671
TEST_TIME 111 9628
TEST_TIME 112 9599
TEST_TIME 113 9588
TEST_TIME 114 9549
TEST_TIME 115 9559
TEST_TIME 116 9556
TEST_TIME 117 9536
TEST_TIME 118 9550
TEST_TIME 119 9548
TEST_TIME 120 9469

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 26-12-2021 16:39:10 to 26-12-2021 16:39:14.

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 1588978 1588978 0 0.00 0 21767 7 0 0
doc_list_biud 1588978 1588978 0 0.00 0 30676 29 0 0
sp_add_invoice_to_stock 5472 5353 119 2.17 41 168818 18195 0 119
sp_cancel_adding_invoice 5645 5645 0 0.00 5 80643 827 0 0
sp_cancel_client_order 1856 1856 0 0.00 4 11723 354 0 0
sp_cancel_customer_reserve 557665 557665 0 0.00 1 30687 66 0 0
sp_cancel_pay_from_customer 757 757 0 0.00 2 6461 76 0 0
sp_cancel_pay_to_supplier 412 412 0 0.00 2 2546 63 0 0
sp_cancel_supplier_invoice 6221 6210 11 0.17 2 25955 940 0 11
sp_cancel_supplier_order 432 432 0 0.00 8 1240957 110282 0 0
sp_cancel_write_off 10065 10065 0 0.00 1 10223 125 0 0
sp_client_order 22387 22387 0 0.00 3 20022 327 0 0
sp_customer_reserve 487400 487400 0 0.00 0 125866 183 0 0
sp_fill_shopping_cart 34199 34199 0 0.00 1 14972 37 0 0
sp_fill_shopping_cart_clo_res 487397 487397 0 0.00 0 12008 12 0 0
sp_get_clo_for_invoice 5350 5350 0 0.00 0 27334 277 0 0
sp_kill_cost_storno 16879 16879 0 0.00 0 5768 65 0 0
sp_kill_qstorno_ret_qs2qd 1132037 1132037 0 0.00 0 30633 36 0 0
sp_kill_qty_storno 597891 597891 0 0.00 0 30674 77 0 0
sp_lock_selected_doc 22070 22070 0 0.00 0 9651 6 0 0
sp_make_cost_storno 29980 29980 0 0.00 0 21765 63 0 0
sp_multiply_rows_for_pdistr 29980 29980 0 0.00 0 6575 28 0 0
sp_multiply_rows_for_qdistr 22387 22387 0 0.00 0 12076 136 0 0
sp_pay_from_customer 7597 7597 0 0.00 4 21809 134 0 0
sp_pay_to_supplier 6365 6365 0 0.00 5 21773 148 0 0
sp_payment_common 13962 13962 0 0.00 1 21794 33 0 0
sp_qd_handle_on_cancel_clo 1856 1856 0 0.00 0 11665 324 0 0
sp_qd_handle_on_invoice_upd_sts 10992 10992 0 0.00 0 19576 334 0 0
sp_qd_handle_on_reserve_upd_sts 10671 10671 0 0.00 0 23317 52 0 0
sp_reserve_write_off 10671 10671 0 0.00 6 23377 174 0 0
sp_supplier_invoice 5910 5910 0 0.00 7 80254 3413 0 0
sp_supplier_order 5922 5922 0 0.00 5 24859 1282 0 0
srv_aggregate_perf_data 112 112 0 0.00 2362 173507 16569 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 2165 159096 40115 0 0
srv_find_qd_qs_mism 1085880 1085880 0 0.00 0 15058 6 0 0
srv_make_invnt_saldo 625 625 0 0.00 27 111411 1112 0 0
srv_make_money_saldo 826 826 0 0.00 0 18722 67 0 0
srv_recalc_idx_stat 4 4 0 0.00 147548 393656 276581 0 0
t$perf-abend:sp_add_invoice_to_stock 119 119 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5353 5353 0 0.00 0 12269 166 0 0
t$perf-norm:sp_cancel_adding_invoice 434 434 0 0.00 0 6023 109 0 0
t$perf-norm:sp_cancel_client_order 1856 1856 0 0.00 0 6625 16 0 0
t$perf-norm:sp_cancel_customer_reserve 698 698 0 0.00 0 2114 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 757 757 0 0.00 0 286 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 412 412 0 0.00 0 583 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 376 376 0 0.00 0 880 15 0 0
t$perf-norm:sp_cancel_supplier_order 432 432 0 0.00 0 30000 1581 0 0
t$perf-norm:sp_cancel_write_off 1088 1088 0 0.00 0 2848 11 0 0
t$perf-norm:sp_client_order 22387 22387 0 0.00 0 7280 18 0 0
t$perf-norm:sp_customer_reserve 1090 1090 0 0.00 0 3476 5 0 0
t$perf-norm:sp_pay_from_customer 7597 7597 0 0.00 0 6547 9 0 0
t$perf-norm:sp_pay_to_supplier 6365 6365 0 0.00 0 10652 10 0 0
t$perf-norm:sp_reserve_write_off 10671 10671 0 0.00 0 7230 9 0 0
t$perf-norm:sp_supplier_invoice 5910 5910 0 0.00 0 5588 20 0 0
t$perf-norm:sp_supplier_order 5922 5922 0 0.00 0 6491 14 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 154 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 227 7 0 0
t$perf-norm:srv_make_invnt_saldo 625 625 0 0.00 0 1394 22 0 0
t$perf-norm:srv_make_money_saldo 826 826 0 0.00 0 1475 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 10 5 0 0
v_add_invoice_to_stock 10930 10692 238 2.17 0 11877 26 0 238
v_all_customers 40922 40922 0 0.00 0 111 1 0 0
v_all_suppliers 32928 32928 0 0.00 0 15 1 0 0
v_all_wares 44774 44774 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 868 868 0 0.00 0 4974 12 0 0
v_cancel_client_order 3712 3712 0 0.00 0 6130 5 0 0
v_cancel_customer_prepayment 1514 1514 0 0.00 0 4235 9 0 0
v_cancel_customer_reserve 1396 1396 0 0.00 0 477 3 0 0
v_cancel_payment_to_supplier 824 824 0 0.00 0 2541 8 0 0
v_cancel_supplier_invoice 774 752 22 2.84 0 1133 16 0 22
v_cancel_supplier_order 864 864 0 0.00 0 1393 7 0 0
v_cancel_write_off 2176 2176 0 0.00 0 2470 15 0 0
v_max_id_clo_ord 5922 5922 0 0.00 0 1463 2 0 0
v_max_id_clo_res 1089 1089 0 0.00 0 4207 18 0 0
v_max_id_ord_sup 5888 5888 0 0.00 0 3387 5 0 0
v_max_non_paid_invoice 1711 1711 0 0.00 0 161 2 0 0
v_max_non_paid_realizn 3947 3947 0 0.00 0 2560 4 0 0
v_min_id_clo_ord 5922 5922 0 0.00 0 2943 7 0 0
v_min_id_clo_res 1089 1089 0 0.00 0 3429 11 0 0
v_min_id_ord_sup 5888 5888 0 0.00 0 5489 11 0 0
v_min_non_paid_invoice 6365 6365 0 0.00 0 13252 23 0 0
v_min_non_paid_realizn 7597 7597 0 0.00 0 21790 27 0 0
v_our_firm 11152 11152 0 0.00 0 24 1 0 0
v_reserve_write_off 21342 21342 0 0.00 0 13998 7 0 0
x_lock_depdocs_on_canc_invoice 11479 11479 0 0.00 0 18440 131 0 0
x_lock_depdocs_on_canc_sup_ord 432 432 0 0.00 0 12099 429 0 0
x_make_qstorno_1000_1200 5922 5922 0 0.00 2 24851 1205 0 0
x_make_qstorno_1200_2000 5910 5910 0 0.00 3 79995 3277 0 0
x_make_qstorno_2100_3300 487400 487400 0 0.00 0 125864 168 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-12-2021 16:39:15 to 26-12-2021 16:39:19.

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 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:06 24634 8192000000 9915649888 10232909824 998388464 10.863 100 87 172 1 1026981760 5841728 22751568
14:10:35 28808 8192000000 9967705392 10295603200 1050242512 11.363 100 88 176 0 1080819376 8314080 22262784
14:12:13 12902 8192000000 10003946336 10338824192 1086266336 11.707 100 88 176 0 1118638976 10463328 21909312
14:13:32 11190 8192000000 10045751712 10397974528 1135959872 12.178 100 77 154 2 1165082816 10736384 18386560
14:14:46 8139 8192000000 10102896432 10462806016 1181920528 12.608 100 87 174 1 1215502240 13127648 20454064
14:15:57 4905 8192000000 10138220704 10512699392 1228867184 13.044 100 81 160 0 1258413744 11681216 17865344
14:17:04 3637 8192000000 10184216640 10562641920 1261426144 13.343 100 81 163 1 1293605216 14228736 17950336
14:18:08 1884 8192000000 10209061776 10593394688 1294884496 13.649 100 95 189 0 1329122544 13035440 21202608
14:19:11 28011 8192000000 10193008192 10605776896 1308210816 13.770 100 42 84 6 1325840848 7864016 9766016
14:20:40 5247 8192000000 10208560944 10623631360 1318365392 13.862 100 36 72 2 1334336944 7474512 8497040
14:21:46 5600 8192000000 10208301216 10625179648 1324742848 13.920 100 22 44 0 1335875264 5908720 5223696
14:22:53 8090 8192000000 10209218432 10632519680 1329167296 13.960 100 12 24 0 1337469712 5786800 2515616
14:24:03 31901 8192000000 10249360736 10657808384 1335455984 14.016 100 68 140 0 1358241824 6481904 16303936
14:25:35 6725 8192000000 10233331056 10656727040 1340731440 14.064 100 25 49 2 1352714592 6558480 5424672
14:26:43 12289 8192000000 10227571728 10647851008 1326523888 13.936 99 20 38 2 1337244768 5906992 4813888
14:27:56 53136 8192000000 10205334960 10614300672 1286362320 13.571 98 37 74 2 1301960592 6322992 9275280
14:29:51 5702 8192000000 10166444032 10572451840 1250114544 13.239 98 38 76 0 1269883856 10824912 8944400
14:31:02 7441 8192000000 10154564736 10555215872 1230869984 13.062 97 28 55 2 1242618080 5706176 6041920
14:32:12 10784 8192000000 10139527536 10534719488 1231407056 13.067 98 18 36 0 1241140784 5205296 4528432
14:33:26 23153 8192000000 10161993792 10564419584 1226379312 13.021 97 11 22 1 1233313328 4540432 2393584
14:34:53 6995 8192000000 10180465056 10592600064 1285750176 13.565 100 27 54 3 1301465520 10502752 5212592
14:36:04 6979 8192000000 10187419344 10600718336 1295262912 13.652 100 23 46 0 1306439104 5245712 5930480
14:37:13 45400 8192000000 10206951984 10621747200 1310966064 13.795 100 19 38 2 1320794704 4767456 5061184
14:39:22 27171 8192000000 10252855776 10665078784 1346176176 14.113 100 59 117 0 1369431152 10140016 13114960
14:40:54 12393 8192000000 10282145408 10693173248 1362168928 14.257 100 72 143 2 1391297424 13544624 15583872
14:42:11 14050 8192000000 10289370064 10707083264 1374071520 14.364 100 58 114 4 1404195968 16519920 13604528
14:43:28 4036 8192000000 10301655648 10723430400 1385331216 14.464 100 42 84 1 1416693344 21144560 10217568
14:44:38 6050 8192000000 10328750448 10744131584 1399043488 14.586 100 67 130 5 1439436672 25016976 15376208
14:45:52 8886 8192000000 10314524256 10737352704 1390415792 14.510 100 46 91 1 1427604688 26285344 10903552
14:47:07 8440 8192000000 10330733808 10750115840 1398097536 14.578 100 57 115 2 1442580048 29422224 15060288
14:48:23 3048 8192000000 10345790480 10768048128 1410997424 14.693 100 51 100 1 1458613216 34547648 13068144
14:49:29 3211 8192000000 10325550752 10734108672 1376991056 14.390 100 68 134 3 1433102800 39564464 16547280
14:50:39 3410 8192000000 10357663360 10766348288 1398286192 14.580 100 81 161 1 1464301168 45838112 20176864
14:51:48 4741 8192000000 10352774864 10758045696 1379970784 14.416 99 69 137 1 1447578464 50671664 16936016
14:52:56 5700 8192000000 10355515216 10766798848 1391017504 14.515 100 61 122 3 1461309376 54372928 15918944
14:54:06 3344 8192000000 10378237504 10784219136 1401310592 14.607 100 83 164 1 1481143296 59187520 20645184
14:55:16 3987 8192000000 10381162928 10789347328 1405207920 14.641 100 70 139 1 1481005712 57521920 18275872
14:56:24 8291 8192000000 10360792464 10773831680 1393003200 14.533 99 47 93 3 1463051872 57472048 12576624
14:57:42 6096 8192000000 10365823600 10775891968 1379621392 14.413 99 67 135 1 1458356496 59569312 19165792
14:58:56 2164 8192000000 10392835280 10799169536 1398740928 14.584 100 84 161 2 1494493424 74023264 21729232
15:00:03 9455 8192000000 10382794928 10814615552 1392260464 14.526 99 54 108 1 1486685712 79337520 15087728
15:01:15 13342 8192000000 10363992656 10794119168 1400478624 14.599 100 34 68 0 1467373632 56821888 10073120
15:02:30 6269 8192000000 10363282144 10789675008 1389952544 14.505 100 48 96 0 1462075248 59307952 12814752
15:03:40 20790 8192000000 10354718688 10793791488 1379452624 14.412 100 32 64 1 1457428928 67998688 9977616
15:05:02 20473 8192000000 10334689696 10775195648 1368948640 14.318 100 30 60 0 1436749408 59235104 8565664
15:06:28 23158 8192000000 10316317456 10765049856 1364727664 14.280 100 26 52 0 1425164128 53350192 7086272
15:07:57 53198 8192000000 10292567536 10728603648 1357918720 14.219 99 27 55 1 1378943376 13009760 8014896
15:09:51 25626 8192000000 10299097840 10754449408 1380689616 14.423 100 15 30 0 1398848432 13894880 4263936
15:11:30 73309 8192000000 10323772592 10770255872 1392579312 14.529 100 11 22 0 1412616384 17567120 2469952
15:13:55 55809 8192000000 10294953328 10746449920 1368186320 14.311 98 32 62 1 1385381920 9836448 7359152
15:15:52 127532 8192000000 10300107904 10756800512 1382374720 14.438 99 14 28 0 1398130192 12833040 2922432
15:19:00 51019 8192000000 10301345664 10741530624 1351057744 14.157 97 19 39 0 1365480352 10386752 4035856
15:20:55 51591 8192000000 10307698432 10752610304 1359913824 14.237 98 14 28 0 1373348960 10339808 3095328
15:22:47 159096 8192000000 10315052672 10764570624 1369416864 14.322 98 5 10 0 1387999360 17576704 1005792
15:26:39 89284 8192000000 10311867152 10748940288 1382754960 14.441 99 41 81 1 1398224832 6634912 8834960
15:29:09 61671 8192000000 10293679424 10738147328 1350303136 14.150 97 25 50 0 1368688560 12840352 5545072
15:31:14 107058 8192000000 10273230320 10720735232 1312974000 13.813 94 20 39 0 1326650864 8819888 4856976
15:34:05 17630 8192000000 10295250256 10735190016 1380657024 14.422 100 44 89 0 1398397856 7030256 10710576
15:35:27 32772 8192000000 10284043472 10738266112 1368214368 14.311 99 34 66 1 1386859904 11324160 7321376
15:37:02 34776 8192000000 10280319168 10742308864 1384779456 14.459 100 10 20 0 1396213632 9078160 2356016
15:38:39 28592 8192000000 10260770896 10728906752 1335424816 14.016 97 9 17 0 1346239360 9266608 1547936
15:40:08 70045 8192000000 10271137376 10733338624 1354648128 14.189 98 17 34 0 1372183216 14202368 3332720
15:42:21 36386 8192000000 10274711888 10732896256 1355205216 14.194 98 16 32 0 1370819712 12264208 3350288
15:44:05 39048 8192000000 10250759184 10709962752 1316592240 13.846 96 14 28 1 1329140496 9444640 3103616
15:45:54 27072 8192000000 10281197232 10727632896 1361645968 14.252 100 50 100 0 1390495440 17493648 11355824
15:47:23 51141 8192000000 10272123664 10713493504 1324949904 13.922 97 24 48 0 1338635600 8180448 5505248
15:49:28 57608 8192000000 10280706592 10740527104 1357275744 14.213 99 16 32 1 1380371264 18879344 4216176
15:51:47 34274 8192000000 10276032816 10728898560 1341062368 14.067 98 20 40 1 1359991120 14347536 4581216
15:53:29 46509 8192000000 10293267664 10743328768 1371085648 14.337 100 20 40 0 1392632656 16663232 4883776
15:55:28 81590 8192000000 10294628976 10733654016 1319326256 13.871 96 17 35 0 1337020096 14209216 3484624
15:57:54 113350 8192000000 10286884192 10736046080 1370838880 14.335 100 24 48 0 1387698032 11697840 5161312
16:00:56 51198 8192000000 10290614976 10748043264 1385859104 14.469 100 31 62 0 1402519216 9941056 6719056
16:02:47 50827 8192000000 10285391056 10740154368 1372199104 14.347 99 12 24 0 1385306352 10312112 2795136
16:04:39 64109 8192000000 10269070480 10724876288 1335306064 14.015 97 11 22 0 1348653760 10679136 2668560
16:06:50 94438 8192000000 10283054144 10744524800 1383659776 14.449 100 18 37 1 1400203504 12055920 4487808
16:09:40 52533 8192000000 10280822112 10737156096 1372516432 14.350 99 21 42 0 1385220784 8276256 4428096
16:11:38 58464 8192000000 10286762112 10737369088 1349025792 14.139 97 20 40 1 1363893872 10288640 4579440
16:13:51 34415 8192000000 10283244896 10740056064 1348836752 14.137 97 17 34 0 1362680928 9865888 3978288
16:15:25 15602 8192000000 10271372368 10715160576 1349435136 14.142 99 37 74 1 1366360288 9025728 7899424
16:16:41 24199 8192000000 10306286768 10747260928 1382910000 14.443 100 42 84 0 1402876864 11140192 8826672
16:18:12 45111 8192000000 10302382560 10757525504 1389336368 14.500 100 20 41 0 1405646208 11869904 4439936
16:20:06 55213 8192000000 10286802704 10739879936 1346222912 14.113 97 16 32 1 1366819184 17029168 3567104
16:22:02 38464 8192000000 10284280736 10738057216 1337118496 14.031 97 22 44 0 1359934768 17840512 4975760
16:23:45 104528 8192000000 10263102800 10715283456 1334941216 14.012 97 31 60 0 1349356096 7287648 7127232
16:26:47 51317 8192000000 10270057936 10716082176 1346449776 14.116 99 37 74 1 1369429392 14762608 8217008
16:28:39 40164 8192000000 10252958176 10718830592 1340973808 14.066 99 9 18 0 1354601520 11941296 1686416
16:30:21 49752 8192000000 10253251456 10700656640 1304196416 13.733 96 16 32 1 1321496912 13511040 3789456
16:32:14 55907 8192000000 10221365136 10682548224 1318086544 13.859 99 11 22 0 1328862496 8243392 2532560
16:34:21 57191 8192000000 10262524880 10712440832 1339350128 14.052 99 15 30 0 1353015088 9958224 3706736
16:36:21 17289 8192000000 10266129056 10710007808 1341803328 14.074 99 34 68 0 1358692048 9364848 7523872
16:37:48 22504 8192000000 9235907856 9510121472 396905440 4.621 30 15 29 0 405597584 5332176 3359968

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 26-12-2021 16:39:21 to 26-12-2021 16:39:36.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 119 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 26-12-2021 16:39:37 to 26-12-2021 16:39:39.

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          252218
MON$OLDEST_ACTIVE               252219
MON$OLDEST_SNAPSHOT             252219
MON$NEXT_TRANSACTION            252219
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                       2062688
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.33547 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33547 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33547 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33547 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 ---
:178: 
:179: mscs-test-161.budzdorov.ru    Sun Dec 26 16:38:12 2021
:180:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:181: 
:182: 
:183: mscs-test-161.budzdorov.ru    Sun Dec 26 16:38:18 2021
:184:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:185: 
:186: 
:187: mscs-test-161.budzdorov.ru    Sun Dec 26 16:38:19 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'

26.12.21 16:39:40. 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-26 16:39:42 No severe PSQL-related problems occured
26.12.21 16:39:44. End of report.