Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.09.2021 07: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 58e1ddde69c44dad93d327777e5becec
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-05-09 08:45
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4793.92
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15564808 kB
MemAvailable 30759108 kB
Buffers 0 kB
Cached 16097220 kB
SwapCached 1628 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.1
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 2021-09-25 07:37:07
DTS_END                         2021-09-25 07:37:07.1440
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(68:17)
IP                              ::1/55058
TRN_ID                          227415
ATT_ID                          570
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: 8924.31 20433 1070917
customer order: creation 164.36 407 19723
customer order: refuse 14.16 412 1699
order to supplier: creation 44.16 1551 5299
order to supplier: removal 3.35 134219 402
invoice (draft): creation 44.52 4309 5342
invoice (draft): removal 50.98 1090 6118
invoice accept: apply 40.04 21574 4805
invoice accept: cancel 46.11 1095 5533
customer reserve: creation 3668.90 212 440268
customer reserve: removal 4571.48 77 548577
realization accept: apply 78.98 230 9478
realization accept: cancel 75.06 146 9007
payment from customer: creation 56.52 152 6782
payment from customer: removal 5.22 126 626
payment to supplier: creation 46.81 172 5617
payment to supplier: removal 3.55 151 426
service: total inventory turnovers 3.34 5330 401
service: total monetary turnovers 5.83 799 699
service: aggregate perf. data 0.92 14857 110
service: refresh index statistics 0.04 221751 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 25-09-2021 07:38:25 to 25-09-2021 07:38:27.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4070
TEST_TIME 2 5010
TEST_TIME 3 6092
TEST_TIME 4 5695
TEST_TIME 5 6289
TEST_TIME 6 6330
TEST_TIME 7 6137
TEST_TIME 8 6026
TEST_TIME 9 5938
TEST_TIME 10 5834
TEST_TIME 11 6030
TEST_TIME 12 6005
TEST_TIME 13 6194
TEST_TIME 14 6302
TEST_TIME 15 6386
TEST_TIME 16 6740
TEST_TIME 17 7085
TEST_TIME 18 7366
TEST_TIME 19 8032
TEST_TIME 20 8438
TEST_TIME 21 8517
TEST_TIME 22 8652
TEST_TIME 23 8688
TEST_TIME 24 8803
TEST_TIME 25 8937
TEST_TIME 26 8883
TEST_TIME 27 9084
TEST_TIME 28 9030
TEST_TIME 29 9508
TEST_TIME 30 9514
TEST_TIME 31 9404
TEST_TIME 32 10074
TEST_TIME 33 11260
TEST_TIME 34 11142
TEST_TIME 35 10858
TEST_TIME 36 10640
TEST_TIME 37 10568
TEST_TIME 38 10412
TEST_TIME 39 10245
TEST_TIME 40 10425
TEST_TIME 41 10360
TEST_TIME 42 10221
TEST_TIME 43 10114
TEST_TIME 44 10056
TEST_TIME 45 10000
TEST_TIME 46 9989
TEST_TIME 47 9867
TEST_TIME 48 9748
TEST_TIME 49 9706
TEST_TIME 50 9627
TEST_TIME 51 9536
TEST_TIME 52 9424
TEST_TIME 53 9389
TEST_TIME 54 9274
TEST_TIME 55 9283
TEST_TIME 56 9271
TEST_TIME 57 9241
TEST_TIME 58 9250
TEST_TIME 59 9166
TEST_TIME 60 9072
TEST_TIME 61 9051
TEST_TIME 62 8984
TEST_TIME 63 8932
TEST_TIME 64 9098
TEST_TIME 65 9055
TEST_TIME 66 9073
TEST_TIME 67 9015
TEST_TIME 68 9059
TEST_TIME 69 9147
TEST_TIME 70 9091
TEST_TIME 71 9098
TEST_TIME 72 9162
TEST_TIME 73 9178
TEST_TIME 74 9209
TEST_TIME 75 9172
TEST_TIME 76 9272
TEST_TIME 77 9225
TEST_TIME 78 9128
TEST_TIME 79 9060
TEST_TIME 80 9040
TEST_TIME 81 9019
TEST_TIME 82 9020
TEST_TIME 83 8951
TEST_TIME 84 8912
TEST_TIME 85 8846
TEST_TIME 86 8797
TEST_TIME 87 8806
TEST_TIME 88 8905
TEST_TIME 89 8917
TEST_TIME 90 8949
TEST_TIME 91 8932
TEST_TIME 92 8923
TEST_TIME 93 8984
TEST_TIME 94 8988
TEST_TIME 95 8997
TEST_TIME 96 9040
TEST_TIME 97 9029
TEST_TIME 98 9020
TEST_TIME 99 8995
TEST_TIME 100 9032
TEST_TIME 101 9040
TEST_TIME 102 8969
TEST_TIME 103 8949
TEST_TIME 104 8899
TEST_TIME 105 8878
TEST_TIME 106 8836
TEST_TIME 107 8818
TEST_TIME 108 8794
TEST_TIME 109 8799
TEST_TIME 110 8797
TEST_TIME 111 8820
TEST_TIME 112 8830
TEST_TIME 113 8797
TEST_TIME 114 8819
TEST_TIME 115 8914
TEST_TIME 116 8937
TEST_TIME 117 8909
TEST_TIME 118 8903
TEST_TIME 119 8992
TEST_TIME 120 8924

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 25-09-2021 07:38:28 to 25-09-2021 07:38:32.

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 1478806 1478806 0 0.00 0 19034 8 0 0
doc_list_biud 1478806 1478806 0 0.00 0 57250 36 0 0
sp_add_invoice_to_stock 4907 4805 102 2.07 37 183373 21574 0 102
sp_cancel_adding_invoice 5533 5533 0 0.00 4 86357 1095 0 0
sp_cancel_client_order 1699 1699 0 0.00 4 15400 413 0 0
sp_cancel_customer_reserve 548577 548577 0 0.00 1 57252 78 0 0
sp_cancel_pay_from_customer 626 626 0 0.00 3 4936 126 0 0
sp_cancel_pay_to_supplier 426 426 0 0.00 3 7275 151 0 0
sp_cancel_supplier_invoice 6127 6118 9 0.14 2 30163 1091 0 9
sp_cancel_supplier_order 402 402 0 0.00 10 1115456 134219 0 0
sp_cancel_write_off 9007 9007 0 0.00 1 15868 146 0 0
sp_client_order 19723 19723 0 0.00 4 30767 407 0 0
sp_customer_reserve 440268 440268 0 0.00 0 65648 213 0 0
sp_fill_shopping_cart 30350 30350 0 0.00 1 15868 51 0 0
sp_fill_shopping_cart_clo_res 440267 440267 0 0.00 0 19670 17 0 0
sp_get_clo_for_invoice 4802 4802 0 0.00 0 35216 396 0 0
sp_kill_cost_storno 15592 15592 0 0.00 0 8863 77 0 0
sp_kill_qstorno_ret_qs2qd 1112681 1112681 0 0.00 0 57246 42 0 0
sp_kill_qty_storno 585611 585611 0 0.00 0 57249 91 0 0
sp_lock_selected_doc 19793 19793 0 0.00 0 8988 8 0 0
sp_make_cost_storno 26674 26674 0 0.00 0 19033 70 0 0
sp_multiply_rows_for_pdistr 26674 26674 0 0.00 0 14164 44 0 0
sp_multiply_rows_for_qdistr 19723 19723 0 0.00 0 20631 194 0 0
sp_pay_from_customer 6782 6782 0 0.00 5 14313 152 0 0
sp_pay_to_supplier 5617 5617 0 0.00 5 19268 172 0 0
sp_payment_common 12399 12399 0 0.00 1 8965 31 0 0
sp_qd_handle_on_cancel_clo 1699 1699 0 0.00 0 15386 376 0 0
sp_qd_handle_on_invoice_upd_sts 10330 10330 0 0.00 0 27980 454 0 0
sp_qd_handle_on_reserve_upd_sts 9478 9478 0 0.00 0 16147 65 0 0
sp_reserve_write_off 9478 9478 0 0.00 7 16885 230 0 0
sp_supplier_invoice 5342 5342 0 0.00 7 101013 4310 0 0
sp_supplier_order 5299 5299 0 0.00 6 36124 1552 0 0
srv_aggregate_perf_data 110 110 0 0.00 2535 231004 14857 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 1255 127113 40506 0 0
srv_find_qd_qs_mism 1025489 1025489 0 0.00 0 17035 6 0 0
srv_make_invnt_saldo 401 401 0 0.00 41 208554 5331 0 0
srv_make_money_saldo 699 699 0 0.00 1 12580 800 0 0
srv_recalc_idx_stat 5 5 0 0.00 82131 319695 221751 0 0
t$perf-abend:sp_add_invoice_to_stock 102 102 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4805 4805 0 0.00 0 23960 199 0 0
t$perf-norm:sp_cancel_adding_invoice 403 403 0 0.00 0 9831 168 0 0
t$perf-norm:sp_cancel_client_order 1699 1699 0 0.00 0 7673 21 0 0
t$perf-norm:sp_cancel_customer_reserve 647 647 0 0.00 0 3250 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 626 626 0 0.00 0 2527 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 426 426 0 0.00 0 281 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 388 388 0 0.00 0 705 15 0 0
t$perf-norm:sp_cancel_supplier_order 402 402 0 0.00 0 31865 1857 0 0
t$perf-norm:sp_cancel_write_off 927 927 0 0.00 0 510 4 0 0
t$perf-norm:sp_client_order 19723 19723 0 0.00 0 23801 26 0 0
t$perf-norm:sp_customer_reserve 932 932 0 0.00 0 11569 20 0 0
t$perf-norm:sp_pay_from_customer 6782 6782 0 0.00 0 7431 11 0 0
t$perf-norm:sp_pay_to_supplier 5617 5617 0 0.00 0 7409 15 0 0
t$perf-norm:sp_reserve_write_off 9478 9478 0 0.00 0 20714 17 0 0
t$perf-norm:sp_supplier_invoice 5342 5342 0 0.00 0 8271 24 0 0
t$perf-norm:sp_supplier_order 5299 5299 0 0.00 0 17660 25 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 302 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 10954 164 0 0
t$perf-norm:srv_make_invnt_saldo 401 401 0 0.00 0 3198 17 0 0
t$perf-norm:srv_make_money_saldo 699 699 0 0.00 0 2325 11 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 8 5 0 0
v_add_invoice_to_stock 9798 9594 204 2.08 0 8313 20 0 204
v_all_customers 35616 35616 0 0.00 0 131 1 0 0
v_all_suppliers 29010 29010 0 0.00 0 24 1 0 0
v_all_wares 39446 39446 0 0.00 0 31 1 0 0
v_cancel_adding_invoice 806 806 0 0.00 0 3901 18 0 0
v_cancel_client_order 3398 3398 0 0.00 0 1511 4 0 0
v_cancel_customer_prepayment 1252 1252 0 0.00 0 4928 15 0 0
v_cancel_customer_reserve 1294 1294 0 0.00 0 1417 5 0 0
v_cancel_payment_to_supplier 852 852 0 0.00 0 4002 19 0 0
v_cancel_supplier_invoice 794 776 18 2.26 0 1747 16 0 18
v_cancel_supplier_order 804 804 0 0.00 0 5142 22 0 0
v_cancel_write_off 1854 1854 0 0.00 0 1901 16 0 0
v_max_id_clo_ord 5299 5299 0 0.00 0 475 1 0 0
v_max_id_clo_res 932 932 0 0.00 0 4055 19 0 0
v_max_id_ord_sup 5328 5328 0 0.00 0 6954 7 0 0
v_max_non_paid_invoice 1739 1739 0 0.00 0 7222 7 0 0
v_max_non_paid_realizn 3768 3768 0 0.00 0 988 3 0 0
v_min_id_clo_ord 5299 5299 0 0.00 0 2590 7 0 0
v_min_id_clo_res 932 932 0 0.00 0 553 3 0 0
v_min_id_ord_sup 5328 5328 0 0.00 0 7317 8 0 0
v_min_non_paid_invoice 5617 5617 0 0.00 0 6800 23 0 0
v_min_non_paid_realizn 6782 6782 0 0.00 0 5438 23 0 0
v_our_firm 9858 9858 0 0.00 0 22 1 0 0
v_reserve_write_off 18956 18956 0 0.00 0 10912 9 0 0
x_lock_depdocs_on_canc_invoice 11263 11263 0 0.00 0 16125 148 0 0
x_lock_depdocs_on_canc_sup_ord 402 402 0 0.00 0 7572 403 0 0
x_make_qstorno_1000_1200 5299 5299 0 0.00 2 36083 1454 0 0
x_make_qstorno_1200_2000 5342 5342 0 0.00 3 100955 4112 0 0
x_make_qstorno_2100_3300 440268 440268 0 0.00 0 65638 192 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 25-09-2021 07:38:33 to 25-09-2021 07:38:37.

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
05:07:11 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:11 25801 8192000000 9731190624 10026749952 807475664 8.972 100 86 172 0 835359616 7273408 20610544
05:09:42 17340 8192000000 9767349872 10072727552 839939728 9.299 100 85 170 0 870968160 10825936 20202496
05:11:05 9606 8192000000 9785201024 10099019776 863184912 9.532 100 84 168 1 898905424 16945728 18774784
05:12:24 17883 8192000000 9850098512 10181332992 905211984 9.950 100 81 162 0 958674608 35688576 17774048
05:13:46 9909 8192000000 9913790176 10258038784 955063600 10.441 100 82 164 0 1026308656 54695584 16549472
05:15:00 6852 8192000000 9969631968 10323378176 989836560 10.780 100 87 172 1 1079890096 72122160 17931376
05:16:10 4230 8192000000 10018869776 10376368128 1014321184 11.017 100 88 175 3 1129593952 97922928 17349840
05:17:16 2406 8192000000 9999285936 10366353408 1041064368 11.275 100 84 167 1 1112456800 54732320 16660112
05:18:21 1400 8192000000 10029047248 10402017280 1062810016 11.483 100 88 176 1 1143602656 62514304 18278336
05:19:24 13032 8192000000 10016819344 10401452032 1069502720 11.547 100 62 124 0 1137611792 55316256 12792816
05:20:38 3152 8192000000 9991292096 10381918208 1076442320 11.614 100 51 102 2 1104738624 17770064 10526240
05:21:48 5176 8192000000 10008798160 10408546304 1090720128 11.750 100 52 104 1 1127537856 26226912 10590816
05:22:58 36956 8192000000 10005633840 10397376512 1090229968 11.745 100 56 114 1 1115241360 12737184 12274208
05:24:40 7539 8192000000 9994087712 10409062400 1092518448 11.767 100 22 46 1 1133018304 35944192 4555664
05:25:51 9811 8192000000 10006993616 10418020352 1090374784 11.746 99 35 67 1 1111677248 13635744 7666720
05:27:05 7991 8192000000 10019208656 10426363904 1105898176 11.894 100 22 44 2 1125272208 14836576 4537456
05:28:14 23871 8192000000 10025998400 10433138688 1105983008 11.894 100 17 34 0 1129185568 19647568 3554992
05:29:38 6410 8192000000 9951009936 10343432192 1001568048 10.894 94 37 75 2 1029330848 19381488 8381312
05:30:46 7339 8192000000 9944089824 10331013120 1010215056 10.977 98 40 81 4 1039728576 20312224 9201296
05:31:54 23875 8192000000 9918374416 10308829184 958205248 10.471 94 30 61 0 997599200 32730784 6663168
05:33:37 9219 8192000000 9959189376 10361548800 1022521008 11.096 99 53 105 2 1060446016 25824432 12100576
05:34:47 7296 8192000000 9983646624 10373750784 1052178160 11.382 100 47 95 1 1091338480 29121504 10038816
05:35:55 12946 8192000000 9975910736 10377465856 1059907312 11.456 100 36 70 3 1089053040 21874928 7270800
05:37:08 86879 8192000000 10012590640 10409074688 1080305376 11.650 100 45 90 0 1112488672 22558368 9624928
05:39:39 3520 8192000000 10066233216 10462457856 1104874736 11.884 100 61 121 0 1178363648 60745312 12743600
05:40:46 6002 8192000000 10098799728 10498662400 1116152944 11.991 100 61 120 0 1201030448 73150672 11726832
05:41:58 13967 8192000000 10127447424 10542084096 1127018640 12.093 100 50 101 0 1245900128 108174192 10707296
05:43:17 4255 8192000000 10141630160 10546970624 1139860832 12.214 100 57 113 4 1248741072 96844912 12035328
05:44:27 5808 8192000000 10176001616 10579644416 1150874160 12.318 100 71 142 0 1279244608 113343456 15026992
05:45:39 15625 8192000000 10181014240 10600742912 1164939552 12.450 100 56 111 0 1304333696 126910048 12484096
05:47:10 10967 8192000000 10235686912 10647752704 1169715968 12.494 100 62 122 0 1342323536 159507776 13099792
05:48:39 3408 8192000000 10245369968 10656804864 1167262832 12.471 99 79 156 1 1341630464 157457856 16909776
05:49:49 4793 8192000000 10298613584 10707845120 1175982560 12.553 100 71 142 2 1393413056 202506944 14923552
05:50:59 1253 8192000000 10308644544 10716385280 1159292720 12.397 99 79 156 3 1409583744 231290064 19000960
05:52:08 2945 8192000000 10360530992 10774716416 1175570608 12.549 100 71 138 0 1462357968 271452048 15335312
05:53:15 4287 8192000000 10372519472 10781077504 1146499152 12.277 99 85 170 1 1461015424 294903248 19613024
05:54:25 4824 8192000000 10374025168 10796933120 1146478512 12.276 100 81 163 0 1474582448 309243632 18860304
05:55:36 2731 8192000000 10350735104 10761916416 1132435648 12.144 98 91 183 2 1440415472 286814848 21164976
05:56:44 4188 8192000000 10305016048 10786594816 1137751024 12.194 99 82 161 0 1410584576 253894320 18939232
05:57:54 4104 8192000000 10272252912 10680250368 1138659264 12.203 99 64 124 1 1360914416 207461680 14793472
05:59:01 6111 8192000000 10272395840 10687463424 1141719616 12.232 99 40 80 1 1363702624 212309200 9673808
06:00:13 8691 8192000000 10356629120 10781347840 1160706752 12.410 100 47 93 1 1459030512 287199616 11124144
06:01:28 5852 8192000000 10356188288 10775470080 1142287648 12.237 99 50 99 1 1452769840 298294800 12187392
06:02:39 5454 8192000000 10405257120 10831339520 1169524800 12.492 100 50 100 0 1510112000 328418256 12168944
06:03:51 7466 8192000000 10447767760 10869940224 1155269712 12.359 99 56 113 2 1540289424 369716080 15303632
06:05:04 8630 8192000000 10449947184 10882670592 1167192384 12.471 100 45 90 1 1549320880 370667136 11461360
06:06:25 27535 8192000000 10422992800 10890014720 1148420576 12.295 99 34 68 0 1572977808 415795200 8762032
06:07:57 22588 8192000000 10465165008 10916675584 1139451904 12.210 99 29 58 2 1547263136 400475696 7335536
06:09:30 52445 8192000000 10271160624 10767069184 1128499488 12.107 99 31 62 1 1427377808 290242064 8636256
06:11:27 71792 8192000000 10062137632 10507763712 1136683936 12.184 100 23 46 2 1164027088 22976816 4366336
06:13:41 2520 8192000000 10104202688 10515808256 1143414592 12.248 100 90 177 1 1202395856 40412640 18568624
06:14:44 57995 8192000000 10148621408 10594299904 1133717248 12.156 98 41 82 1 1246729392 103946592 9065552
06:17:57 54141 8192000000 10067297600 10540183552 1140188256 12.217 100 16 31 0 1200715312 56777344 3749712
06:20:32 78531 8192000000 10127544944 10602364928 1152688336 12.335 99 16 32 1 1225792192 69516000 3587856
06:22:52 63011 8192000000 10102170336 10586304512 1160388192 12.407 100 14 30 0 1246907088 83227344 3291552
06:25:02 125118 8192000000 10075991984 10554400768 1133986816 12.159 98 21 42 1 1177328128 38942144 4399168
06:28:09 32972 8192000000 10056232784 10540875776 1127035600 12.093 98 13 27 0 1160726992 31092112 2599280
06:29:45 2253 8192000000 10074439584 10505662464 1145583008 12.268 100 74 147 5 1180377056 17855616 16938432
06:30:48 40310 8192000000 10112297280 10568544256 1145605744 12.268 100 25 50 0 1216581856 65707024 5269088
06:32:29 63918 8192000000 10152629120 10652483584 1144290080 12.256 99 30 60 0 1264817952 113506176 7021696
06:34:33 55938 8192000000 10086022256 10604445696 1105338976 11.888 97 10 20 0 1200436400 92868160 2229264
06:36:55 67873 8192000000 10135213808 10594844672 1148730992 12.298 100 18 36 1 1210413536 57686080 3996464
06:39:04 67453 8192000000 10160878192 10676617216 1157031216 12.375 100 15 30 0 1283300000 123210544 3058240
06:41:24 47970 8192000000 10058882160 10507665408 1113561664 11.966 97 18 36 0 1157892848 40770960 3560224
06:43:15 26296 8192000000 10082546544 10525593600 1105111008 11.886 96 29 58 0 1145061056 34389296 5560752
06:44:45 62994 8192000000 10126503296 10630213632 1130927104 12.130 99 28 56 0 1237240752 100188992 6124656
06:46:55 21609 8192000000 10068506912 10498383872 1112543232 11.956 98 35 70 0 1154464192 35084432 6836528
06:48:19 71081 8192000000 10080721968 10574819328 1127865232 12.101 99 14 28 0 1177711360 46857728 2988400
06:50:30 38482 8192000000 10102965056 10554441728 1128835344 12.110 100 35 70 0 1216459184 80807088 6816752
06:52:10 127113 8192000000 10070750720 10583838720 1061155696 11.468 94 10 20 0 1103147248 39984576 2006976
06:55:26 125708 8192000000 10047328512 10492219392 1064076576 11.495 95 29 58 0 1110500480 40160880 6263024
06:58:34 40062 8192000000 10049443776 10503794688 1102749072 11.864 99 14 28 1 1153620368 48272128 2599168
07:01:30 59559 8192000000 10099254160 10541076480 1126791136 12.091 100 15 30 1 1201229712 71040352 3398224
07:03:42 60843 8192000000 10117986656 10586337280 1111853936 11.950 98 13 26 0 1231361440 116497712 3009792
07:06:04 55206 8192000000 10079477472 10588884992 1107262032 11.906 98 10 20 0 1179677728 70237888 2177808
07:07:59 56609 8192000000 10077987200 10520944640 1109439152 11.927 98 21 42 1 1149787328 35873104 4475072
07:10:01 54479 8192000000 10075949168 10582663168 1122890464 12.054 99 15 30 1 1212673472 86464064 3318944
07:11:56 72075 8192000000 10099768640 10538500096 1126035872 12.084 98 13 26 0 1173479360 44950864 2492624
07:14:14 42788 8192000000 10075207808 10522632192 1118862912 12.016 97 39 76 1 1155946208 28982848 8100448
07:15:57 27583 8192000000 10095284800 10525507584 1142476064 12.239 100 29 58 1 1179991984 31480048 6035872
07:17:25 70567 8192000000 10063769968 10546184192 1119679408 12.024 98 19 38 1 1183290320 59608064 4002848
07:19:42 98096 8192000000 10064871536 10522267648 1106708912 11.901 97 34 68 1 1162662208 49069152 6884144
07:22:25 53569 8192000000 10088821056 10538295296 1103266144 11.869 97 14 28 0 1163612608 57623568 2722896
07:24:19 70809 8192000000 10105271184 10568945664 1130048992 12.122 99 17 34 0 1213282576 79496176 3737408
07:26:33 67133 8192000000 10118861312 10608795648 1142477312 12.239 100 7 14 0 1197833776 53853072 1503392
07:28:45 31851 8192000000 10099316144 10543468544 1135678384 12.175 99 21 42 0 1198606336 58327312 4600640
07:30:36 73941 8192000000 10148712144 10624876544 1138908976 12.205 98 24 48 2 1245211008 100789040 5512992
07:32:50 61082 8192000000 10081204064 10581454848 1110238352 11.935 97 12 24 0 1174560016 61747312 2574352
07:35:03 33809 8192000000 10134360896 10577248256 1132805728 12.148 99 41 82 0 1234177536 92957360 8414448
07:36:39 54320 8192000000 9357794128 9766887424 454503696 5.256 40 4 7 0 499385008 44097264 784048

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 25-09-2021 07:38:39 to 25-09-2021 07:38:54.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 102 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 25-09-2021 07:38:55 to 25-09-2021 07:38:57.

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          227414
MON$OLDEST_ACTIVE               227860
MON$OLDEST_SNAPSHOT             227860
MON$NEXT_TRANSACTION            227860
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                       2092096
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.1.2618 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2618 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2618 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2618 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 ---
:46: 
:47: mscs-test-161.budzdorov.ru    Sat Sep 25 07:37:46 2021
:48:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:49: 
:50: 
:51: mscs-test-161.budzdorov.ru    Sat Sep 25 07:37:50 2021
:52:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:53: 
--- end of diff output ---

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

25.09.21 07:38:58. 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
2021-09-25 07:39:01 No severe PSQL-related problems occured
25.09.21 07:39:02. End of report.