Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.04.2022 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 16530244 kB
MemAvailable 31480176 kB
Buffers 0 kB
Cached 15145504 kB
SwapCached 2688 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.10
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-04-16 01:38:10
DTS_END                         2022-04-16 01:38:10.0470
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/46012
TRN_ID                          244274
ATT_ID                          710
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: 9584.83 20459 1150179
customer order: creation 175.92 350 21110
customer order: refuse 15.32 378 1838
order to supplier: creation 47.99 1231 5759
order to supplier: removal 3.52 111049 422
invoice (draft): creation 47.04 3341 5645
invoice (draft): removal 53.85 853 6462
invoice accept: apply 43.50 17897 5220
invoice accept: cancel 48.70 802 5844
customer reserve: creation 4081.08 174 489730
customer reserve: removal 4756.87 63 570824
realization accept: apply 87.96 176 10555
realization accept: cancel 89.61 114 10753
payment from customer: creation 60.06 112 7207
payment from customer: removal 6.15 70 738
payment to supplier: creation 50.63 131 6076
payment to supplier: removal 3.19 76 383
service: total inventory turnovers 5.29 1081 635
service: total monetary turnovers 7.30 25 876
service: aggregate perf. data 0.82 21338 98
service: refresh index statistics 0.03 249916 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-04-2022 01:38:50 to 16-04-2022 01:38:52.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2185
TEST_TIME 2 2716
TEST_TIME 3 4153
TEST_TIME 4 4322
TEST_TIME 5 4526
TEST_TIME 6 4425
TEST_TIME 7 4461
TEST_TIME 8 4934
TEST_TIME 9 5594
TEST_TIME 10 5808
TEST_TIME 11 5850
TEST_TIME 12 6134
TEST_TIME 13 6285
TEST_TIME 14 6305
TEST_TIME 15 6254
TEST_TIME 16 6367
TEST_TIME 17 6501
TEST_TIME 18 6763
TEST_TIME 19 7042
TEST_TIME 20 7045
TEST_TIME 21 7292
TEST_TIME 22 7737
TEST_TIME 23 8694
TEST_TIME 24 8932
TEST_TIME 25 9310
TEST_TIME 26 10094
TEST_TIME 27 11009
TEST_TIME 28 10933
TEST_TIME 29 10808
TEST_TIME 30 10837
TEST_TIME 31 10803
TEST_TIME 32 10701
TEST_TIME 33 10633
TEST_TIME 34 10653
TEST_TIME 35 10671
TEST_TIME 36 10498
TEST_TIME 37 10273
TEST_TIME 38 10292
TEST_TIME 39 10213
TEST_TIME 40 10393
TEST_TIME 41 10310
TEST_TIME 42 10170
TEST_TIME 43 10111
TEST_TIME 44 10006
TEST_TIME 45 9901
TEST_TIME 46 10124
TEST_TIME 47 10090
TEST_TIME 48 10084
TEST_TIME 49 10070
TEST_TIME 50 9935
TEST_TIME 51 9946
TEST_TIME 52 10005
TEST_TIME 53 9884
TEST_TIME 54 9819
TEST_TIME 55 9782
TEST_TIME 56 9853
TEST_TIME 57 9793
TEST_TIME 58 9747
TEST_TIME 59 9798
TEST_TIME 60 9816
TEST_TIME 61 9911
TEST_TIME 62 9908
TEST_TIME 63 9943
TEST_TIME 64 9919
TEST_TIME 65 10028
TEST_TIME 66 9934
TEST_TIME 67 10070
TEST_TIME 68 10039
TEST_TIME 69 10035
TEST_TIME 70 10140
TEST_TIME 71 10126
TEST_TIME 72 10016
TEST_TIME 73 10021
TEST_TIME 74 9941
TEST_TIME 75 9893
TEST_TIME 76 9857
TEST_TIME 77 9826
TEST_TIME 78 9825
TEST_TIME 79 9772
TEST_TIME 80 9756
TEST_TIME 81 9762
TEST_TIME 82 9718
TEST_TIME 83 9723
TEST_TIME 84 9679
TEST_TIME 85 9689
TEST_TIME 86 9688
TEST_TIME 87 9740
TEST_TIME 88 9744
TEST_TIME 89 9719
TEST_TIME 90 9690
TEST_TIME 91 9760
TEST_TIME 92 9772
TEST_TIME 93 9839
TEST_TIME 94 9837
TEST_TIME 95 9844
TEST_TIME 96 9853
TEST_TIME 97 9828
TEST_TIME 98 9807
TEST_TIME 99 9821
TEST_TIME 100 9790
TEST_TIME 101 9729
TEST_TIME 102 9735
TEST_TIME 103 9740
TEST_TIME 104 9687
TEST_TIME 105 9664
TEST_TIME 106 9650
TEST_TIME 107 9597
TEST_TIME 108 9548
TEST_TIME 109 9579
TEST_TIME 110 9628
TEST_TIME 111 9581
TEST_TIME 112 9635
TEST_TIME 113 9610
TEST_TIME 114 9589
TEST_TIME 115 9620
TEST_TIME 116 9603
TEST_TIME 117 9594
TEST_TIME 118 9619
TEST_TIME 119 9666
TEST_TIME 120 9589

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 16-04-2022 01:38:53 to 16-04-2022 01:38:57.

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 1601539 1601539 0 0.00 0 20993 7 0 0
doc_list_biud 1601539 1601539 0 0.00 0 30084 29 0 0
sp_add_invoice_to_stock 5341 5220 121 2.26 37 169988 17898 0 121
sp_cancel_adding_invoice 5844 5844 0 0.00 5 53543 802 0 0
sp_cancel_client_order 1838 1838 0 0.00 4 30100 379 0 0
sp_cancel_customer_reserve 570824 570824 0 0.00 1 29967 64 0 0
sp_cancel_pay_from_customer 738 738 0 0.00 2 3799 71 0 0
sp_cancel_pay_to_supplier 383 383 0 0.00 3 3468 76 0 0
sp_cancel_supplier_invoice 6472 6462 10 0.15 1 18725 853 0 10
sp_cancel_supplier_order 422 422 0 0.00 5 1258007 111050 0 0
sp_cancel_write_off 10753 10753 0 0.00 1 12538 114 0 0
sp_client_order 21110 21110 0 0.00 3 20735 351 0 0
sp_customer_reserve 489730 489730 0 0.00 0 54540 175 0 0
sp_fill_shopping_cart 32501 32501 0 0.00 1 19728 38 0 0
sp_fill_shopping_cart_clo_res 489728 489728 0 0.00 0 17864 13 0 0
sp_get_clo_for_invoice 5216 5216 0 0.00 0 26866 259 0 0
sp_kill_cost_storno 17718 17718 0 0.00 0 7386 57 0 0
sp_kill_qstorno_ret_qs2qd 1159285 1159285 0 0.00 0 29963 34 0 0
sp_kill_qty_storno 611914 611914 0 0.00 0 30084 74 0 0
sp_lock_selected_doc 21732 21732 0 0.00 0 8369 6 0 0
sp_make_cost_storno 29054 29054 0 0.00 0 13935 52 0 0
sp_multiply_rows_for_pdistr 29054 29054 0 0.00 0 11179 32 0 0
sp_multiply_rows_for_qdistr 21110 21110 0 0.00 0 11919 154 0 0
sp_pay_from_customer 7207 7207 0 0.00 4 11842 112 0 0
sp_pay_to_supplier 6076 6076 0 0.00 4 15252 131 0 0
sp_payment_common 13283 13283 0 0.00 1 15246 27 0 0
sp_qd_handle_on_cancel_clo 1838 1838 0 0.00 0 30083 347 0 0
sp_qd_handle_on_invoice_upd_sts 11060 11060 0 0.00 0 29853 337 0 0
sp_qd_handle_on_reserve_upd_sts 10555 10555 0 0.00 0 10458 49 0 0
sp_reserve_write_off 10555 10555 0 0.00 7 15082 177 0 0
sp_supplier_invoice 5645 5645 0 0.00 6 100701 3342 0 0
sp_supplier_order 5759 5759 0 0.00 5 86171 1231 0 0
srv_aggregate_perf_data 98 98 0 0.00 3115 338157 21339 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 976 117957 46582 0 0
srv_find_qd_qs_mism 1099532 1099532 0 0.00 0 20984 6 0 0
srv_make_invnt_saldo 635 635 0 0.00 30 72790 1082 0 0
srv_make_money_saldo 876 876 0 0.00 1 1178 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 203586 308058 249917 0 0
t$perf-abend:sp_add_invoice_to_stock 121 121 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5220 5220 0 0.00 0 15448 164 0 0
t$perf-norm:sp_cancel_adding_invoice 419 419 0 0.00 0 3213 101 0 0
t$perf-norm:sp_cancel_client_order 1838 1838 0 0.00 0 1236 8 0 0
t$perf-norm:sp_cancel_customer_reserve 719 719 0 0.00 0 582 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 738 738 0 0.00 0 808 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 383 383 0 0.00 0 803 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 399 399 0 0.00 0 4193 36 0 0
t$perf-norm:sp_cancel_supplier_order 422 422 0 0.00 0 20156 1471 0 0
t$perf-norm:sp_cancel_write_off 1043 1043 0 0.00 0 2220 11 0 0
t$perf-norm:sp_client_order 21110 21110 0 0.00 0 20046 21 0 0
t$perf-norm:sp_customer_reserve 1054 1054 0 0.00 0 9029 15 0 0
t$perf-norm:sp_pay_from_customer 7207 7207 0 0.00 0 5544 8 0 0
t$perf-norm:sp_pay_to_supplier 6076 6076 0 0.00 0 4590 12 0 0
t$perf-norm:sp_reserve_write_off 10555 10555 0 0.00 0 12335 13 0 0
t$perf-norm:sp_supplier_invoice 5645 5645 0 0.00 0 5090 19 0 0
t$perf-norm:sp_supplier_order 5759 5759 0 0.00 0 12978 20 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 428 17 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 220 5 0 0
t$perf-norm:srv_make_invnt_saldo 635 635 0 0.00 0 1061 22 0 0
t$perf-norm:srv_make_money_saldo 876 876 0 0.00 0 769 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 10674 10432 242 2.26 0 7939 19 0 242
v_all_customers 38626 38626 0 0.00 0 18 1 0 0
v_all_suppliers 31770 31770 0 0.00 0 83 1 0 0
v_all_wares 42214 42214 0 0.00 0 142 1 0 0
v_cancel_adding_invoice 838 838 0 0.00 0 1148 10 0 0
v_cancel_client_order 3676 3676 0 0.00 0 1403 5 0 0
v_cancel_customer_prepayment 1476 1476 0 0.00 0 3790 7 0 0
v_cancel_customer_reserve 1438 1438 0 0.00 0 2128 4 0 0
v_cancel_payment_to_supplier 766 766 0 0.00 0 710 5 0 0
v_cancel_supplier_invoice 818 798 20 2.44 0 1591 14 0 20
v_cancel_supplier_order 844 844 0 0.00 0 2312 8 0 0
v_cancel_write_off 2086 2086 0 0.00 0 11482 18 0 0
v_max_id_clo_ord 5758 5758 0 0.00 0 423 1 0 0
v_max_id_clo_res 1054 1054 0 0.00 0 1883 11 0 0
v_max_id_ord_sup 5635 5635 0 0.00 0 6494 6 0 0
v_max_non_paid_invoice 1584 1584 0 0.00 0 10525 14 0 0
v_max_non_paid_realizn 3816 3816 0 0.00 0 7600 7 0 0
v_min_id_clo_ord 5758 5758 0 0.00 0 2646 4 0 0
v_min_id_clo_res 1054 1054 0 0.00 0 762 2 0 0
v_min_id_ord_sup 5635 5635 0 0.00 0 3971 7 0 0
v_min_non_paid_invoice 6076 6076 0 0.00 0 15243 21 0 0
v_min_non_paid_realizn 7207 7207 0 0.00 0 9606 15 0 0
v_our_firm 10370 10370 0 0.00 0 17 1 0 0
v_reserve_write_off 21110 21110 0 0.00 0 8583 7 0 0
x_lock_depdocs_on_canc_invoice 11907 11907 0 0.00 0 14772 137 0 0
x_lock_depdocs_on_canc_sup_ord 422 422 0 0.00 0 8655 327 0 0
x_make_qstorno_1000_1200 5759 5759 0 0.00 1 86110 1149 0 0
x_make_qstorno_1200_2000 5645 5645 0 0.00 3 95250 3208 0 0
x_make_qstorno_2100_3300 489730 489730 0 0.00 0 54539 159 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-04-2022 01:38:58 to 16-04-2022 01:39:02.

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:08:13 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:14 16667 8192000000 9880614400 10188308480 969006176 10.577 100 85 170 0 997434720 5614608 22813936
23:10:36 20413 8192000000 9936320192 10257022976 1013474448 11.009 100 81 160 2 1042472960 7841536 21156976
23:12:04 16423 8192000000 9968920320 10296836096 1051859360 11.379 100 92 183 1 1085141280 10198336 23083584
23:13:26 12157 8192000000 10025670032 10368913408 1110752640 11.940 100 80 160 2 1140860512 10772976 19334896
23:14:41 12289 8192000000 10071646464 10425704448 1161753664 12.420 100 85 169 1 1194260016 13423648 19082704
23:15:56 7935 8192000000 10119840224 10485415936 1207367184 12.845 100 86 172 1 1239901872 13244656 19290032
23:17:07 3916 8192000000 10152942560 10523488256 1238297984 13.131 100 84 165 1 1270191936 13810592 18083360
23:18:14 2668 8192000000 10191499040 10570735616 1273804816 13.456 100 90 180 1 1308110096 14037104 20268176
23:19:18 2419 8192000000 10214442000 10594770944 1294740576 13.647 100 92 185 2 1328597568 13668048 20188944
23:20:22 1855 8192000000 10220043296 10606821376 1303584928 13.728 100 86 173 0 1336419120 13602176 19232016
23:21:27 3051 8192000000 10203602976 10611998720 1315203248 13.833 100 52 104 2 1334987568 8658976 11125344
23:22:31 3732 8192000000 10215520896 10621894656 1324386432 13.916 100 39 78 1 1340083168 6755248 8941488
23:23:36 8583 8192000000 10207580176 10625908736 1325032992 13.922 100 19 39 2 1334741408 5508064 4200352
23:24:47 40521 8192000000 10227272096 10643787776 1332778624 13.992 100 34 68 0 1348146976 6408272 8960080
23:26:30 10900 8192000000 10234088992 10653605888 1339241680 14.051 100 28 56 1 1351488144 5959712 6286752
23:27:44 8768 8192000000 10227423808 10643439616 1330503216 13.972 99 17 34 0 1340499008 5577424 4418368
23:28:58 50465 8192000000 10244260272 10644680704 1323554160 13.909 99 55 109 1 1343647392 6530256 13562976
23:30:49 6156 8192000000 10157128384 10566844416 1253747680 13.273 98 40 80 0 1268774416 5406160 9620576
23:31:58 11372 8192000000 10145853872 10550272000 1223674144 12.996 97 22 44 0 1235145248 6580624 4890480
23:33:10 56054 8192000000 10163241904 10563559424 1257952912 13.311 100 48 97 1 1275030336 4504816 12572608
23:35:07 4199 8192000000 10163286704 10573279232 1273435088 13.453 100 48 97 2 1292682672 7673792 11573792
23:36:11 6224 8192000000 10189591344 10598084608 1295758096 13.657 100 23 46 1 1306944016 5582608 5603312
23:37:21 14714 8192000000 10195077712 10615902208 1309930864 13.785 100 14 28 0 1318840288 5207344 3702080
23:38:36 77806 8192000000 10246573728 10655936512 1336073136 14.022 100 49 97 1 1353952512 6572624 11306752
23:40:59 8785 8192000000 10263703216 10680897536 1361981472 14.255 100 41 82 2 1381537680 10209280 9346928
23:42:46 12433 8192000000 10288568240 10710106112 1380498208 14.421 100 49 98 0 1407376800 15284016 11594576
23:44:02 19132 8192000000 10300374480 10722312192 1383914880 14.452 100 40 80 0 1409414160 15216528 10282752
23:45:35 7821 8192000000 10331192976 10752135168 1405157168 14.641 100 53 106 0 1441965136 24241248 12566720
23:46:47 5285 8192000000 10327574368 10755235840 1403402672 14.625 100 52 104 1 1445190608 29299728 12488208
23:48:04 9123 8192000000 10345089024 10771886080 1418399776 14.759 100 57 114 1 1459909872 27382272 14127824
23:49:20 4992 8192000000 10351688480 10773966848 1418831584 14.762 100 62 121 4 1462320064 29159648 14328832
23:50:34 6726 8192000000 10356210880 10778611712 1415165392 14.730 100 69 138 1 1468076560 35503904 17407264
23:51:45 11705 8192000000 10359027328 10779975680 1418161840 14.756 100 60 120 1 1467412416 33787616 15462960
23:53:04 969 8192000000 10371259584 10784931840 1417349376 14.749 100 88 177 1 1477129552 38149776 21630400
23:54:09 2938 8192000000 10363750480 10780626944 1409607552 14.680 100 70 139 1 1471232208 44280608 17344048
23:55:18 2585 8192000000 10366489984 10778025984 1402647344 14.619 100 77 154 1 1472677712 49904432 20125936
23:56:30 3068 8192000000 10363214224 10764091392 1380149728 14.418 99 88 176 0 1453907264 51062208 22695328
23:57:37 1565 8192000000 10363593712 10764947456 1381811664 14.433 99 88 176 1 1456407120 52029360 22566096
23:58:45 2855 8192000000 10364372128 10770640896 1383852688 14.451 100 72 142 0 1464358352 61768544 18737120
23:59:55 15648 8192000000 10360170192 10784485376 1385547584 14.466 100 44 88 1 1466794432 68429968 12816880
00:01:20 17130 8192000000 10354538672 10792828928 1373233072 14.356 99 40 80 3 1462221936 77418896 11569968
00:02:48 23692 8192000000 10323800480 10762104832 1352493168 14.170 99 24 48 0 1421516864 61940432 7083264
00:04:14 24035 8192000000 10304617872 10751197184 1349357120 14.142 100 20 40 0 1410710128 54792192 6560816
00:05:46 79174 8192000000 10276007040 10731503616 1367124912 14.301 100 14 28 1 1384287552 13785376 3377264
00:08:53 106699 8192000000 10269651840 10710888448 1320775056 13.884 96 13 26 1 1331879616 8938816 2165744
00:11:49 69493 8192000000 10284816800 10733588480 1321679664 13.892 96 11 22 0 1333297632 9248112 2369856
00:14:10 111346 8192000000 10299675856 10748297216 1383842560 14.451 100 21 42 1 1398324192 9298896 5182736
00:17:17 62019 8192000000 10305265536 10758234112 1377396512 14.393 99 39 78 3 1401828144 15767296 8664336
00:19:31 31737 8192000000 10299409136 10764156928 1393667456 14.539 100 23 45 1 1408741984 9936192 5138336
00:21:05 36896 8192000000 10321236880 10772475904 1414606096 14.725 100 33 66 2 1432609136 10323024 7680016
00:22:46 49703 8192000000 10347407968 10790649856 1421170432 14.783 100 24 48 0 1441549792 13840048 6539312
00:24:41 28379 8192000000 10317539680 10796847104 1412838960 14.709 100 12 24 0 1431305904 15706320 2760624
00:26:10 54401 8192000000 10321607616 10784858112 1403663344 14.628 99 12 24 0 1414812704 8336128 2813232
00:28:05 70564 8192000000 10325247376 10794254336 1410342528 14.687 99 19 38 0 1427452656 12728608 4381520
00:31:01 50622 8192000000 10309027568 10769547264 1383379424 14.447 97 23 45 1 1397341088 9464240 4497424
00:32:54 54160 8192000000 10310743536 10786783232 1390221168 14.508 98 5 10 0 1403389104 12052688 1115248
00:34:50 48967 8192000000 10279829088 10747047936 1328810816 13.956 95 6 12 0 1341983008 11944672 1227520
00:36:39 31709 8192000000 10286410784 10738475008 1344760672 14.100 97 31 60 1 1364186832 12380560 7045600
00:38:24 61832 8192000000 10303165120 10759229440 1359740576 14.235 98 17 34 0 1378629376 15440352 3448448
00:40:26 50408 8192000000 10298331296 10759938048 1384304448 14.455 99 13 26 1 1396854128 9730784 2818896
00:42:18 17788 8192000000 10307087504 10754256896 1366942304 14.300 98 38 76 1 1388456992 13406400 8108288
00:43:39 71623 8192000000 10311947360 10769686528 1370511984 14.332 99 8 16 0 1390804224 18607568 1684672
00:45:53 75202 8192000000 10264048368 10709483520 1253782416 13.273 91 11 22 0 1271543088 15487280 2273392
00:48:09 109544 8192000000 10264530208 10708844544 1293962800 13.640 95 21 42 1 1313117760 14312896 4842064
00:52:03 75633 8192000000 10247410016 10706358272 1317987424 13.858 97 13 26 1 1339492752 17982672 3522656
00:54:19 29998 8192000000 10258029936 10705690624 1352897168 14.174 100 34 68 0 1371801600 11370224 7534208
00:55:53 68838 8192000000 10271605392 10732556288 1368332176 14.312 100 11 22 0 1384987040 13682096 2972768
00:59:22 62599 8192000000 10270555616 10735640576 1368316928 14.312 100 18 36 0 1390490368 17510576 4662864
01:01:30 52290 8192000000 10261891280 10710831104 1337486976 14.035 98 11 22 0 1354567744 14735472 2345296
01:03:32 46536 8192000000 10268354112 10712047616 1346096320 14.112 98 24 48 1 1361361312 9978032 5286960
01:05:21 67665 8192000000 10272898864 10731388928 1346671328 14.118 98 13 26 1 1359442864 9611232 3160304
01:07:29 32918 8192000000 10286443408 10731102208 1371772016 14.343 100 38 76 0 1393013328 13007584 8233728
01:09:05 66946 8192000000 10295080256 10754150400 1369141952 14.319 99 16 32 1 1386883472 14393760 3347760
01:11:15 90133 8192000000 10295361248 10750754816 1364114592 14.274 98 18 36 1 1381732560 13841632 3776336
01:13:57 42549 8192000000 10304720944 10758619136 1381753264 14.432 99 30 60 0 1399569776 10524880 7291632
01:15:45 49613 8192000000 10318844368 10758828032 1386038928 14.471 99 32 63 0 1409028864 15862432 7127504
01:17:36 117957 8192000000 10281354048 10728095744 1312059792 13.805 94 18 36 0 1321919568 5629664 4230112
01:20:50 74577 8192000000 10280034816 10754519040 1356371328 14.205 98 7 14 0 1374772896 16728944 1672624
01:23:05 81022 8192000000 10257284480 10712440832 1337536576 14.035 97 18 36 0 1350616928 9068080 4012272
01:25:30 82954 8192000000 10258334464 10715303936 1314934544 13.831 96 12 24 0 1330172208 12256176 2981488
01:27:59 90535 8192000000 10275177104 10729828352 1356320160 14.204 99 9 18 0 1372989008 14567152 2101696
01:30:30 64107 8192000000 10288788592 10728075264 1334403168 14.007 97 34 68 0 1350000800 8053520 7544112
01:32:35 31067 8192000000 10277956400 10716164096 1329078736 13.959 97 54 108 1 1353418368 12688752 11650880
01:34:08 13095 8192000000 10286562496 10722672640 1365798640 14.289 100 38 76 2 1384249856 9823792 8627424
01:35:22 116445 8192000000 10287330160 10756513792 1354678240 14.190 98 13 26 2 1377809824 19960400 3171184

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 16-04-2022 01:39:04 to 16-04-2022 01:39:18.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 16-04-2022 01:39:19 to 16-04-2022 01:39:21.

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          242987
MON$OLDEST_ACTIVE               244669
MON$OLDEST_SNAPSHOT             244669
MON$NEXT_TRANSACTION            244669
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                       2035936
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33577 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33577 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33577 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33577 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 ---
:493: 
:494: mscs-test-161.budzdorov.ru    Sat Apr 16 01:38:11 2022
:495:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:496: 
:497: 
:498: mscs-test-161.budzdorov.ru    Sat Apr 16 01:38:11 2022
:499:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:500: 
:501: 
:502: mscs-test-161.budzdorov.ru    Sat Apr 16 01:38:12 2022
:503:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:504: 
--- end of diff output ---

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

16.04.22 01:39:22. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2022-04-16 01:39:25 No severe PSQL-related problems occured
16.04.22 01:39:26. End of report.