Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 02.03.2023 01:40
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 15488464 kB
MemAvailable 30453868 kB
Buffers 0 kB
Cached 16244180 kB
SwapCached 3084 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.11
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 2023-03-02 01:38:41
DTS_END                         2023-03-02 01:38:41.7480
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/50644
TRN_ID                          250111
ATT_ID                          676
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: 9644.48 20029 1157338
customer order: creation 180.50 318 21660
customer order: refuse 15.87 310 1904
order to supplier: creation 48.89 1046 5867
order to supplier: removal 3.50 96868 420
invoice (draft): creation 48.35 3050 5802
invoice (draft): removal 54.58 722 6549
invoice accept: apply 43.56 15750 5227
invoice accept: cancel 48.81 752 5857
customer reserve: creation 4048.52 154 485822
customer reserve: removal 4839.58 54 580750
realization accept: apply 89.17 166 10700
realization accept: cancel 83.85 123 10062
payment from customer: creation 64.18 124 7701
payment from customer: removal 5.93 88 712
payment to supplier: creation 52.50 129 6300
payment to supplier: removal 3.72 88 446
service: total inventory turnovers 5.33 936 640
service: total monetary turnovers 6.73 21 807
service: aggregate perf. data 0.90 17309 108
service: refresh index statistics 0.03 262565 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 02-03-2023 01:40:24 to 02-03-2023 01:40:26.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1896
TEST_TIME 2 2765
TEST_TIME 3 2992
TEST_TIME 4 2750
TEST_TIME 5 2843
TEST_TIME 6 2886
TEST_TIME 7 3073
TEST_TIME 8 3805
TEST_TIME 9 4300
TEST_TIME 10 4570
TEST_TIME 11 4838
TEST_TIME 12 5174
TEST_TIME 13 5629
TEST_TIME 14 5814
TEST_TIME 15 5980
TEST_TIME 16 6327
TEST_TIME 17 6786
TEST_TIME 18 7010
TEST_TIME 19 7836
TEST_TIME 20 8331
TEST_TIME 21 8682
TEST_TIME 22 9705
TEST_TIME 23 10251
TEST_TIME 24 10442
TEST_TIME 25 10519
TEST_TIME 26 10432
TEST_TIME 27 10346
TEST_TIME 28 10134
TEST_TIME 29 9921
TEST_TIME 30 9744
TEST_TIME 31 9639
TEST_TIME 32 9692
TEST_TIME 33 9811
TEST_TIME 34 9990
TEST_TIME 35 9863
TEST_TIME 36 9698
TEST_TIME 37 9648
TEST_TIME 38 9785
TEST_TIME 39 9821
TEST_TIME 40 9783
TEST_TIME 41 9726
TEST_TIME 42 9664
TEST_TIME 43 9708
TEST_TIME 44 9657
TEST_TIME 45 9854
TEST_TIME 46 9885
TEST_TIME 47 9959
TEST_TIME 48 10030
TEST_TIME 49 10009
TEST_TIME 50 9895
TEST_TIME 51 9814
TEST_TIME 52 9704
TEST_TIME 53 9609
TEST_TIME 54 9629
TEST_TIME 55 9594
TEST_TIME 56 9553
TEST_TIME 57 9554
TEST_TIME 58 9492
TEST_TIME 59 9464
TEST_TIME 60 9469
TEST_TIME 61 9580
TEST_TIME 62 9541
TEST_TIME 63 9604
TEST_TIME 64 9594
TEST_TIME 65 9643
TEST_TIME 66 9678
TEST_TIME 67 9649
TEST_TIME 68 9698
TEST_TIME 69 9690
TEST_TIME 70 9817
TEST_TIME 71 9856
TEST_TIME 72 9826
TEST_TIME 73 9936
TEST_TIME 74 9838
TEST_TIME 75 9737
TEST_TIME 76 9733
TEST_TIME 77 9691
TEST_TIME 78 9657
TEST_TIME 79 9644
TEST_TIME 80 9607
TEST_TIME 81 9527
TEST_TIME 82 9588
TEST_TIME 83 9571
TEST_TIME 84 9581
TEST_TIME 85 9559
TEST_TIME 86 9649
TEST_TIME 87 9613
TEST_TIME 88 9613
TEST_TIME 89 9694
TEST_TIME 90 9684
TEST_TIME 91 9687
TEST_TIME 92 9721
TEST_TIME 93 9748
TEST_TIME 94 9747
TEST_TIME 95 9754
TEST_TIME 96 9854
TEST_TIME 97 9778
TEST_TIME 98 9720
TEST_TIME 99 9664
TEST_TIME 100 9659
TEST_TIME 101 9633
TEST_TIME 102 9602
TEST_TIME 103 9575
TEST_TIME 104 9524
TEST_TIME 105 9494
TEST_TIME 106 9505
TEST_TIME 107 9555
TEST_TIME 108 9534
TEST_TIME 109 9546
TEST_TIME 110 9560
TEST_TIME 111 9570
TEST_TIME 112 9624
TEST_TIME 113 9624
TEST_TIME 114 9599
TEST_TIME 115 9651
TEST_TIME 116 9721
TEST_TIME 117 9688
TEST_TIME 118 9706
TEST_TIME 119 9717
TEST_TIME 120 9646

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 02-03-2023 01:40:27 to 02-03-2023 01:40:31.

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 1606786 1606786 0 0.00 0 23217 6 0 0
doc_list_biud 1606785 1606785 0 0.00 0 36406 25 0 0
sp_add_invoice_to_stock 5379 5227 152 2.82 25 169547 15750 0 152
sp_cancel_adding_invoice 5857 5857 0 0.00 3 57782 753 0 0
sp_cancel_client_order 1904 1904 0 0.00 4 21579 311 0 0
sp_cancel_customer_reserve 580750 580750 0 0.00 0 29629 54 0 0
sp_cancel_pay_from_customer 712 712 0 0.00 2 7879 88 0 0
sp_cancel_pay_to_supplier 446 446 0 0.00 3 5540 88 0 0
sp_cancel_supplier_invoice 6565 6549 16 0.24 1 23552 723 0 16
sp_cancel_supplier_order 420 420 0 0.00 13 1126161 96868 0 0
sp_cancel_write_off 10062 10062 0 0.00 1 21412 123 0 0
sp_client_order 21660 21660 0 0.00 3 17243 318 0 0
sp_customer_reserve 485822 485822 0 0.00 0 85585 155 0 0
sp_fill_shopping_cart 33323 33323 0 0.00 1 15146 38 0 0
sp_fill_shopping_cart_clo_res 485821 485821 0 0.00 0 17125 13 0 0
sp_get_clo_for_invoice 5226 5226 0 0.00 0 24268 287 0 0
sp_kill_cost_storno 17077 17077 0 0.00 0 7344 55 0 0
sp_kill_qstorno_ret_qs2qd 1178531 1178531 0 0.00 0 28120 30 0 0
sp_kill_qty_storno 621467 621467 0 0.00 0 36405 64 0 0
sp_lock_selected_doc 22050 22050 0 0.00 0 7801 6 0 0
sp_make_cost_storno 29927 29927 0 0.00 0 19374 53 0 0
sp_multiply_rows_for_pdistr 29926 29926 0 0.00 0 9227 26 0 0
sp_multiply_rows_for_qdistr 21660 21660 0 0.00 0 12765 144 0 0
sp_pay_from_customer 7701 7701 0 0.00 4 23238 125 0 0
sp_pay_to_supplier 6300 6300 0 0.00 5 22973 130 0 0
sp_payment_common 14001 14001 0 0.00 1 15173 30 0 0
sp_qd_handle_on_cancel_clo 1904 1904 0 0.00 0 21570 288 0 0
sp_qd_handle_on_invoice_upd_sts 11083 11083 0 0.00 0 36403 320 0 0
sp_qd_handle_on_reserve_upd_sts 10699 10699 0 0.00 0 13419 60 0 0
sp_reserve_write_off 10700 10700 0 0.00 5 15256 167 0 0
sp_supplier_invoice 5802 5802 0 0.00 6 88163 3051 0 0
sp_supplier_order 5867 5867 0 0.00 5 25006 1047 0 0
srv_aggregate_perf_data 108 108 0 0.00 2249 150101 17310 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1581 125894 46981 0 0
srv_find_qd_qs_mism 1106550 1106550 0 0.00 0 22429 5 0 0
srv_make_invnt_saldo 640 640 0 0.00 26 45933 937 0 0
srv_make_money_saldo 807 807 0 0.00 0 653 22 0 0
srv_recalc_idx_stat 4 4 0 0.00 172484 408772 262566 0 0
t$perf-abend:sp_add_invoice_to_stock 152 152 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5227 5227 0 0.00 0 19773 149 0 0
t$perf-norm:sp_cancel_adding_invoice 416 416 0 0.00 0 3713 77 0 0
t$perf-norm:sp_cancel_client_order 1904 1904 0 0.00 0 4051 11 0 0
t$perf-norm:sp_cancel_customer_reserve 678 678 0 0.00 0 989 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 712 712 0 0.00 0 417 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 446 446 0 0.00 0 239 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 431 431 0 0.00 0 2739 20 0 0
t$perf-norm:sp_cancel_supplier_order 420 420 0 0.00 0 23524 1431 0 0
t$perf-norm:sp_cancel_write_off 1118 1118 0 0.00 0 341 2 0 0
t$perf-norm:sp_client_order 21660 21660 0 0.00 0 6856 13 0 0
t$perf-norm:sp_customer_reserve 1048 1048 0 0.00 0 6822 11 0 0
t$perf-norm:sp_pay_from_customer 7701 7701 0 0.00 0 7949 11 0 0
t$perf-norm:sp_pay_to_supplier 6300 6300 0 0.00 0 6947 9 0 0
t$perf-norm:sp_reserve_write_off 10700 10700 0 0.00 0 7075 8 0 0
t$perf-norm:sp_supplier_invoice 5802 5802 0 0.00 0 8509 18 0 0
t$perf-norm:sp_supplier_order 5867 5867 0 0.00 0 6930 12 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 4225 42 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 7 1 0 0
t$perf-norm:srv_make_invnt_saldo 640 640 0 0.00 0 4279 24 0 0
t$perf-norm:srv_make_money_saldo 807 807 0 0.00 0 968 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10756 10452 304 2.82 0 13695 20 0 304
v_all_customers 39840 39840 0 0.00 0 102 0 0 0
v_all_suppliers 32578 32578 0 0.00 0 22 1 0 0
v_all_wares 43320 43320 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 832 832 0 0.00 0 922 7 0 0
v_cancel_client_order 3808 3808 0 0.00 0 11765 6 0 0
v_cancel_customer_prepayment 1424 1424 0 0.00 0 7810 12 0 0
v_cancel_customer_reserve 1356 1356 0 0.00 0 13705 20 0 0
v_cancel_payment_to_supplier 892 892 0 0.00 0 3160 9 0 0
v_cancel_supplier_invoice 894 862 32 3.57 0 1046 14 0 32
v_cancel_supplier_order 840 840 0 0.00 0 2789 13 0 0
v_cancel_write_off 2236 2236 0 0.00 0 7495 17 0 0
v_max_id_clo_ord 5866 5866 0 0.00 0 251 1 0 0
v_max_id_clo_res 1048 1048 0 0.00 0 3006 12 0 0
v_max_id_ord_sup 5796 5796 0 0.00 0 8180 5 0 0
v_max_non_paid_invoice 1673 1673 0 0.00 0 3689 4 0 0
v_max_non_paid_realizn 4007 4007 0 0.00 0 3157 4 0 0
v_min_id_clo_ord 5866 5866 0 0.00 0 7915 16 0 0
v_min_id_clo_res 1048 1048 0 0.00 0 3651 10 0 0
v_min_id_ord_sup 5796 5796 0 0.00 0 6813 8 0 0
v_min_non_paid_invoice 6300 6300 0 0.00 0 13216 19 0 0
v_min_non_paid_realizn 7701 7701 0 0.00 0 15169 28 0 0
v_our_firm 10868 10868 0 0.00 0 21 1 0 0
v_reserve_write_off 21398 21398 0 0.00 0 8161 7 0 0
x_lock_depdocs_on_canc_invoice 11975 11975 0 0.00 0 27742 111 0 0
x_lock_depdocs_on_canc_sup_ord 420 420 0 0.00 0 2886 265 0 0
x_make_qstorno_1000_1200 5867 5867 0 0.00 1 24979 967 0 0
x_make_qstorno_1200_2000 5802 5802 0 0.00 2 88124 2921 0 0
x_make_qstorno_2100_3300 485822 485822 0 0.00 0 85584 140 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-03-2023 01:40:32 to 02-03-2023 01:40:36.

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:45 6 8192000000 8798879696 8810168320 5307904 0.064 2 1 2 0 5407504 56816 42784
23:09:46 30803 8192000000 9898082704 10208788480 979285488 10.677 100 88 176 0 1007961824 5581408 23094928
23:11:23 16185 8192000000 9942379344 10261430272 1023858256 11.109 100 96 192 0 1056675136 8080176 24736704
23:12:42 16033 8192000000 9976515248 10306740224 1057378784 11.431 100 84 166 0 1087935504 9924240 20632480
23:14:10 9382 8192000000 10024612048 10367209472 1109493216 11.928 100 82 160 0 1139598928 11255600 18850112
23:15:26 7575 8192000000 10076356448 10437275648 1162138272 12.423 100 77 154 0 1192663664 13123744 17401648
23:16:37 2524 8192000000 10126808096 10494939136 1208226400 12.853 100 85 170 0 1241882960 14441840 19214720
23:17:47 1895 8192000000 10161278800 10534141952 1248378272 13.223 100 87 174 1 1281419808 13485728 19555808
23:18:50 1598 8192000000 10189146736 10571628544 1278201536 13.497 100 80 160 0 1310058576 13769056 18087984
23:19:52 40043 8192000000 10190710736 10599043072 1299998320 13.695 100 51 103 1 1321167648 9407888 11761440
23:21:42 5093 8192000000 10197923296 10606473216 1307891888 13.767 100 35 71 0 1323421392 7009744 8519760
23:22:48 10359 8192000000 10210540272 10623754240 1320210704 13.879 100 25 50 2 1332863968 6263216 6390048
23:24:01 6931 8192000000 10212471408 10630606848 1328238992 13.951 100 23 46 0 1339729888 6475280 5015616
23:25:11 74912 8192000000 10222390368 10646560768 1334243008 14.005 100 36 72 0 1347399152 5303392 7852752
23:27:26 6934 8192000000 10228626272 10649587712 1327548416 13.945 99 22 41 1 1342111904 9884112 4679376
23:28:36 8253 8192000000 10205841136 10623393792 1296227248 13.661 98 16 30 0 1304172400 4506832 3438320
23:29:46 4984 8192000000 10165361488 10566692864 1225079504 13.009 95 18 37 1 1234542080 5162432 4300144
23:30:51 24683 8192000000 10136057744 10544037888 1199482160 12.772 94 26 47 0 1208963536 4140960 5340416
23:32:16 4894 8192000000 10146102144 10542096384 1226120464 13.018 98 32 62 0 1240265392 7191888 6953040
23:33:23 7166 8192000000 10168943936 10567106560 1267542784 13.399 100 28 57 1 1279499296 5103184 6853328
23:34:31 11283 8192000000 10170678912 10580594688 1273667456 13.455 99 17 35 0 1287421936 9413360 4341120
23:35:42 38308 8192000000 10180431392 10597892096 1295716528 13.656 100 22 44 0 1305737152 5132448 4888176
23:37:21 7377 8192000000 10188886896 10606067712 1301186256 13.706 100 31 62 0 1314283984 5675888 7421840
23:38:30 8572 8192000000 10215082016 10630258688 1322462432 13.899 100 26 53 0 1333598400 5253216 5882752
23:39:48 39517 8192000000 10257469664 10672959488 1346968512 14.120 100 36 69 0 1364119040 8818608 8331920
23:41:37 34469 8192000000 10285685232 10706984960 1374050496 14.363 100 42 83 1 1402985104 18731920 10202688
23:44:14 14846 8192000000 10312442832 10736824320 1393621344 14.538 100 42 84 1 1427561232 24126080 9813808
23:45:31 18674 8192000000 10337129808 10763059200 1409177296 14.677 100 35 68 0 1452224816 34371120 8676400
23:47:07 22354 8192000000 10347108944 10778910720 1415950512 14.737 100 35 70 0 1460809648 35050288 9808848
23:48:36 6003 8192000000 10362446176 10786934784 1420735104 14.779 100 64 127 1 1471095824 35357376 15003344
23:49:46 10242 8192000000 10376570240 10797359104 1424468336 14.812 100 66 130 1 1479441648 38478960 16494352
23:51:05 1677 8192000000 10385717632 10802810880 1429480096 14.857 100 83 165 1 1491098560 41126800 20491664
23:52:14 2465 8192000000 10393716560 10813046784 1434341056 14.900 100 79 156 0 1496541856 42992336 19208464
23:53:22 1575 8192000000 10400626592 10822270976 1438884448 14.940 100 67 132 0 1502967648 46511376 17571824
23:54:28 1644 8192000000 10384203424 10792935424 1415968320 14.737 100 85 170 1 1488926320 51431376 21526624
23:55:34 2787 8192000000 10368760240 10782052352 1397962640 14.577 100 72 142 0 1474077024 57754624 18359760
23:56:41 2851 8192000000 10379340448 10791096320 1405805344 14.647 100 84 166 0 1482147600 54213072 22129184
23:57:48 2899 8192000000 10366312736 10787971072 1397863712 14.576 100 61 120 0 1472089056 58436224 15789120
23:59:01 15864 8192000000 10316701568 10744774656 1375782800 14.379 100 36 69 0 1427362912 41381296 10198816
00:00:27 27381 8192000000 10278473264 10729394176 1325206400 13.924 98 20 40 3 1383705008 52469648 6028960
00:01:55 49076 8192000000 10254249920 10688176128 1282514544 13.536 94 15 30 0 1306059584 19731904 3813136
00:03:45 69885 8192000000 10258853888 10700894208 1330697984 13.973 99 13 26 0 1353822992 19627760 3497248
00:05:58 55908 8192000000 10231341600 10666278912 1294091008 13.641 96 26 52 1 1307825008 6654064 7079936
00:07:55 37725 8192000000 10280727968 10714595328 1330227328 13.969 97 43 86 1 1351718976 11216288 10275360
00:09:34 39745 8192000000 10287597872 10725752832 1366373808 14.295 100 42 79 1 1397897648 21334928 10188912
00:11:22 30828 8192000000 10277301312 10721427456 1327735680 13.947 97 14 28 0 1350940528 20142000 3062848
00:12:58 37848 8192000000 10264048000 10712518656 1367345568 14.303 100 9 18 0 1375394416 6447664 1601184
00:14:36 75950 8192000000 10266674960 10724478976 1356856384 14.209 99 12 24 0 1373876784 14546000 2474400
00:16:52 46205 8192000000 10296083328 10742644736 1362928048 14.264 99 18 34 0 1380409424 13814800 3666576
00:18:40 42616 8192000000 10277895648 10724855808 1361160112 14.248 99 19 39 0 1373355248 7453584 4741552
00:20:26 11676 8192000000 10300641008 10733715456 1379856656 14.415 100 52 103 0 1402766112 11075552 11833904
00:21:44 82709 8192000000 10302739840 10747289600 1365834928 14.290 98 20 39 0 1388013248 17950688 4227632
00:24:08 52055 8192000000 10302433648 10756927488 1372197584 14.347 99 14 27 0 1393828336 18660528 2970224
00:26:04 69195 8192000000 10313268512 10752626688 1375973360 14.381 99 18 36 0 1395336160 15429488 3933312
00:28:14 65483 8192000000 10269033728 10718408704 1371523248 14.341 99 35 68 0 1390418368 11336352 7558768
00:30:23 63522 8192000000 10287118896 10736910336 1380701184 14.423 99 27 54 0 1398100176 11712000 5686992
00:32:34 64767 8192000000 10290515408 10758774784 1394279696 14.544 100 7 14 0 1408482512 12950704 1252112
00:34:39 46270 8192000000 10289177728 10741219328 1363096080 14.265 98 9 18 0 1376092464 10858320 2138064
00:36:26 52810 8192000000 10275862176 10740609024 1361296400 14.249 98 5 10 0 1369997952 7528704 1172848
00:38:19 63257 8192000000 10263122736 10715140096 1321005696 13.886 95 24 48 0 1339681920 13413840 5262384
00:40:23 42179 8192000000 10274798768 10726756352 1329754784 13.965 96 23 46 0 1342146768 7278000 5113984
00:42:05 67838 8192000000 10271579712 10713747456 1295782720 13.657 94 24 48 0 1312824512 11439984 5601808
00:44:20 53313 8192000000 10235731584 10682216448 1280717616 13.520 95 28 56 0 1299137344 11878096 6541632
00:46:15 48043 8192000000 10277111152 10725543936 1350389920 14.151 99 33 66 0 1371851856 14030880 7431056
00:48:04 73360 8192000000 10267816272 10717343744 1334856144 14.011 97 11 22 0 1350996320 13770688 2369488
00:50:18 123493 8192000000 10254963136 10701410304 1264200528 13.369 92 11 22 0 1281451888 14957264 2294096
00:53:22 66285 8192000000 10261033296 10706407424 1336909504 14.030 98 32 63 0 1352756512 8876896 6970112
00:55:30 17858 8192000000 10263025744 10707681280 1361797616 14.253 100 22 44 1 1375321728 8839152 4684960
00:56:50 58087 8192000000 10266616144 10728189952 1376006368 14.381 100 13 26 0 1392787360 13648624 3132368
00:58:54 82290 8192000000 10279528480 10728611840 1372755408 14.352 99 16 33 1 1389378528 12562976 4060144
01:01:25 55103 8192000000 10282669328 10741960704 1368060272 14.310 99 16 32 1 1388703888 16930992 3712624
01:03:24 69100 8192000000 10277104336 10732216320 1346050096 14.112 97 16 31 0 1361713440 11817968 3845376
01:05:38 40462 8192000000 10310702672 10756841472 1389377984 14.500 100 23 46 0 1407035088 12282272 5374832
01:07:35 45221 8192000000 10301947392 10752856064 1383418272 14.447 99 22 44 0 1400548496 12607152 4523072
01:09:23 81818 8192000000 10303885696 10767683584 1360710704 14.244 97 12 24 0 1372566848 9538992 2317152
01:11:45 57529 8192000000 10303887696 10753609728 1391454256 14.519 100 19 38 0 1413560528 18375872 3730400
01:13:43 40498 8192000000 10317929456 10765737984 1378455712 14.403 99 35 70 0 1400622800 14430816 7736272
01:15:33 125893 8192000000 10280625104 10730016768 1340261712 14.060 96 28 56 0 1355311472 8599328 6450432
01:18:40 67424 8192000000 10261721136 10722738176 1332239360 13.987 96 22 44 2 1343833184 6909200 4684624
01:20:53 41357 8192000000 10286988944 10736058368 1366013680 14.291 99 13 27 0 1378251664 9143664 3094320
01:22:52 102502 8192000000 10281563664 10734854144 1364602160 14.279 99 13 26 0 1382222128 14412960 3207008
01:26:00 73902 8192000000 10257754960 10712686592 1286594544 13.573 93 8 16 0 1300631904 12389968 1647392
01:28:15 24529 8192000000 10243831824 10689970176 1325864496 13.930 98 36 69 0 1343719696 10115504 7739696
01:29:46 46202 8192000000 10255626928 10706960384 1307018208 13.759 96 22 44 0 1323264432 11296080 4950144
01:31:44 72511 8192000000 10252557600 10696790016 1279234592 13.506 95 30 61 0 1296851536 10874512 6742432
01:34:05 85871 8192000000 10245271680 10690220032 1329659184 13.964 99 3 6 0 1348329472 18290736 379552
01:36:31 50631 8192000000 10275487136 10710740992 1328502816 13.954 98 14 27 0 1341691968 10334656 2854496
01:38:23 30618 8192000000 9685414208 10050027520 818563616 9.084 60 8 16 0 831860208 11851280 1445312

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 02-03-2023 01:40:38 to 02-03-2023 01:40:53.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 152 v_add_invoice_to_stock 335544517
except 16 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 02-03-2023 01:40:54 to 02-03-2023 01:40:56.

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          250279
MON$OLDEST_ACTIVE               250738
MON$OLDEST_SNAPSHOT             250738
MON$NEXT_TRANSACTION            250738
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                       2010048
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33665 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33665 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33665 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33665 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 ---
:861: 
:862: mscs-test-161.budzdorov.ru    Thu Mar  2 01:38:57 2023
:863:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:864: 
:865: 
:866: mscs-test-161.budzdorov.ru    Thu Mar  2 01:39:01 2023
:867:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:868: 
--- end of diff output ---

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

02.03.23 01:40:57. 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
2023-03-02 01:41:00 No severe PSQL-related problems occured
02.03.23 01:41:01. End of report.