Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.05.2022 13:37
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 16106092 kB
MemAvailable 31394548 kB
Buffers 0 kB
Cached 15565916 kB
SwapCached 2588 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 4.0.2
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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-05-20 13:37:14
DTS_END                         2022-05-20 13:37:14.1460
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(131:22) ==> SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/48192
TRN_ID                          221411
ATT_ID                          670
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: 9311.73 20150 1117407
customer order: creation 170.89 446 20507
customer order: refuse 14.77 361 1772
order to supplier: creation 44.80 1424 5376
order to supplier: removal 3.62 149844 434
invoice (draft): creation 45.98 2876 5518
invoice (draft): removal 52.20 954 6264
invoice accept: apply 41.70 23495 5004
invoice accept: cancel 47.49 1029 5699
customer reserve: creation 3945.06 218 473407
customer reserve: removal 4653.95 90 558474
realization accept: apply 84.47 259 10136
realization accept: cancel 79.27 160 9512
payment from customer: creation 59.09 179 7091
payment from customer: removal 5.83 93 699
payment to supplier: creation 47.46 175 5695
payment to supplier: removal 3.48 93 417
service: total inventory turnovers 3.89 4039 467
service: total monetary turnovers 6.78 310 814
service: aggregate perf. data 0.97 16790 116
service: refresh index statistics 0.04 200159 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-05-2022 13:38:19 to 20-05-2022 13:38:21.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4948
TEST_TIME 2 4955
TEST_TIME 3 4763
TEST_TIME 4 5055
TEST_TIME 5 5174
TEST_TIME 6 5230
TEST_TIME 7 5521
TEST_TIME 8 5729
TEST_TIME 9 5804
TEST_TIME 10 6114
TEST_TIME 11 6396
TEST_TIME 12 6716
TEST_TIME 13 6988
TEST_TIME 14 7108
TEST_TIME 15 7196
TEST_TIME 16 7444
TEST_TIME 17 7776
TEST_TIME 18 8027
TEST_TIME 19 8973
TEST_TIME 20 8887
TEST_TIME 21 8651
TEST_TIME 22 8695
TEST_TIME 23 8961
TEST_TIME 24 8869
TEST_TIME 25 9165
TEST_TIME 26 9492
TEST_TIME 27 9593
TEST_TIME 28 9793
TEST_TIME 29 10268
TEST_TIME 30 10518
TEST_TIME 31 11044
TEST_TIME 32 11080
TEST_TIME 33 11193
TEST_TIME 34 11124
TEST_TIME 35 11055
TEST_TIME 36 10995
TEST_TIME 37 10862
TEST_TIME 38 10763
TEST_TIME 39 10808
TEST_TIME 40 10769
TEST_TIME 41 10832
TEST_TIME 42 10775
TEST_TIME 43 10697
TEST_TIME 44 10638
TEST_TIME 45 10677
TEST_TIME 46 10687
TEST_TIME 47 10525
TEST_TIME 48 10335
TEST_TIME 49 10229
TEST_TIME 50 10115
TEST_TIME 51 10097
TEST_TIME 52 10043
TEST_TIME 53 10011
TEST_TIME 54 9963
TEST_TIME 55 9992
TEST_TIME 56 9998
TEST_TIME 57 9960
TEST_TIME 58 9893
TEST_TIME 59 9858
TEST_TIME 60 9921
TEST_TIME 61 9904
TEST_TIME 62 9888
TEST_TIME 63 9811
TEST_TIME 64 9828
TEST_TIME 65 9905
TEST_TIME 66 9961
TEST_TIME 67 9928
TEST_TIME 68 9998
TEST_TIME 69 9981
TEST_TIME 70 9899
TEST_TIME 71 9907
TEST_TIME 72 10047
TEST_TIME 73 10035
TEST_TIME 74 10060
TEST_TIME 75 9932
TEST_TIME 76 9879
TEST_TIME 77 9814
TEST_TIME 78 9794
TEST_TIME 79 9766
TEST_TIME 80 9701
TEST_TIME 81 9758
TEST_TIME 82 9720
TEST_TIME 83 9652
TEST_TIME 84 9576
TEST_TIME 85 9588
TEST_TIME 86 9554
TEST_TIME 87 9566
TEST_TIME 88 9537
TEST_TIME 89 9575
TEST_TIME 90 9645
TEST_TIME 91 9629
TEST_TIME 92 9596
TEST_TIME 93 9593
TEST_TIME 94 9686
TEST_TIME 95 9684
TEST_TIME 96 9707
TEST_TIME 97 9698
TEST_TIME 98 9708
TEST_TIME 99 9662
TEST_TIME 100 9575
TEST_TIME 101 9618
TEST_TIME 102 9615
TEST_TIME 103 9593
TEST_TIME 104 9568
TEST_TIME 105 9535
TEST_TIME 106 9492
TEST_TIME 107 9447
TEST_TIME 108 9430
TEST_TIME 109 9395
TEST_TIME 110 9354
TEST_TIME 111 9387
TEST_TIME 112 9353
TEST_TIME 113 9342
TEST_TIME 114 9327
TEST_TIME 115 9336
TEST_TIME 116 9304
TEST_TIME 117 9333
TEST_TIME 118 9362
TEST_TIME 119 9398
TEST_TIME 120 9337

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 20-05-2022 13:38:23 to 20-05-2022 13:38:26.

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 1552964 1552964 0 0.00 0 40845 12 0 0
doc_list_biud 1552960 1552960 0 0.00 0 115738 38 0 0
sp_add_invoice_to_stock 5128 5004 124 2.41 62 167593 23496 0 124
sp_cancel_adding_invoice 5699 5699 0 0.00 7 85673 1030 0 0
sp_cancel_client_order 1772 1772 0 0.00 5 11417 361 0 0
sp_cancel_customer_reserve 558474 558474 0 0.00 1 39370 90 0 0
sp_cancel_pay_from_customer 699 699 0 0.00 4 3960 94 0 0
sp_cancel_pay_to_supplier 417 417 0 0.00 4 1683 94 0 0
sp_cancel_supplier_invoice 6284 6264 20 0.31 3 115746 954 0 20
sp_cancel_supplier_order 434 434 0 0.00 8 1256393 149844 0 0
sp_cancel_write_off 9512 9512 0 0.00 2 12222 161 0 0
sp_client_order 20507 20507 0 0.00 6 16523 446 0 0
sp_customer_reserve 473407 473407 0 0.00 1 96175 218 0 0
sp_fill_shopping_cart 31397 31397 0 0.00 2 13768 75 0 0
sp_fill_shopping_cart_clo_res 473391 473391 0 0.00 0 18135 18 0 0
sp_get_clo_for_invoice 4968 4968 0 0.00 0 32075 328 0 0
sp_kill_cost_storno 16327 16327 0 0.00 0 40837 71 0 0
sp_kill_qstorno_ret_qs2qd 1133158 1133158 0 0.00 0 115737 40 0 0
sp_kill_qty_storno 597251 597251 0 0.00 0 115738 91 0 0
sp_lock_selected_doc 20898 20898 0 0.00 0 10471 7 0 0
sp_make_cost_storno 27883 27883 0 0.00 0 11419 74 0 0
sp_multiply_rows_for_pdistr 27881 27881 0 0.00 0 9672 38 0 0
sp_multiply_rows_for_qdistr 20507 20507 0 0.00 0 16266 172 0 0
sp_pay_from_customer 7091 7091 0 0.00 5 13789 179 0 0
sp_pay_to_supplier 5695 5695 0 0.00 6 11387 175 0 0
sp_payment_common 12785 12785 0 0.00 2 13763 42 0 0
sp_qd_handle_on_cancel_clo 1772 1772 0 0.00 0 11409 294 0 0
sp_qd_handle_on_invoice_upd_sts 10661 10661 0 0.00 0 19500 470 0 0
sp_qd_handle_on_reserve_upd_sts 10134 10134 0 0.00 0 23815 60 0 0
sp_reserve_write_off 10136 10136 0 0.00 8 23844 260 0 0
sp_supplier_invoice 5518 5518 0 0.00 10 101579 2877 0 0
sp_supplier_order 5376 5376 0 0.00 9 117066 1424 0 0
srv_aggregate_perf_data 116 116 0 0.00 2628 186596 16790 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 803 162430 41981 0 0
srv_find_qd_qs_mism 1068161 1068161 0 0.00 0 15079 9 0 0
srv_make_invnt_saldo 467 467 0 0.00 50 62899 4040 0 0
srv_make_money_saldo 814 814 0 0.00 1 25772 310 0 0
srv_recalc_idx_stat 5 5 0 0.00 110510 282154 200160 0 0
t$perf-abend:sp_add_invoice_to_stock 124 124 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 20 20 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5010 5010 0 0.00 1 14058 849 0 0
t$perf-norm:sp_cancel_adding_invoice 375 375 0 0.00 1 28440 471 0 0
t$perf-norm:sp_cancel_client_order 1772 1772 0 0.00 0 5707 24 0 0
t$perf-norm:sp_cancel_customer_reserve 718 718 0 0.00 0 3240 33 0 0
t$perf-norm:sp_cancel_pay_from_customer 699 699 0 0.00 0 5335 20 0 0
t$perf-norm:sp_cancel_pay_to_supplier 417 417 0 0.00 0 3687 25 0 0
t$perf-norm:sp_cancel_supplier_invoice 388 388 0 0.00 0 2359 45 0 0
t$perf-norm:sp_cancel_supplier_order 434 434 0 0.00 0 105838 6950 0 0
t$perf-norm:sp_cancel_write_off 1000 1000 0 0.00 0 7670 28 0 0
t$perf-norm:sp_client_order 20507 20507 0 0.00 0 7681 35 0 0
t$perf-norm:sp_customer_reserve 959 959 0 0.00 0 1275 12 0 0
t$perf-norm:sp_pay_from_customer 7091 7091 0 0.00 0 16958 23 0 0
t$perf-norm:sp_pay_to_supplier 5695 5695 0 0.00 0 7288 21 0 0
t$perf-norm:sp_reserve_write_off 10136 10136 0 0.00 0 16889 27 0 0
t$perf-norm:sp_supplier_invoice 5518 5518 0 0.00 0 10304 33 0 0
t$perf-norm:sp_supplier_order 5376 5376 0 0.00 0 3695 28 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 203 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 2399 50 0 0
t$perf-norm:srv_make_invnt_saldo 467 467 0 0.00 0 4256 35 0 0
t$perf-norm:srv_make_money_saldo 814 814 0 0.00 0 393 5 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 6 259 94 0 0
v_add_invoice_to_stock 10170 9922 248 2.43 0 6928 26 0 248
v_all_customers 37602 37602 0 0.00 0 74 3 0 0
v_all_suppliers 30524 30524 0 0.00 0 148 4 0 0
v_all_wares 41012 41012 0 0.00 0 71 3 0 0
v_cancel_adding_invoice 750 750 0 0.00 0 1918 16 0 0
v_cancel_client_order 3544 3544 0 0.00 0 4692 11 0 0
v_cancel_customer_prepayment 1398 1398 0 0.00 0 908 9 0 0
v_cancel_customer_reserve 1436 1436 0 0.00 0 11809 18 0 0
v_cancel_payment_to_supplier 834 834 0 0.00 0 835 10 0 0
v_cancel_supplier_invoice 816 776 40 4.90 0 9871 42 0 40
v_cancel_supplier_order 868 868 0 0.00 0 1722 14 0 0
v_cancel_write_off 2000 2000 0 0.00 0 11873 26 0 0
v_max_id_clo_ord 5374 5374 0 0.00 0 727 4 0 0
v_max_id_clo_res 959 959 0 0.00 0 3574 18 0 0
v_max_id_ord_sup 5515 5515 0 0.00 0 6231 7 0 0
v_max_non_paid_invoice 1321 1321 0 0.00 0 137 6 0 0
v_max_non_paid_realizn 3619 3619 0 0.00 0 3165 7 0 0
v_min_id_clo_ord 5374 5374 0 0.00 0 3193 8 0 0
v_min_id_clo_res 959 959 0 0.00 0 10700 25 0 0
v_min_id_ord_sup 5515 5515 0 0.00 0 4914 9 0 0
v_min_non_paid_invoice 5694 5694 0 0.00 0 6445 13 0 0
v_min_non_paid_realizn 7091 7091 0 0.00 0 8018 17 0 0
v_our_firm 10352 10352 0 0.00 0 59 4 0 0
v_reserve_write_off 20268 20268 0 0.00 0 12049 12 0 0
x_lock_depdocs_on_canc_invoice 11575 11575 0 0.00 0 35634 115 0 0
x_lock_depdocs_on_canc_sup_ord 434 434 0 0.00 0 15924 362 0 0
x_make_qstorno_1000_1200 5376 5376 0 0.00 3 117016 1269 0 0
x_make_qstorno_1200_2000 5518 5518 0 0.00 4 98685 2670 0 0
x_make_qstorno_2100_3300 473406 473406 0 0.00 0 96166 190 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-05-2022 13:38:27 to 20-05-2022 13:38:31.

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
11:07:17 2 8192000000 8796669024 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:19 18815 8192000000 9729162848 10028662784 805896512 8.956 100 86 172 0 835181056 8113264 21171280
11:09:43 21666 8192000000 9778893872 10091266048 852289760 9.423 100 90 180 1 884602960 11166880 21146320
11:11:10 26173 8192000000 9811239104 10134183936 881082816 9.710 100 92 184 0 923017792 21081936 20853040
11:12:41 12557 8192000000 9880334640 10214121472 924597536 10.141 100 86 172 1 991756768 48697776 18461456
11:14:00 3719 8192000000 9926284016 10272591872 957952592 10.469 100 89 178 1 1043278096 66204688 19120816
11:15:10 1360 8192000000 9960663968 10314285056 1000147760 10.880 100 90 173 0 1081955920 65013072 16795088
11:16:14 2511 8192000000 9986301040 10352517120 1027682000 11.146 100 84 160 2 1104335008 61165088 15487920
11:17:18 854 8192000000 10031187776 10409623552 1053924000 11.398 100 89 175 0 1154861328 83645328 17292000
11:18:20 920 8192000000 10035319152 10410573824 1068330512 11.536 100 98 194 1 1157480304 69089424 20060368
11:19:22 893 8192000000 10040098048 10422063104 1071754688 11.569 100 91 176 2 1162488480 72320512 18413280
11:20:24 1036 8192000000 10043495056 10426736640 1078796320 11.636 100 96 182 1 1162759776 65452464 18510992
11:21:25 1164 8192000000 10055602048 10439696384 1086578976 11.710 100 96 182 1 1178823760 73125968 19118816
11:22:29 1376 8192000000 10068349040 10459574272 1093077520 11.772 100 100 188 1 1189713408 78196720 18439168
11:23:31 1164 8192000000 10059605248 10449096704 1101140384 11.848 100 91 169 1 1185018512 66254224 17623904
11:24:33 1428 8192000000 10061717024 10454065152 1100075104 11.838 100 82 154 1 1186730272 69961936 16693232
11:25:36 1316 8192000000 10092878336 10483838976 1102607904 11.862 100 97 188 0 1218240096 96790304 18841888
11:26:38 1268 8192000000 10071012016 10465660928 1112744640 11.958 100 92 174 2 1201129584 67457248 20927696
11:27:40 1045 8192000000 10044810656 10433589248 1098852784 11.827 100 91 169 0 1166643424 50345792 17444848
11:28:42 928 8192000000 10058779168 10448224256 1099728272 11.835 100 90 173 1 1178668752 59893280 19047200
11:29:44 1140 8192000000 10029513520 10414714880 1094799648 11.788 100 96 189 0 1149896192 35746208 19350336
11:30:46 1198 8192000000 10004570816 10387419136 1061773600 11.473 99 88 167 2 1131600352 53546400 16280352
11:31:47 3461 8192000000 10008422176 10394976256 1050544976 11.366 98 57 107 0 1124870240 63432848 10892416
11:32:51 4029 8192000000 9992559168 10377011200 1054083296 11.400 100 61 116 2 1120198064 55110032 11004736
11:33:56 2254 8192000000 10000668448 10375946240 1028573792 11.155 99 83 156 1 1120349744 75299680 16476272
11:34:59 832 8192000000 9995631568 10371805184 1045507392 11.318 100 86 168 5 1121910400 57773264 18629744
11:36:01 917 8192000000 10002810672 10381717504 1036872080 11.235 100 82 160 2 1130486944 77880544 15734320
11:37:03 1128 8192000000 10026905776 10403311616 1043695104 11.300 100 91 174 1 1148347344 86761984 17890256
11:38:07 29290 8192000000 10001156496 10386640896 1054555984 11.404 99 63 116 1 1100369328 33698464 12114880
11:39:44 4275 8192000000 10048814112 10432712704 1087226384 11.716 100 71 139 1 1167950640 65088960 15635296
11:40:58 1235 8192000000 10105349168 10489659392 1105655216 11.891 100 98 193 3 1222067680 96372880 20039584
11:42:14 10492 8192000000 10155399856 10555633664 1121115664 12.038 100 56 112 0 1276074528 142807280 12151584
11:43:31 6904 8192000000 10201084688 10605010944 1136982192 12.187 100 60 119 1 1320952000 171391280 12578528
11:44:42 1051 8192000000 10244494752 10642526208 1148252208 12.293 100 97 180 2 1357558080 189378736 19927136
11:45:49 1772 8192000000 10286968512 10677501952 1157592864 12.381 100 94 187 0 1400249648 222339952 20316832
11:46:56 1175 8192000000 10320545040 10717003776 1164310944 12.444 100 97 185 1 1433483536 248007008 21165584
11:47:59 1064 8192000000 10345080912 10756534272 1171450752 12.510 100 90 180 0 1457150464 265392416 20307296
11:49:04 2419 8192000000 10378705376 10794590208 1173932768 12.534 100 79 156 1 1479681424 288461312 17287344
11:50:09 797 8192000000 10422177168 10826743808 1177956064 12.571 100 96 189 1 1531033104 332097152 20979888
11:51:13 4501 8192000000 10409638560 10830467072 1177001616 12.562 100 82 159 0 1524662448 328682336 18978496
11:52:21 1575 8192000000 10418934448 10853519360 1185421376 12.641 100 96 183 0 1527517232 319095744 23000112
11:53:25 2221 8192000000 10442604144 10876485632 1182909744 12.617 100 92 177 1 1543801872 340517360 20374768
11:54:33 1373 8192000000 10388419856 10857848832 1163684064 12.438 98 96 187 1 1501273120 315616432 21972624
11:55:39 1886 8192000000 10407264880 10875658240 1185371136 12.640 100 97 189 2 1514835760 307964656 21499968
11:56:44 4884 8192000000 10348068368 10793082880 1183810560 12.626 100 51 101 1 1454549456 258489056 12249840
11:57:57 4884 8192000000 10384506048 10803937280 1173482160 12.529 100 60 111 0 1478490816 291081664 13926992
11:59:16 1027 8192000000 10475697920 10915250176 1190644448 12.689 100 97 189 2 1579006144 366154512 22207184
12:00:19 2294 8192000000 10494130384 10951004160 1186678224 12.652 100 76 149 3 1597955376 392290976 18986176
12:01:24 8286 8192000000 10495262576 10926567424 1175316208 12.546 100 44 88 1 1599806160 412481104 12008848
12:02:46 7951 8192000000 10440888608 10902867968 1170501936 12.502 100 40 77 0 1548226768 367518816 10206016
12:03:56 13282 8192000000 10406644880 10864414720 1172795408 12.523 100 45 90 0 1529745424 346030416 10919600
12:05:14 5141 8192000000 10402312320 10894774272 1168389712 12.482 100 41 81 0 1506187264 327808080 9989472
12:06:30 20727 8192000000 10319141424 10840858624 1159890448 12.402 100 26 52 1 1460828384 293920512 7017424
12:07:55 44123 8192000000 10274276400 10771472384 1151143360 12.320 100 15 29 1 1394664176 239540160 3980656
12:09:42 7077 8192000000 10131325936 10556514304 1138251008 12.199 99 67 122 2 1235588144 84605776 12731360
12:10:52 35739 8192000000 10076186352 10516955136 1135332384 12.172 99 28 54 2 1192683840 51665424 5686032
12:12:48 116536 8192000000 10088995504 10541932544 1109197824 11.925 97 14 28 1 1168555856 56635296 2722736
12:16:02 140038 8192000000 10078316096 10592886784 1120847152 12.035 98 14 23 2 1182804000 59906736 2050112
12:19:34 42430 8192000000 10058393632 10520629248 1114729376 11.977 98 17 34 0 1174718288 56801456 3187456
12:21:21 42272 8192000000 10095210432 10558287872 1123999696 12.065 98 15 30 0 1170855520 43291728 3564096
12:23:04 162423 8192000000 10063729936 10509725696 1075075088 11.601 94 12 24 0 1111071568 33123552 2872928
12:26:47 109729 8192000000 10071555328 10491142144 1080707520 11.654 95 28 50 0 1140482064 53994320 5780224
12:29:37 27604 8192000000 10114969360 10542989312 1126854624 12.092 99 50 97 0 1195870720 58366368 10649728
12:31:08 44014 8192000000 10111182496 10549350400 1110341280 11.936 98 26 52 0 1191511120 75313088 5856752
12:33:01 42877 8192000000 10078409200 10524274688 1114483184 11.975 99 19 38 1 1176529648 57786992 4259472
12:35:32 75802 8192000000 10071704784 10537390080 1113435008 11.965 98 12 24 0 1183613616 67164144 3014464
12:37:55 53248 8192000000 10061898000 10536361984 1118631472 12.014 99 10 20 0 1185205440 64480064 2093904
12:39:48 35973 8192000000 10108603216 10562228224 1142376992 12.238 100 32 64 0 1202989200 54031840 6580368
12:41:29 72086 8192000000 10117639680 10610200576 1119247504 12.020 98 17 34 1 1232089648 109557728 3284416
12:43:48 57213 8192000000 10096290096 10540634112 1082892944 11.675 95 21 42 1 1169230848 81841680 4496224
12:45:48 79689 8192000000 10141065216 10600599552 1122647712 12.052 98 17 34 0 1192709072 65836496 4224864
12:48:08 108001 8192000000 10099737840 10536337408 1118163824 12.010 98 22 44 0 1154296672 31587936 4544912
12:51:00 110774 8192000000 10074600608 10503430144 1116320784 11.992 98 35 70 1 1194722736 71133712 7268240
12:54:14 47050 8192000000 10111784304 10554105856 1124546640 12.070 98 35 71 3 1190874544 58840720 7487184
12:56:02 53089 8192000000 10091205104 10544574464 1108368736 11.917 98 7 14 0 1177518112 67680960 1468416
12:58:01 59014 8192000000 10045840896 10538983424 1121402032 12.040 100 5 10 1 1159595824 37340320 853472
13:00:00 73626 8192000000 10068691312 10552664064 1104951776 11.885 98 7 14 1 1153967888 47549360 1466752
13:02:26 28259 8192000000 10049347072 10486005760 1118038560 12.008 99 38 69 0 1143644768 17763680 7842528
13:03:58 70032 8192000000 10101028560 10559496192 1096847648 11.808 96 13 26 0 1159706176 59931232 2927296
13:06:26 72159 8192000000 10103691344 10573058048 1078388032 11.632 95 31 62 0 1167302912 82515648 6399232
13:08:42 32370 8192000000 10111796672 10543194112 1117792832 12.006 99 28 56 1 1167590816 43325744 6472240
13:10:15 60527 8192000000 10167590224 10613084160 1096881872 11.808 96 19 38 1 1214036656 113108880 4045904
13:12:21 20575 8192000000 10094188816 10526031872 1122311392 12.049 99 38 76 0 1171341200 41491152 7538656
13:13:49 26180 8192000000 10101796928 10535104512 1137569760 12.193 100 44 89 0 1200071248 53939376 8562112
13:15:18 18388 8192000000 10136032032 10586013696 1135216000 12.171 99 46 92 1 1220115248 75568384 9330864
13:16:38 150945 8192000000 10072883568 10514964480 1116233280 11.991 98 26 49 2 1171919456 50155872 5530304
13:20:10 80219 8192000000 10071310800 10542833664 1135767552 12.176 100 16 33 1 1183869392 45030768 3071072
13:22:31 61320 8192000000 10067088096 10510868480 1119966800 12.027 98 14 27 0 1161571088 38762272 2842016
13:24:40 72085 8192000000 10068605360 10551713792 1137875984 12.196 100 16 31 1 1191139328 49791952 3471392
13:26:57 106607 8192000000 10064796960 10567102464 1125531904 12.079 99 16 32 1 1207666192 79303680 2830608
13:29:45 36063 8192000000 10117110528 10556698624 1105531728 11.890 97 29 58 1 1179164800 67069632 6563440
13:31:33 72047 8192000000 10143939136 10599825408 1116627856 11.995 98 35 70 3 1243202736 118679440 7895440
13:33:46 24088 8192000000 10161393840 10605498368 1108008448 11.914 97 47 94 1 1254521488 136345904 10167136
13:35:16 57726 8192000000 10125171792 10624675840 1118500976 12.013 99 18 36 0 1238098192 115391344 4205872

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 20-05-2022 13:38:33 to 20-05-2022 13:38:49.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 124 v_add_invoice_to_stock 335544517
except 20 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 20-05-2022 13:38:50 to 20-05-2022 13:38:52.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          215779
MON$OLDEST_ACTIVE               222353
MON$OLDEST_SNAPSHOT             222353
MON$NEXT_TRANSACTION            222353
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-30 07:30:08.6030 GMT
MON$PAGES                       2073312
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             780
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2767 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2767 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2767 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2767 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.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 ---
:16: 
:17: mscs-test-161.budzdorov.ru    Fri May 20 13:37:18 2022
:18:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:19: 
:20: 
:21: mscs-test-161.budzdorov.ru    Fri May 20 13:37:20 2022
:22:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:23: 
--- end of diff output ---

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

20.05.22 13:38:53. There are 3 logs in /var/tmp/logs-oltp40 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-05-20 13:38:55 No severe PSQL-related problems occured
20.05.22 13:38:57. End of report.