Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.06.2022 13: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 16097660 kB
MemAvailable 31352680 kB
Buffers 0 kB
Cached 15643156 kB
SwapCached 2448 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-06-07 13:37:16
DTS_END                         2022-06-07 13:37:16.5010
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/42128
TRN_ID                          226735
ATT_ID                          651
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: 8973.61 22211 1076833
customer order: creation 178.77 450 21452
customer order: refuse 15.57 363 1868
order to supplier: creation 46.40 1272 5568
order to supplier: removal 3.38 161670 406
invoice (draft): creation 47.00 2840 5640
invoice (draft): removal 49.75 974 5970
invoice accept: apply 42.85 25944 5142
invoice accept: cancel 44.37 1133 5324
customer reserve: creation 3944.03 251 473283
customer reserve: removal 4315.38 101 517845
realization accept: apply 85.53 270 10263
realization accept: cancel 66.63 182 7996
payment from customer: creation 61.00 169 7320
payment from customer: removal 5.94 115 713
payment to supplier: creation 50.68 191 6081
payment to supplier: removal 3.51 123 421
service: total inventory turnovers 3.84 4334 461
service: total monetary turnovers 7.95 333 954
service: aggregate perf. data 1.01 14101 121
service: refresh index statistics 0.04 229400 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 07-06-2022 13:38:45 to 07-06-2022 13:38:48.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8610
TEST_TIME 2 7031
TEST_TIME 3 6077
TEST_TIME 4 6231
TEST_TIME 5 5882
TEST_TIME 6 6213
TEST_TIME 7 6573
TEST_TIME 8 6602
TEST_TIME 9 6660
TEST_TIME 10 6973
TEST_TIME 11 7088
TEST_TIME 12 7050
TEST_TIME 13 6987
TEST_TIME 14 7086
TEST_TIME 15 7368
TEST_TIME 16 7547
TEST_TIME 17 7640
TEST_TIME 18 7652
TEST_TIME 19 7635
TEST_TIME 20 7852
TEST_TIME 21 8137
TEST_TIME 22 8462
TEST_TIME 23 8310
TEST_TIME 24 8129
TEST_TIME 25 8298
TEST_TIME 26 8647
TEST_TIME 27 8987
TEST_TIME 28 9267
TEST_TIME 29 9530
TEST_TIME 30 10290
TEST_TIME 31 10565
TEST_TIME 32 10728
TEST_TIME 33 10954
TEST_TIME 34 10856
TEST_TIME 35 10709
TEST_TIME 36 10566
TEST_TIME 37 10441
TEST_TIME 38 10179
TEST_TIME 39 10179
TEST_TIME 40 10427
TEST_TIME 41 10227
TEST_TIME 42 10091
TEST_TIME 43 10016
TEST_TIME 44 9975
TEST_TIME 45 9937
TEST_TIME 46 9922
TEST_TIME 47 9777
TEST_TIME 48 9827
TEST_TIME 49 9806
TEST_TIME 50 9678
TEST_TIME 51 9616
TEST_TIME 52 9588
TEST_TIME 53 9561
TEST_TIME 54 9526
TEST_TIME 55 9540
TEST_TIME 56 9527
TEST_TIME 57 9537
TEST_TIME 58 9590
TEST_TIME 59 9597
TEST_TIME 60 9605
TEST_TIME 61 9690
TEST_TIME 62 9761
TEST_TIME 63 9747
TEST_TIME 64 9821
TEST_TIME 65 9794
TEST_TIME 66 9669
TEST_TIME 67 9607
TEST_TIME 68 9549
TEST_TIME 69 9480
TEST_TIME 70 9479
TEST_TIME 71 9409
TEST_TIME 72 9318
TEST_TIME 73 9275
TEST_TIME 74 9355
TEST_TIME 75 9356
TEST_TIME 76 9325
TEST_TIME 77 9300
TEST_TIME 78 9342
TEST_TIME 79 9334
TEST_TIME 80 9307
TEST_TIME 81 9316
TEST_TIME 82 9328
TEST_TIME 83 9380
TEST_TIME 84 9348
TEST_TIME 85 9343
TEST_TIME 86 9398
TEST_TIME 87 9360
TEST_TIME 88 9325
TEST_TIME 89 9271
TEST_TIME 90 9299
TEST_TIME 91 9273
TEST_TIME 92 9212
TEST_TIME 93 9171
TEST_TIME 94 9161
TEST_TIME 95 9176
TEST_TIME 96 9141
TEST_TIME 97 9112
TEST_TIME 98 9076
TEST_TIME 99 9073
TEST_TIME 100 9062
TEST_TIME 101 9057
TEST_TIME 102 9061
TEST_TIME 103 9182
TEST_TIME 104 9173
TEST_TIME 105 9341
TEST_TIME 106 9325
TEST_TIME 107 9318
TEST_TIME 108 9356
TEST_TIME 109 9350
TEST_TIME 110 9275
TEST_TIME 111 9241
TEST_TIME 112 9217
TEST_TIME 113 9182
TEST_TIME 114 9158
TEST_TIME 115 9160
TEST_TIME 116 9150
TEST_TIME 117 9128
TEST_TIME 118 9084
TEST_TIME 119 9060
TEST_TIME 120 8999

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 07-06-2022 13:38:49 to 07-06-2022 13:38:53.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1514820 1514820 0 0.00 0 40293 14 0 0
doc_list_biud 1514815 1514815 0 0.00 0 41706 40 0 0
sp_add_invoice_to_stock 5261 5142 119 2.26 37 244375 25945 0 119
sp_cancel_adding_invoice 5324 5324 0 0.00 7 93343 1134 0 0
sp_cancel_client_order 1868 1868 0 0.00 6 21013 364 0 0
sp_cancel_customer_reserve 517845 517845 0 0.00 1 41708 101 0 0
sp_cancel_pay_from_customer 713 713 0 0.00 3 5462 115 0 0
sp_cancel_pay_to_supplier 421 421 0 0.00 4 4013 124 0 0
sp_cancel_supplier_invoice 5980 5970 10 0.16 3 30629 974 0 10
sp_cancel_supplier_order 406 406 0 0.00 13 1351941 161670 0 0
sp_cancel_write_off 7996 7996 0 0.00 2 19974 183 0 0
sp_client_order 21452 21452 0 0.00 5 33033 451 0 0
sp_customer_reserve 473283 473283 0 0.00 1 84236 251 0 0
sp_fill_shopping_cart 32648 32648 0 0.00 2 8242 75 0 0
sp_fill_shopping_cart_clo_res 473273 473273 0 0.00 0 12905 17 0 0
sp_get_clo_for_invoice 5097 5097 0 0.00 0 24146 240 0 0
sp_kill_cost_storno 14454 14454 0 0.00 0 5911 64 0 0
sp_kill_qstorno_ret_qs2qd 1050062 1050062 0 0.00 0 41702 44 0 0
sp_kill_qty_storno 554764 554764 0 0.00 0 41705 101 0 0
sp_lock_selected_doc 21383 21383 0 0.00 0 10782 8 0 0
sp_make_cost_storno 28758 28758 0 0.00 0 40274 77 0 0
sp_multiply_rows_for_pdistr 28756 28756 0 0.00 0 13279 37 0 0
sp_multiply_rows_for_qdistr 21452 21452 0 0.00 0 24608 166 0 0
sp_pay_from_customer 7320 7320 0 0.00 5 18936 169 0 0
sp_pay_to_supplier 6081 6081 0 0.00 6 26680 191 0 0
sp_payment_common 13401 13401 0 0.00 2 11014 43 0 0
sp_qd_handle_on_cancel_clo 1868 1868 0 0.00 0 21003 300 0 0
sp_qd_handle_on_invoice_upd_sts 10417 10417 0 0.00 0 25560 466 0 0
sp_qd_handle_on_reserve_upd_sts 10262 10262 0 0.00 0 17513 65 0 0
sp_reserve_write_off 10263 10263 0 0.00 8 26989 271 0 0
sp_supplier_invoice 5640 5640 0 0.00 11 91836 2841 0 0
sp_supplier_order 5568 5568 0 0.00 8 36634 1272 0 0
srv_aggregate_perf_data 121 121 0 0.00 2553 185236 14101 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 779 149834 34745 0 0
srv_find_qd_qs_mism 1027531 1027531 0 0.00 0 20111 11 0 0
srv_make_invnt_saldo 461 461 0 0.00 77 76654 4335 0 0
srv_make_money_saldo 954 954 0 0.00 1 22299 333 0 0
srv_recalc_idx_stat 5 5 0 0.00 67309 475112 229400 0 0
t$perf-abend:sp_add_invoice_to_stock 119 119 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5148 5148 0 0.00 1 19966 928 0 0
t$perf-norm:sp_cancel_adding_invoice 457 457 0 0.00 1 16829 361 0 0
t$perf-norm:sp_cancel_client_order 1868 1868 0 0.00 0 2511 27 0 0
t$perf-norm:sp_cancel_customer_reserve 678 678 0 0.00 0 1325 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 713 713 0 0.00 0 4649 27 0 0
t$perf-norm:sp_cancel_pay_to_supplier 421 421 0 0.00 0 1697 22 0 0
t$perf-norm:sp_cancel_supplier_invoice 420 420 0 0.00 0 2940 44 0 0
t$perf-norm:sp_cancel_supplier_order 406 406 0 0.00 0 102581 5969 0 0
t$perf-norm:sp_cancel_write_off 1065 1065 0 0.00 0 9433 30 0 0
t$perf-norm:sp_client_order 21452 21452 0 0.00 0 19748 38 0 0
t$perf-norm:sp_customer_reserve 989 989 0 0.00 0 2081 19 0 0
t$perf-norm:sp_pay_from_customer 7320 7320 0 0.00 0 9445 22 0 0
t$perf-norm:sp_pay_to_supplier 6081 6081 0 0.00 0 5422 23 0 0
t$perf-norm:sp_reserve_write_off 10263 10263 0 0.00 0 23599 28 0 0
t$perf-norm:sp_supplier_invoice 5640 5640 0 0.00 0 8060 33 0 0
t$perf-norm:sp_supplier_order 5568 5568 0 0.00 0 13937 36 0 0
t$perf-norm:srv_aggregate_perf_data 121 121 0 0.00 0 509 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 253 8 0 0
t$perf-norm:srv_make_invnt_saldo 461 461 0 0.00 0 353 13 0 0
t$perf-norm:srv_make_money_saldo 954 954 0 0.00 0 380 4 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 12 164 52 0 0
v_add_invoice_to_stock 10424 10186 238 2.28 0 6895 23 0 238
v_all_customers 38784 38784 0 0.00 0 64 4 0 0
v_all_suppliers 31520 31520 0 0.00 0 72 4 0 0
v_all_wares 42894 42894 0 0.00 0 118 4 0 0
v_cancel_adding_invoice 914 914 0 0.00 0 1007 13 0 0
v_cancel_client_order 3736 3736 0 0.00 0 2340 8 0 0
v_cancel_customer_prepayment 1426 1426 0 0.00 0 4033 15 0 0
v_cancel_customer_reserve 1356 1356 0 0.00 0 740 10 0 0
v_cancel_payment_to_supplier 842 842 0 0.00 0 3908 21 0 0
v_cancel_supplier_invoice 860 840 20 2.32 0 3095 25 0 20
v_cancel_supplier_order 812 812 0 0.00 0 203 8 0 0
v_cancel_write_off 2130 2130 0 0.00 0 4847 22 0 0
v_max_id_clo_ord 5566 5566 0 0.00 0 831 5 0 0
v_max_id_clo_res 989 989 0 0.00 0 2340 15 0 0
v_max_id_ord_sup 5635 5635 0 0.00 0 6326 10 0 0
v_max_non_paid_invoice 1522 1522 0 0.00 0 211 7 0 0
v_max_non_paid_realizn 3894 3894 0 0.00 0 3346 8 0 0
v_min_id_clo_ord 5566 5566 0 0.00 0 1115 7 0 0
v_min_id_clo_res 989 989 0 0.00 0 3518 14 0 0
v_min_id_ord_sup 5635 5635 0 0.00 0 5771 7 0 0
v_min_non_paid_invoice 6081 6081 0 0.00 0 11008 15 0 0
v_min_non_paid_realizn 7320 7320 0 0.00 0 5102 16 0 0
v_our_firm 10962 10962 0 0.00 0 49 4 0 0
v_reserve_write_off 20524 20524 0 0.00 0 4117 10 0 0
x_lock_depdocs_on_canc_invoice 10874 10874 0 0.00 0 22564 121 0 0
x_lock_depdocs_on_canc_sup_ord 406 406 0 0.00 0 29239 409 0 0
x_make_qstorno_1000_1200 5568 5568 0 0.00 3 36283 1118 0 0
x_make_qstorno_1200_2000 5640 5640 0 0.00 6 91799 2647 0 0
x_make_qstorno_2100_3300 473281 473281 0 0.00 0 84234 223 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-06-2022 13:38:54 to 07-06-2022 13:38:58.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
11:07:20 1 8192000000 8796669024 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:21 37828 8192000000 9718815104 10015502336 788150592 8.776 100 89 178 0 817997120 7750080 22096448
11:10:05 26997 8192000000 9763866176 10069651456 830663408 9.206 100 86 172 1 864836880 13330768 20842704
11:11:37 16995 8192000000 9806010016 10125393920 867003952 9.570 100 87 173 0 917266736 29991136 20271648
11:12:57 8295 8192000000 9869199520 10204471296 919497648 10.091 100 76 152 0 980806656 44877392 16431616
11:14:09 3896 8192000000 9919203552 10265804800 966061152 10.548 100 84 165 0 1040010640 56340240 17609248
11:15:15 2232 8192000000 9982652240 10335854592 1004292176 10.920 100 81 158 1 1091604464 71431952 15880336
11:16:20 1061 8192000000 10021657760 10383941632 1033960704 11.207 100 96 188 1 1141604560 88431536 19212320
11:17:22 1473 8192000000 10029828128 10399408128 1049203376 11.353 100 90 172 0 1151570528 85362928 17004224
11:18:24 1045 8192000000 10025318464 10399838208 1057687360 11.434 100 96 192 1 1147994432 71425728 18881344
11:19:27 4709 8192000000 10022990080 10407223296 1066068096 11.515 100 63 121 0 1145878064 66800368 13009600
11:20:33 874 8192000000 10043335296 10429177856 1071207424 11.564 100 95 183 3 1161495536 70907360 19380752
11:21:35 1333 8192000000 10043720480 10425151488 1079014928 11.638 100 99 196 0 1161560112 62831040 19714144
11:22:38 1040 8192000000 10050896432 10438299648 1083348960 11.679 100 92 186 0 1173823664 72805968 17668736
11:23:41 1433 8192000000 10071173088 10456813568 1086472640 11.709 100 98 188 2 1196974864 91258320 19243904
11:24:43 3054 8192000000 10034036816 10421014528 1089008448 11.733 100 86 161 3 1160438656 55371024 16059184
11:25:47 2294 8192000000 10064173024 10455015424 1093794432 11.779 100 73 138 2 1180391216 73283216 13313568
11:26:50 2721 8192000000 10045686688 10431561728 1088876240 11.732 100 88 165 2 1163140848 58189472 16075136
11:27:54 1025 8192000000 10064342832 10450710528 1092752464 11.769 100 95 184 1 1179091936 67646480 18692992
11:28:55 4256 8192000000 10032410208 10423865344 1061362592 11.470 98 70 136 0 1143957776 68326720 14268464
11:30:06 1518 8192000000 10022412032 10410012672 1072270368 11.574 99 79 154 2 1149203968 60801904 16131696
11:31:09 2211 8192000000 10026724336 10419810304 1062757696 11.483 100 63 124 1 1153497408 77856992 12882720
11:32:15 930 8192000000 9999604048 10372603904 1031301888 11.181 99 84 161 2 1123762672 76160496 16300288
11:33:17 1260 8192000000 10010174736 10382569472 1042881408 11.292 100 92 182 0 1132283680 70008512 19393760
11:34:19 2756 8192000000 9972538192 10350362624 1030641440 11.175 100 59 110 2 1093991856 52119136 11231280
11:35:23 1034 8192000000 9994856384 10371301376 1036433984 11.230 100 95 181 3 1113401472 58538160 18429328
11:36:24 1117 8192000000 10001036384 10381332480 1043109520 11.295 100 87 172 1 1122036752 59587536 19339696
11:37:27 861 8192000000 10021538464 10394857472 1060174704 11.458 100 88 165 3 1139523104 63119952 16228448
11:38:33 16431 8192000000 10033061664 10414866432 1073211360 11.583 100 74 138 2 1148412272 61749360 13451552
11:39:57 3437 8192000000 10033954304 10418999296 1078771808 11.636 100 68 121 1 1153955072 60328192 14855072
11:41:06 2021 8192000000 10056530592 10437177344 1090428064 11.747 100 88 156 3 1167932944 60735968 16768912
11:42:12 2652 8192000000 10101453024 10490130432 1104211232 11.878 100 90 172 0 1219227840 96435120 18581488
11:43:21 5256 8192000000 10105516848 10501533696 1103421232 11.870 99 67 124 0 1229866816 112265104 14180480
11:44:31 1173 8192000000 10118337648 10518728704 1115577056 11.985 100 88 168 2 1234810384 101966224 17267104
11:45:39 1330 8192000000 10160641888 10556719104 1135101936 12.169 100 87 171 0 1275880720 122224272 18554512
11:46:43 1119 8192000000 10163268288 10572017664 1144321216 12.256 100 92 177 2 1280066048 117259424 18485408
11:47:50 1143 8192000000 10206544240 10606436352 1158128080 12.386 100 84 166 1 1315807760 139622128 18057552
11:48:54 1201 8192000000 10243195648 10641395712 1163153904 12.433 100 92 182 2 1349330176 165823024 20353248
11:49:59 2107 8192000000 10251480896 10655092736 1167991232 12.478 100 88 165 0 1365379024 179911152 17476640
11:51:07 1240 8192000000 10276611904 10690011136 1170829216 12.505 100 96 187 0 1386024880 194701456 20494208
11:52:11 1681 8192000000 10335689168 10752065536 1183767824 12.625 100 85 168 0 1451256320 247928224 19560272
11:53:16 1022 8192000000 10351675504 10757140480 1170508672 12.502 99 95 187 1 1459380368 268521296 20350400
11:54:19 779 8192000000 10371785024 10788016128 1178294976 12.574 100 85 163 2 1482546000 286141184 18109840
11:55:23 837 8192000000 10416471264 10824060928 1185779072 12.644 100 95 185 1 1521811392 313843296 22189024
11:56:27 773 8192000000 10445791456 10863644672 1176392912 12.557 99 97 184 2 1554982704 355968816 22620976
11:57:31 1493 8192000000 10466111952 10907820032 1181837920 12.607 100 98 193 3 1572887328 368621568 22427840
11:58:34 3053 8192000000 10517294240 10955845632 1187955056 12.664 100 88 176 1 1619774448 410290496 21528896
11:59:41 3516 8192000000 10461316656 10906054656 1192115904 12.703 100 72 128 3 1565671104 358228736 15326464
12:00:49 5368 8192000000 10516890096 10938826752 1194042384 12.721 100 64 127 0 1616483792 406509936 15931472
12:02:03 6099 8192000000 10557777600 11011887104 1189753456 12.681 100 55 106 2 1652573440 448261280 14558704
12:03:19 9431 8192000000 10555708608 11011960832 1196508848 12.744 100 56 112 0 1667097504 455591376 14997280
12:04:39 23254 8192000000 10489398688 10997129216 1181985264 12.609 100 32 64 0 1604481056 413645488 8850304
12:06:03 16789 8192000000 10411970880 10931462144 1140977136 12.225 98 28 56 0 1536173584 387524512 7671936
12:07:22 67125 8192000000 10229447728 10672021504 1141928144 12.234 99 16 32 0 1340004560 194382864 3693552
12:09:29 50799 8192000000 10087551472 10562437120 1098052448 11.819 96 21 40 2 1181379904 79149552 4177904
12:11:23 60423 8192000000 10050596976 10494480384 1061561760 11.471 93 23 43 2 1083828160 17939888 4326512
12:13:29 58738 8192000000 10084763184 10510778368 1094325936 11.784 97 38 76 0 1139028064 37397216 7304912
12:15:43 63317 8192000000 10166774224 10609340416 1131772272 12.138 100 39 75 4 1260008544 119549344 8686928
12:17:50 69071 8192000000 10076497232 10569764864 1119293744 12.020 99 13 26 0 1166735632 45122032 2319856
12:20:13 34718 8192000000 10099812576 10546872320 1122788320 12.053 99 24 48 0 1183544576 55330048 5426208
12:21:55 78753 8192000000 10116524800 10575060992 1147527328 12.286 100 32 64 1 1231404016 76278624 7598064
12:24:15 70435 8192000000 10104060480 10597584896 1146118960 12.273 100 17 35 0 1228061472 78150240 3792272
12:27:21 39241 8192000000 10098701392 10565558272 1153792256 12.345 100 19 39 0 1200384128 42619712 3972160
12:30:22 71723 8192000000 10067522080 10557296640 1116743840 11.996 97 30 55 1 1161789408 39686496 5359072
12:32:36 34050 8192000000 10117800672 10593419264 1147265760 12.284 100 31 62 1 1224988352 71303248 6419344
12:34:12 40909 8192000000 10135856720 10593247232 1124567088 12.070 98 20 40 1 1209816800 80949408 4300304
12:35:57 48845 8192000000 10149596800 10602516480 1140765312 12.223 99 23 46 2 1232057024 86315296 4976416
12:37:51 56892 8192000000 10138522048 10599849984 1122485712 12.050 98 22 40 5 1225145392 98273984 4385696
12:39:49 60848 8192000000 10116368144 10550120448 1079784800 11.645 94 19 36 2 1129243440 46084480 3374160
12:41:51 25658 8192000000 10105294336 10528751616 1141303008 12.228 100 46 89 3 1183454208 32507088 9644112
12:43:18 62467 8192000000 10103514608 10536849408 1134239120 12.161 100 56 113 1 1199797696 54129200 11429376
12:45:31 33695 8192000000 10087461152 10530525184 1138648752 12.203 100 40 79 1 1188067728 41578192 7840784
12:47:06 63587 8192000000 10097916992 10553729024 1137743168 12.194 99 18 36 1 1213680000 72198656 3738176
12:50:35 52343 8192000000 10149482336 10614554624 1142000624 12.234 100 21 42 1 1273371216 126452112 4918480
12:52:50 46267 8192000000 10061099216 10509385728 1105560128 11.890 97 22 42 3 1148861200 38507584 4793488
12:54:36 14126 8192000000 10108446336 10554306560 1132164416 12.142 100 47 90 2 1212554096 70388928 10000752
12:55:51 49009 8192000000 10073879616 10514685952 1104972704 11.885 98 28 55 3 1155205232 44180304 6052224
12:57:40 13819 8192000000 10085208928 10509537280 1113019264 11.961 99 46 90 1 1160178576 38477600 8681712
12:58:56 33201 8192000000 10131728112 10585440256 1125192704 12.076 99 36 72 2 1203229104 70814928 7221472
13:00:31 26727 8192000000 10065903184 10479226880 1064940240 11.504 95 45 89 1 1109897792 35976000 8981552
13:01:59 34340 8192000000 10131691616 10564104192 1090121456 11.744 98 39 78 0 1220574144 122339344 8113344
13:03:57 110737 8192000000 10074112176 10507063296 1068831024 11.541 96 33 65 0 1119143392 43354736 6957632
13:06:49 71574 8192000000 10054431344 10536538112 1095983344 11.800 98 13 24 1 1164128544 65652464 2492736
13:09:06 53419 8192000000 10048318768 10522632192 1118745312 12.015 100 8 16 0 1159069904 38919312 1405280
13:11:41 51276 8192000000 10090317104 10534055936 1122940448 12.055 99 21 43 0 1168736976 41015104 4781424
13:13:36 25857 8192000000 10112024592 10552291328 1130029216 12.122 98 30 58 2 1197747792 61509104 6209472
13:15:06 33748 8192000000 10180944864 10619043840 1153256800 12.340 99 46 92 0 1257644320 93936192 10451328
13:16:41 30172 8192000000 10234926960 10671935488 1155701856 12.363 99 53 106 1 1308868400 141517376 11649168
13:18:20 16665 8192000000 10250388032 10687897600 1147222896 12.283 98 46 92 1 1334636064 176634336 10778832
13:19:37 50465 8192000000 10267689600 10760912896 1163372736 12.435 100 34 68 0 1390472608 219042176 8057696
13:21:29 41780 8192000000 10228135872 10679394304 1147513280 12.286 100 23 46 0 1305822176 152793792 5515104
13:23:13 91495 8192000000 10098885008 10535571456 1117743952 12.006 97 36 72 1 1178682448 53694464 7244032
13:25:45 149821 8192000000 10064241568 10511568896 1110425424 11.936 97 16 32 0 1146734560 33220176 3088960
13:29:21 33084 8192000000 10097183072 10546483200 1122780544 12.053 99 24 44 1 1177241216 49563200 4897472
13:31:00 56841 8192000000 10068399424 10533847040 1099864912 11.836 97 12 24 0 1160063984 57952512 2246560
13:33:04 78530 8192000000 10056378656 10522546176 1099685696 11.835 97 25 50 2 1154345872 49872560 4787616
13:35:22 61188 8192000000 10062938880 10510082048 1115003072 11.980 98 26 50 3 1143836784 24276224 4557488

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 07-06-2022 13:39:00 to 07-06-2022 13:39:16.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 119 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:01, from 07-06-2022 13:39:17 to 07-06-2022 13:39:18.

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          225643
MON$OLDEST_ACTIVE               227900
MON$OLDEST_SNAPSHOT             227900
MON$NEXT_TRANSACTION            227900
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                       2067296
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             784
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2772 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2772 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2772 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2772 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 ---
:264: 
:265: mscs-test-161.budzdorov.ru    Tue Jun  7 13:37:18 2022
:266:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:267: 
:268: 
:269: mscs-test-161.budzdorov.ru    Tue Jun  7 13:37:18 2022
:270:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:271: 
:272: 
:273: mscs-test-161.budzdorov.ru    Tue Jun  7 13:37:26 2022
:274:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:275: 
:276: 
:277: mscs-test-161.budzdorov.ru    Tue Jun  7 13:37:26 2022
:278:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:279: 
--- end of diff output ---

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

07.06.22 13:39:19. 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-06-07 13:39:22 No severe PSQL-related problems occured
07.06.22 13:39:23. End of report.