Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 26.08.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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 16199200 kB
MemAvailable 30917120 kB
Buffers 0 kB
Cached 15455680 kB
SwapCached 1308 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-08-26 01:37:48
DTS_END                         2021-08-26 01:37:48.0290
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/53984
TRN_ID                          241834
ATT_ID                          717
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: 9587.47 20573 1150496
customer order: creation 178.41 380 21409
customer order: refuse 15.78 386 1894
order to supplier: creation 47.05 1394 5646
order to supplier: removal 3.46 102445 415
invoice (draft): creation 47.76 3476 5731
invoice (draft): removal 53.97 773 6476
invoice accept: apply 43.68 17076 5242
invoice accept: cancel 49.14 855 5897
customer reserve: creation 4032.18 168 483861
customer reserve: removal 4806.37 59 576764
realization accept: apply 86.87 186 10424
realization accept: cancel 89.41 103 10729
payment from customer: creation 60.98 131 7317
payment from customer: removal 6.03 119 723
payment to supplier: creation 50.50 121 6060
payment to supplier: removal 3.42 59 410
service: total inventory turnovers 4.99 1053 599
service: total monetary turnovers 6.64 26 797
service: aggregate perf. data 0.82 19806 98
service: refresh index statistics 0.03 262847 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 26-08-2021 01:38:46 to 26-08-2021 01:38:48.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5367
TEST_TIME 2 4639
TEST_TIME 3 4670
TEST_TIME 4 4856
TEST_TIME 5 5243
TEST_TIME 6 5502
TEST_TIME 7 5743
TEST_TIME 8 6083
TEST_TIME 9 6202
TEST_TIME 10 6366
TEST_TIME 11 6492
TEST_TIME 12 6756
TEST_TIME 13 6632
TEST_TIME 14 6821
TEST_TIME 15 6980
TEST_TIME 16 6948
TEST_TIME 17 7647
TEST_TIME 18 8220
TEST_TIME 19 8488
TEST_TIME 20 8505
TEST_TIME 21 8629
TEST_TIME 22 8838
TEST_TIME 23 9158
TEST_TIME 24 9408
TEST_TIME 25 9556
TEST_TIME 26 9972
TEST_TIME 27 10083
TEST_TIME 28 10908
TEST_TIME 29 10700
TEST_TIME 30 10578
TEST_TIME 31 10445
TEST_TIME 32 10193
TEST_TIME 33 10478
TEST_TIME 34 10431
TEST_TIME 35 10395
TEST_TIME 36 10287
TEST_TIME 37 10193
TEST_TIME 38 9995
TEST_TIME 39 9987
TEST_TIME 40 9875
TEST_TIME 41 9892
TEST_TIME 42 10038
TEST_TIME 43 10175
TEST_TIME 44 10235
TEST_TIME 45 10083
TEST_TIME 46 10134
TEST_TIME 47 10108
TEST_TIME 48 10023
TEST_TIME 49 10074
TEST_TIME 50 10014
TEST_TIME 51 10156
TEST_TIME 52 10137
TEST_TIME 53 10091
TEST_TIME 54 10308
TEST_TIME 55 10300
TEST_TIME 56 10313
TEST_TIME 57 10247
TEST_TIME 58 10232
TEST_TIME 59 10226
TEST_TIME 60 10111
TEST_TIME 61 10079
TEST_TIME 62 9970
TEST_TIME 63 9927
TEST_TIME 64 9980
TEST_TIME 65 9911
TEST_TIME 66 9795
TEST_TIME 67 9719
TEST_TIME 68 9696
TEST_TIME 69 9693
TEST_TIME 70 9843
TEST_TIME 71 9833
TEST_TIME 72 9821
TEST_TIME 73 9792
TEST_TIME 74 9818
TEST_TIME 75 9875
TEST_TIME 76 9878
TEST_TIME 77 9867
TEST_TIME 78 9845
TEST_TIME 79 9907
TEST_TIME 80 9934
TEST_TIME 81 9979
TEST_TIME 82 9896
TEST_TIME 83 9855
TEST_TIME 84 9769
TEST_TIME 85 9790
TEST_TIME 86 9749
TEST_TIME 87 9693
TEST_TIME 88 9695
TEST_TIME 89 9687
TEST_TIME 90 9623
TEST_TIME 91 9668
TEST_TIME 92 9652
TEST_TIME 93 9644
TEST_TIME 94 9663
TEST_TIME 95 9671
TEST_TIME 96 9713
TEST_TIME 97 9711
TEST_TIME 98 9704
TEST_TIME 99 9685
TEST_TIME 100 9737
TEST_TIME 101 9811
TEST_TIME 102 9812
TEST_TIME 103 9804
TEST_TIME 104 9806
TEST_TIME 105 9748
TEST_TIME 106 9706
TEST_TIME 107 9688
TEST_TIME 108 9644
TEST_TIME 109 9631
TEST_TIME 110 9598
TEST_TIME 111 9596
TEST_TIME 112 9561
TEST_TIME 113 9539
TEST_TIME 114 9534
TEST_TIME 115 9523
TEST_TIME 116 9487
TEST_TIME 117 9488
TEST_TIME 118 9519
TEST_TIME 119 9571
TEST_TIME 120 9590

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 26-08-2021 01:38:49 to 26-08-2021 01:38:53.

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 1596697 1596697 0 0.00 0 15053 6 0 0
doc_list_biud 1596692 1596692 0 0.00 0 22220 27 0 0
sp_add_invoice_to_stock 5380 5242 138 2.56 43 136738 17076 0 138
sp_cancel_adding_invoice 5897 5897 0 0.00 5 61441 855 0 0
sp_cancel_client_order 1894 1894 0 0.00 4 14394 386 0 0
sp_cancel_customer_reserve 576764 576764 0 0.00 1 22223 60 0 0
sp_cancel_pay_from_customer 723 723 0 0.00 2 8795 119 0 0
sp_cancel_pay_to_supplier 410 410 0 0.00 3 1966 60 0 0
sp_cancel_supplier_invoice 6488 6476 12 0.18 2 16647 774 0 12
sp_cancel_supplier_order 415 415 0 0.00 4 848344 102446 0 0
sp_cancel_write_off 10729 10729 0 0.00 1 13009 104 0 0
sp_client_order 21409 21409 0 0.00 3 16908 380 0 0
sp_customer_reserve 483861 483861 0 0.00 0 53118 168 0 0
sp_fill_shopping_cart 32762 32762 0 0.00 1 12906 38 0 0
sp_fill_shopping_cart_clo_res 483846 483846 0 0.00 0 15994 15 0 0
sp_get_clo_for_invoice 5227 5227 0 0.00 0 23298 277 0 0
sp_kill_cost_storno 17759 17759 0 0.00 0 9426 55 0 0
sp_kill_qstorno_ret_qs2qd 1171148 1171148 0 0.00 0 22138 32 0 0
sp_kill_qty_storno 617826 617826 0 0.00 0 22220 70 0 0
sp_lock_selected_doc 21689 21689 0 0.00 0 10583 8 0 0
sp_make_cost_storno 29028 29028 0 0.00 0 13584 54 0 0
sp_multiply_rows_for_pdistr 29028 29028 0 0.00 0 9118 31 0 0
sp_multiply_rows_for_qdistr 21409 21409 0 0.00 0 16669 192 0 0
sp_pay_from_customer 7317 7317 0 0.00 4 21870 132 0 0
sp_pay_to_supplier 6060 6060 0 0.00 5 11331 121 0 0
sp_payment_common 13377 13377 0 0.00 1 21863 30 0 0
sp_qd_handle_on_cancel_clo 1894 1894 0 0.00 0 14369 347 0 0
sp_qd_handle_on_invoice_upd_sts 11124 11124 0 0.00 0 16525 323 0 0
sp_qd_handle_on_reserve_upd_sts 10424 10424 0 0.00 0 18342 60 0 0
sp_reserve_write_off 10424 10424 0 0.00 6 19587 187 0 0
sp_supplier_invoice 5731 5731 0 0.00 6 98260 3477 0 0
sp_supplier_order 5646 5646 0 0.00 5 77674 1394 0 0
srv_aggregate_perf_data 98 98 0 0.00 2903 161153 19807 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 1005 122792 42352 0 0
srv_find_qd_qs_mism 1100021 1100021 0 0.00 0 15052 5 0 0
srv_make_invnt_saldo 599 599 0 0.00 33 37447 1054 0 0
srv_make_money_saldo 797 797 0 0.00 1 2038 27 0 0
srv_recalc_idx_stat 4 4 0 0.00 175505 331249 262848 0 0
t$perf-abend:sp_add_invoice_to_stock 138 138 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5242 5242 0 0.00 0 9732 150 0 0
t$perf-norm:sp_cancel_adding_invoice 422 422 0 0.00 0 4468 117 0 0
t$perf-norm:sp_cancel_client_order 1894 1894 0 0.00 0 7910 17 0 0
t$perf-norm:sp_cancel_customer_reserve 698 698 0 0.00 0 2641 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 723 723 0 0.00 0 2506 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 410 410 0 0.00 0 201 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 414 414 0 0.00 0 4095 42 0 0
t$perf-norm:sp_cancel_supplier_order 415 415 0 0.00 0 18581 1578 0 0
t$perf-norm:sp_cancel_write_off 1062 1062 0 0.00 0 1998 6 0 0
t$perf-norm:sp_client_order 21409 21409 0 0.00 0 6832 19 0 0
t$perf-norm:sp_customer_reserve 1061 1061 0 0.00 0 4334 9 0 0
t$perf-norm:sp_pay_from_customer 7317 7317 0 0.00 0 4752 9 0 0
t$perf-norm:sp_pay_to_supplier 6060 6060 0 0.00 0 9533 14 0 0
t$perf-norm:sp_reserve_write_off 10424 10424 0 0.00 0 6943 12 0 0
t$perf-norm:sp_supplier_invoice 5731 5731 0 0.00 0 3749 17 0 0
t$perf-norm:sp_supplier_order 5646 5646 0 0.00 0 3050 13 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 3211 53 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 191 5 0 0
t$perf-norm:srv_make_invnt_saldo 599 599 0 0.00 0 924 22 0 0
t$perf-norm:srv_make_money_saldo 797 797 0 0.00 0 1162 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 10730 10454 276 2.57 0 7480 22 0 276
v_all_customers 38594 38594 0 0.00 0 17 1 0 0
v_all_suppliers 31368 31368 0 0.00 0 16 1 0 0
v_all_wares 42818 42818 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 844 844 0 0.00 0 3543 11 0 0
v_cancel_client_order 3788 3788 0 0.00 0 4323 10 0 0
v_cancel_customer_prepayment 1446 1446 0 0.00 0 4255 12 0 0
v_cancel_customer_reserve 1396 1396 0 0.00 0 3624 13 0 0
v_cancel_payment_to_supplier 820 820 0 0.00 0 283 4 0 0
v_cancel_supplier_invoice 852 828 24 2.81 0 8865 35 0 24
v_cancel_supplier_order 830 830 0 0.00 0 3341 21 0 0
v_cancel_write_off 2124 2124 0 0.00 0 3876 17 0 0
v_max_id_clo_ord 5643 5643 0 0.00 0 4508 3 0 0
v_max_id_clo_res 1061 1061 0 0.00 0 3089 24 0 0
v_max_id_ord_sup 5710 5710 0 0.00 0 5128 5 0 0
v_max_non_paid_invoice 1729 1729 0 0.00 0 3241 6 0 0
v_max_non_paid_realizn 4095 4095 0 0.00 0 1422 3 0 0
v_min_id_clo_ord 5643 5643 0 0.00 0 9069 8 0 0
v_min_id_clo_res 1061 1061 0 0.00 0 5851 18 0 0
v_min_id_ord_sup 5710 5710 0 0.00 0 6146 11 0 0
v_min_non_paid_invoice 6060 6060 0 0.00 0 6187 17 0 0
v_min_non_paid_realizn 7317 7317 0 0.00 0 21860 23 0 0
v_our_firm 10668 10668 0 0.00 0 14 1 0 0
v_reserve_write_off 20848 20848 0 0.00 0 5986 9 0 0
x_lock_depdocs_on_canc_invoice 11959 11959 0 0.00 0 17465 103 0 0
x_lock_depdocs_on_canc_sup_ord 415 415 0 0.00 0 17225 361 0 0
x_make_qstorno_1000_1200 5646 5646 0 0.00 1 77630 1307 0 0
x_make_qstorno_1200_2000 5731 5731 0 0.00 3 90939 3341 0 0
x_make_qstorno_2100_3300 483861 483861 0 0.00 0 53116 151 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-08-2021 01:38:54 to 26-08-2021 01:38:58.

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:51 2 8192000000 8799814064 8810823680 6259056 0.076 2 1 2 0 6378320 56704 62560
23:08:52 18765 8192000000 10375119792 10692767744 1404861184 14.638 100 88 176 0 1443420992 9987888 28571920
23:10:15 18181 8192000000 10422496448 10750447616 1463390640 15.156 100 91 182 1 1501654576 8592000 29671936
23:11:36 23355 8192000000 10458407680 10801483776 1500359744 15.479 100 77 154 1 1533892240 9461648 24070848
23:13:05 22434 8192000000 10576293408 10931335168 1600798128 16.346 100 75 150 2 1633545488 10285808 22461552
23:14:30 7378 8192000000 10643280176 11011760128 1686269520 17.070 100 77 154 0 1721835120 12858624 22706976
23:15:40 5959 8192000000 10690620960 11065290752 1731681136 17.449 100 77 152 4 1766464784 12909328 21874320
23:16:47 3604 8192000000 10740957008 11121782784 1783255440 17.876 100 89 177 1 1822275472 13958448 25061584
23:17:52 2604 8192000000 10774884016 11161948160 1818225584 18.163 100 90 181 0 1857676432 14558128 24892720
23:18:57 1380 8192000000 10811028880 11196121088 1845381056 18.385 100 91 185 2 1883902720 13093120 25428544
23:19:59 8407 8192000000 10786246944 11197775872 1849419376 18.417 100 41 82 1 1870173408 9425440 11328592
23:21:09 4080 8192000000 10798455136 11214917632 1862230096 18.521 100 29 58 0 1877621536 7645360 7746080
23:22:14 8875 8192000000 10804838576 11230818304 1876567488 18.637 100 30 60 2 1891858592 6866224 8424880
23:23:25 29170 8192000000 10820799664 11250663424 1882081376 18.682 100 43 87 1 1900115312 5619680 12414256
23:24:56 7346 8192000000 10819207456 11258134528 1888596096 18.734 100 26 50 3 1903061712 6649488 7816128
23:26:05 9952 8192000000 10820141136 11256643584 1896194928 18.796 99 20 40 1 1908076016 6448352 5432736
23:27:15 16675 8192000000 10814586352 11248345088 1882806720 18.688 99 17 34 0 1894455440 5584176 6064544
23:28:33 19019 8192000000 10799216736 11228229632 1853640880 18.452 98 18 36 2 1864204496 5569216 4994400
23:29:53 8572 8192000000 10749286096 11177689088 1784153424 17.884 96 23 46 2 1796446832 5247440 7045968
23:31:01 6948 8192000000 10692666640 11097985024 1669736992 16.931 92 25 50 1 1682782672 5339216 7706464
23:32:09 54836 8192000000 10731314032 11142172672 1792904496 17.956 99 29 58 0 1806701232 4808656 8988080
23:34:06 6194 8192000000 10739080336 11167301632 1831952240 18.275 100 23 44 4 1845550832 6558800 7039792
23:35:14 7993 8192000000 10771520384 11199143936 1862529584 18.524 100 19 38 2 1873494928 5926224 5039120
23:36:22 8942 8192000000 10785444080 11214704640 1873159280 18.610 100 15 31 0 1882753568 5495968 4098320
23:37:31 44093 8192000000 10851035920 11259514880 1905360752 18.869 100 48 92 2 1924364064 6166432 12836880
23:39:20 19451 8192000000 10871667632 11296735232 1939589680 19.143 100 53 106 0 1964442576 10079072 14773824
23:40:50 31054 8192000000 10900416864 11334774784 1964490720 19.342 100 51 102 1 1997124688 16964800 15669168
23:42:38 11652 8192000000 10925032064 11351437312 1977889664 19.448 100 63 124 0 2013120112 17556784 17673664
23:44:07 9375 8192000000 10924642192 11356667904 1982376160 19.484 100 47 93 1 2017618160 21339712 13902288
23:45:21 7631 8192000000 10943675744 11373670400 1992987936 19.567 100 49 96 0 2026984928 19398112 14598880
23:46:32 4155 8192000000 10964218112 11390472192 2009800976 19.700 100 55 108 1 2049379184 23252528 16325680
23:47:42 9413 8192000000 10951538464 11390951424 1988426576 19.531 99 53 104 0 2033565568 29106928 16032064
23:48:55 6913 8192000000 10996738944 11426045952 2024806144 19.818 100 84 167 0 2082644864 32859984 24978736
23:50:12 12472 8192000000 10972868384 11418165248 2016118224 19.750 100 52 102 0 2064194336 32346448 15729664
23:51:30 1701 8192000000 11010737488 11433422848 2029811600 19.857 100 89 179 2 2095244656 37651424 27781632
23:52:36 8279 8192000000 11006331856 11434643456 2022374304 19.799 100 80 160 1 2092774112 46090784 24309024
23:53:52 5098 8192000000 10973319552 11408564224 1988205280 19.530 100 57 112 1 2054733216 46834464 19693472
23:55:02 1002 8192000000 10992790528 11414573056 2003768656 19.652 100 85 168 3 2073150880 42855360 26526864
23:56:09 9038 8192000000 10968301440 11428880384 2013748624 19.731 100 40 80 0 2061891296 35666128 12476544
23:57:29 4558 8192000000 10962764752 11403059200 1995884112 19.590 100 52 103 2 2050004176 37560272 16559792
23:58:36 5081 8192000000 10934143616 11364130816 1944942128 19.186 99 56 112 0 2006044944 42816672 18286144
23:59:44 4870 8192000000 10949399520 11380375552 1967459568 19.365 100 52 105 2 2029950880 46595872 15895440
00:01:04 28304 8192000000 10896509904 11355201536 1927887328 19.050 99 23 46 2 1985774800 50183600 7703872
00:02:34 12734 8192000000 10885584768 11330658304 1915882304 18.954 99 21 42 2 1963532848 40778640 6871904
00:03:49 42552 8192000000 10862395920 11312410624 1858312240 18.490 97 17 35 0 1905085712 40504272 6269200
00:06:07 58278 8192000000 10839835568 11282599936 1853637616 18.452 97 17 34 1 1868483296 9788048 5057632
00:08:08 122161 8192000000 10860931744 11301965824 1874153792 18.618 97 30 60 1 1899875888 17289040 8433056
00:11:14 36316 8192000000 10889830608 11321327616 1891718928 18.760 97 33 66 0 1909988160 8796592 9472640
00:12:58 29265 8192000000 10908011040 11337162752 1945584240 19.191 100 37 74 2 1971598160 15558288 10455632
00:14:33 52323 8192000000 10885148960 11342725120 1926816432 19.041 98 20 40 2 1951192304 18550912 5824960
00:16:28 10518 8192000000 10938247072 11380121600 1983987488 19.496 100 55 110 2 2025237312 25702528 15547296
00:17:45 42069 8192000000 10941075424 11395272704 1991010624 19.552 100 47 95 3 2031107776 25989200 14107952
00:19:38 52645 8192000000 10924472128 11400744960 1992905792 19.567 100 15 30 0 2024048368 26018976 5123600
00:21:54 29199 8192000000 10901222832 11378454528 1982919344 19.488 100 6 12 0 1994429568 9839376 1670848
00:23:24 55985 8192000000 10919744928 11384225792 1972620112 19.406 99 13 25 0 1984669904 8982672 3067120
00:25:26 57184 8192000000 10928357152 11391447040 1963987520 19.338 98 34 70 1 1985192944 11523952 9681472
00:27:28 83498 8192000000 10909295408 11393888256 1958617088 19.295 98 15 30 1 1976553312 14020112 3916112
00:29:52 44063 8192000000 10927901808 11385004032 1972576896 19.406 99 15 30 0 1990925136 13606208 4742032
00:31:41 73925 8192000000 10901610240 11372376064 1947036176 19.203 98 10 20 1 1963487376 13930288 2520912
00:33:56 60669 8192000000 10923671520 11390414848 1948947872 19.218 98 18 35 3 1968946208 15150512 4847824
00:36:00 93806 8192000000 10895829952 11341246464 1952666048 19.248 98 28 54 1 1975455616 15667408 7122160
00:39:02 44059 8192000000 10914798592 11367124992 1978829776 19.455 99 39 76 0 1998272192 7516944 11925472
00:40:47 55937 8192000000 10861730944 11342045184 1908753248 18.897 97 20 38 1 1924451136 9836320 5861568
00:42:47 21799 8192000000 10869686768 11353812992 1933285232 19.093 99 4 8 0 1948139120 14090704 763184
00:44:12 46996 8192000000 10880553776 11346763776 1936660048 19.120 99 14 28 1 1953207104 12588720 3958336
00:46:00 40060 8192000000 10893692832 11348959232 1942976784 19.171 99 27 54 0 1963984560 13023680 7984096
00:47:47 83959 8192000000 10855396976 11308093440 1825508624 18.223 94 19 38 0 1842866256 11917712 5439920
00:50:11 41287 8192000000 10868828016 11329609728 1869948976 18.584 96 39 78 1 1891264688 10391472 10924240
00:51:55 96206 8192000000 10835793376 11310092288 1820611968 18.183 95 16 32 0 1840284016 15599536 4072512
00:54:34 75631 8192000000 10879996528 11330482176 1910217200 18.908 99 13 26 2 1926412096 12468096 3726800
00:56:50 26549 8192000000 10882933328 11322908672 1932570496 19.087 100 45 90 1 1957055424 11812960 12671968
00:58:27 77293 8192000000 10862117664 11331227648 1912976800 18.931 99 22 44 1 1933295184 14199472 6118912
01:01:24 34916 8192000000 10846230576 11289845760 1897294320 18.805 98 39 78 2 1920018736 10867088 11857328
01:03:00 61483 8192000000 10851232608 11323838464 1911818368 18.921 99 14 28 0 1925468448 9945920 3704160
01:05:22 48789 8192000000 10865694288 11330289664 1931578208 19.079 99 23 44 4 1948229552 10682016 5969328
01:07:11 53900 8192000000 10881771360 11365490688 1955895456 19.273 100 10 20 0 1971030352 12224928 2909968
01:09:06 62872 8192000000 10901905040 11367014400 1964652528 19.343 100 20 40 1 1980251856 10192256 5407072
01:11:09 34171 8192000000 10913904240 11381059584 1981031600 19.473 100 29 58 1 2001974256 11747168 9195488
01:12:44 26881 8192000000 10924984896 11385171968 1987114928 19.521 100 35 68 2 2007682912 10360128 10207856
01:14:17 49516 8192000000 10908111152 11382288384 1962696256 19.327 99 20 39 1 1982128768 13493072 5939440
01:16:08 33002 8192000000 10922404064 11375452160 1953647488 19.256 99 39 76 0 1974919296 10071408 11200400
01:17:46 85625 8192000000 10918589296 11403001856 1950851376 19.233 98 12 24 0 1973171584 19236160 3084048
01:20:12 82217 8192000000 10909606640 11392344064 1969631696 19.383 99 13 26 0 1987260112 14150224 3478192
01:22:35 95596 8192000000 10891286624 11368742912 1953352880 19.253 99 25 50 0 1971184736 10075568 7756288
01:25:19 14319 8192000000 10914432448 11381317632 1974341648 19.420 100 26 52 2 1993145504 11575248 7228608
01:26:34 37887 8192000000 10880359584 11370934272 1966117200 19.355 100 3 6 0 1980450864 12865808 1467856
01:28:12 58142 8192000000 10897665824 11374772224 1953803680 19.257 99 17 34 0 1974048000 15028976 5215344
01:30:11 53243 8192000000 10864373504 11347804160 1918631488 18.976 98 14 28 0 1931071824 8515232 3925104
01:32:04 43666 8192000000 10878277248 11354775552 1926565840 19.039 98 14 28 0 1942155792 11677328 3912624
01:33:53 47373 8192000000 10893958704 11361615872 1923435664 19.014 97 24 48 1 1945517328 15216592 6865072
01:35:41 122791 8192000000 10878505232 11344961536 1886926720 18.721 96 16 32 4 1907862320 16469040 4466560

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-08-2021 01:39:00 to 26-08-2021 01:39:15.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 138 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

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

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          241436
MON$OLDEST_ACTIVE               242216
MON$OLDEST_SNAPSHOT             242216
MON$NEXT_TRANSACTION            242216
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                       2026784
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.33489 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33489 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33489 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33489 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 ---
:505: 
:506: mscs-test-161.budzdorov.ru    Thu Aug 26 01:37:49 2021
:507:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:508: 
:509: 
:510: mscs-test-161.budzdorov.ru    Thu Aug 26 01:37:56 2021
:511:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:512: 
:513: 
:514: mscs-test-161.budzdorov.ru    Thu Aug 26 01:37:56 2021
:515:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:516: 
:517: 
:518: mscs-test-161.budzdorov.ru    Thu Aug 26 01:37:56 2021
:519:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:520: 
--- end of diff output ---

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

26.08.21 01:39:19. 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-08-26 01:39:21 No severe PSQL-related problems occured
26.08.21 01:39:22. End of report.