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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-10-26 01:37:54
DTS_END                         2021-10-26 01:37:54.9560
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/48518
TRN_ID                          244042
ATT_ID                          661
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: 9802.65 17583 1176318
customer order: creation 177.58 350 21309
customer order: refuse 15.13 380 1816
order to supplier: creation 47.04 1175 5645
order to supplier: removal 3.49 107917 419
invoice (draft): creation 46.43 3277 5571
invoice (draft): removal 57.66 829 6919
invoice accept: apply 43.13 17001 5176
invoice accept: cancel 52.38 776 6285
customer reserve: creation 3966.29 170 475955
customer reserve: removal 5080.69 57 609683
realization accept: apply 86.91 168 10429
realization accept: cancel 92.95 113 11154
payment from customer: creation 60.18 96 7222
payment from customer: removal 5.68 62 681
payment to supplier: creation 50.93 110 6112
payment to supplier: removal 3.64 108 437
service: total inventory turnovers 4.63 1149 556
service: total monetary turnovers 6.98 27 837
service: aggregate perf. data 0.90 20547 108
service: refresh index statistics 0.03 197348 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 26-10-2021 01:40:08 to 26-10-2021 01:40:10.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1359
TEST_TIME 2 1511
TEST_TIME 3 3176
TEST_TIME 4 3952
TEST_TIME 5 4013
TEST_TIME 6 3963
TEST_TIME 7 3911
TEST_TIME 8 4093
TEST_TIME 9 4443
TEST_TIME 10 4492
TEST_TIME 11 4610
TEST_TIME 12 4649
TEST_TIME 13 5119
TEST_TIME 14 5702
TEST_TIME 15 5888
TEST_TIME 16 6395
TEST_TIME 17 6635
TEST_TIME 18 7094
TEST_TIME 19 7505
TEST_TIME 20 7791
TEST_TIME 21 8262
TEST_TIME 22 8442
TEST_TIME 23 8493
TEST_TIME 24 8620
TEST_TIME 25 9461
TEST_TIME 26 10553
TEST_TIME 27 10552
TEST_TIME 28 10835
TEST_TIME 29 10616
TEST_TIME 30 10471
TEST_TIME 31 10543
TEST_TIME 32 10625
TEST_TIME 33 10718
TEST_TIME 34 10583
TEST_TIME 35 10684
TEST_TIME 36 10647
TEST_TIME 37 10579
TEST_TIME 38 10339
TEST_TIME 39 10296
TEST_TIME 40 10221
TEST_TIME 41 10280
TEST_TIME 42 10130
TEST_TIME 43 10159
TEST_TIME 44 10064
TEST_TIME 45 10039
TEST_TIME 46 10128
TEST_TIME 47 10079
TEST_TIME 48 9970
TEST_TIME 49 9955
TEST_TIME 50 9925
TEST_TIME 51 9805
TEST_TIME 52 9704
TEST_TIME 53 9800
TEST_TIME 54 9770
TEST_TIME 55 9737
TEST_TIME 56 9675
TEST_TIME 57 9790
TEST_TIME 58 9783
TEST_TIME 59 9841
TEST_TIME 60 9808
TEST_TIME 61 9983
TEST_TIME 62 10082
TEST_TIME 63 10086
TEST_TIME 64 10004
TEST_TIME 65 10006
TEST_TIME 66 10044
TEST_TIME 67 10086
TEST_TIME 68 10201
TEST_TIME 69 10155
TEST_TIME 70 10055
TEST_TIME 71 10043
TEST_TIME 72 10101
TEST_TIME 73 10017
TEST_TIME 74 9939
TEST_TIME 75 9962
TEST_TIME 76 9892
TEST_TIME 77 9840
TEST_TIME 78 9783
TEST_TIME 79 9750
TEST_TIME 80 9743
TEST_TIME 81 9667
TEST_TIME 82 9736
TEST_TIME 83 9745
TEST_TIME 84 9765
TEST_TIME 85 9830
TEST_TIME 86 9835
TEST_TIME 87 9867
TEST_TIME 88 9899
TEST_TIME 89 9857
TEST_TIME 90 9901
TEST_TIME 91 9931
TEST_TIME 92 9931
TEST_TIME 93 10000
TEST_TIME 94 9995
TEST_TIME 95 10057
TEST_TIME 96 9963
TEST_TIME 97 9949
TEST_TIME 98 9878
TEST_TIME 99 9869
TEST_TIME 100 9898
TEST_TIME 101 9850
TEST_TIME 102 9822
TEST_TIME 103 9797
TEST_TIME 104 9736
TEST_TIME 105 9728
TEST_TIME 106 9782
TEST_TIME 107 9773
TEST_TIME 108 9768
TEST_TIME 109 9773
TEST_TIME 110 9764
TEST_TIME 111 9741
TEST_TIME 112 9835
TEST_TIME 113 9816
TEST_TIME 114 9787
TEST_TIME 115 9915
TEST_TIME 116 9872
TEST_TIME 117 9895
TEST_TIME 118 9896
TEST_TIME 119 9817
TEST_TIME 120 9803

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 26-10-2021 01:40:11 to 26-10-2021 01:40:15.

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 1616911 1616911 0 0.00 0 22500 6 0 0
doc_list_biud 1616911 1616911 0 0.00 0 31474 28 0 0
sp_add_invoice_to_stock 5336 5176 160 2.99 53 168298 17001 0 160
sp_cancel_adding_invoice 6285 6285 0 0.00 5 56431 776 0 0
sp_cancel_client_order 1816 1816 0 0.00 4 11691 380 0 0
sp_cancel_customer_reserve 609683 609683 0 0.00 1 31475 58 0 0
sp_cancel_pay_from_customer 681 681 0 0.00 3 1720 62 0 0
sp_cancel_pay_to_supplier 437 437 0 0.00 2 9934 109 0 0
sp_cancel_supplier_invoice 6930 6919 11 0.15 2 22669 830 0 11
sp_cancel_supplier_order 419 419 0 0.00 10 1179146 107918 0 0
sp_cancel_write_off 11154 11154 0 0.00 1 16669 114 0 0
sp_client_order 21309 21309 0 0.00 3 22493 350 0 0
sp_customer_reserve 475955 475955 0 0.00 0 82143 170 0 0
sp_fill_shopping_cart 32509 32509 0 0.00 1 14721 36 0 0
sp_fill_shopping_cart_clo_res 475954 475954 0 0.00 0 16853 13 0 0
sp_get_clo_for_invoice 5175 5175 0 0.00 0 30103 299 0 0
sp_kill_cost_storno 18557 18557 0 0.00 0 10471 58 0 0
sp_kill_qstorno_ret_qs2qd 1237858 1237858 0 0.00 0 31472 32 0 0
sp_kill_qty_storno 651880 651880 0 0.00 0 31474 68 0 0
sp_lock_selected_doc 21589 21589 0 0.00 0 9927 5 0 0
sp_make_cost_storno 28938 28938 0 0.00 0 14570 48 0 0
sp_multiply_rows_for_pdistr 28938 28938 0 0.00 0 8779 24 0 0
sp_multiply_rows_for_qdistr 21309 21309 0 0.00 0 22462 172 0 0
sp_pay_from_customer 7222 7222 0 0.00 4 17009 97 0 0
sp_pay_to_supplier 6112 6112 0 0.00 5 11923 110 0 0
sp_payment_common 13334 13334 0 0.00 2 17002 25 0 0
sp_qd_handle_on_cancel_clo 1816 1816 0 0.00 0 11616 357 0 0
sp_qd_handle_on_invoice_upd_sts 11460 11460 0 0.00 0 31445 317 0 0
sp_qd_handle_on_reserve_upd_sts 10429 10429 0 0.00 0 22731 66 0 0
sp_reserve_write_off 10429 10429 0 0.00 6 25159 169 0 0
sp_supplier_invoice 5571 5571 0 0.00 7 73603 3278 0 0
sp_supplier_order 5645 5645 0 0.00 4 43395 1175 0 0
srv_aggregate_perf_data 108 108 0 0.00 2595 202624 20547 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2886 206576 46320 0 0
srv_find_qd_qs_mism 1127177 1127177 0 0.00 0 22498 5 0 0
srv_make_invnt_saldo 556 556 0 0.00 35 44730 1150 0 0
srv_make_money_saldo 837 837 0 0.00 1 3213 27 0 0
srv_recalc_idx_stat 4 4 0 0.00 125832 248981 197348 0 0
t$perf-abend:sp_add_invoice_to_stock 160 160 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5176 5176 0 0.00 0 16453 140 0 0
t$perf-norm:sp_cancel_adding_invoice 443 443 0 0.00 0 20941 129 0 0
t$perf-norm:sp_cancel_client_order 1816 1816 0 0.00 0 3680 13 0 0
t$perf-norm:sp_cancel_customer_reserve 742 742 0 0.00 0 6767 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 681 681 0 0.00 0 629 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 437 437 0 0.00 0 1054 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 432 432 0 0.00 0 1891 15 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 0 27513 1620 0 0
t$perf-norm:sp_cancel_write_off 1016 1016 0 0.00 0 3893 21 0 0
t$perf-norm:sp_client_order 21309 21309 0 0.00 0 12349 16 0 0
t$perf-norm:sp_customer_reserve 1014 1014 0 0.00 0 4193 13 0 0
t$perf-norm:sp_pay_from_customer 7223 7223 0 0.00 0 9193 11 0 0
t$perf-norm:sp_pay_to_supplier 6112 6112 0 0.00 0 5061 11 0 0
t$perf-norm:sp_reserve_write_off 10429 10429 0 0.00 0 12210 11 0 0
t$perf-norm:sp_supplier_invoice 5571 5571 0 0.00 0 6324 20 0 0
t$perf-norm:sp_supplier_order 5645 5645 0 0.00 0 12482 17 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 352 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 2180 92 0 0
t$perf-norm:srv_make_invnt_saldo 556 556 0 0.00 0 1013 19 0 0
t$perf-norm:srv_make_money_saldo 837 837 0 0.00 0 826 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 10668 10348 320 2.99 0 7038 19 0 320
v_all_customers 39010 39010 0 0.00 0 136 1 0 0
v_all_suppliers 31266 31266 0 0.00 0 16 1 0 0
v_all_wares 42618 42618 0 0.00 0 23 1 0 0
v_cancel_adding_invoice 886 886 0 0.00 0 298 6 0 0
v_cancel_client_order 3632 3632 0 0.00 0 1845 4 0 0
v_cancel_customer_prepayment 1362 1362 0 0.00 0 606 3 0 0
v_cancel_customer_reserve 1484 1484 0 0.00 0 350 4 0 0
v_cancel_payment_to_supplier 874 874 0 0.00 0 1585 7 0 0
v_cancel_supplier_invoice 886 864 22 2.48 0 6667 31 0 22
v_cancel_supplier_order 838 838 0 0.00 0 823 7 0 0
v_cancel_write_off 2032 2032 0 0.00 0 1541 12 0 0
v_max_id_clo_ord 5643 5643 0 0.00 0 654 1 0 0
v_max_id_clo_res 1014 1014 0 0.00 0 4345 20 0 0
v_max_id_ord_sup 5557 5557 0 0.00 0 6870 6 0 0
v_max_non_paid_invoice 1679 1679 0 0.00 0 5312 5 0 0
v_max_non_paid_realizn 3815 3815 0 0.00 0 7475 6 0 0
v_min_id_clo_ord 5643 5643 0 0.00 0 4653 9 0 0
v_min_id_clo_res 1014 1014 0 0.00 0 1329 5 0 0
v_min_id_ord_sup 5557 5557 0 0.00 0 3112 2 0 0
v_min_non_paid_invoice 6112 6112 0 0.00 0 11325 17 0 0
v_min_non_paid_realizn 7222 7222 0 0.00 0 16994 17 0 0
v_our_firm 10422 10422 0 0.00 0 12 1 0 0
v_reserve_write_off 20858 20858 0 0.00 0 3606 7 0 0
x_lock_depdocs_on_canc_invoice 12772 12772 0 0.00 0 24588 116 0 0
x_lock_depdocs_on_canc_sup_ord 419 419 0 0.00 0 10874 366 0 0
x_make_qstorno_1000_1200 5645 5645 0 0.00 1 43385 1096 0 0
x_make_qstorno_1200_2000 5571 5571 0 0.00 3 73595 3151 0 0
x_make_qstorno_2100_3300 475955 475955 0 0.00 0 82140 155 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-10-2021 01:40:16 to 26-10-2021 01:40:20.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
23:07:58 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:00 22919 8192000000 9900757488 10214031360 991021040 10.791 100 84 168 0 1018751360 5756208 21974112
23:10:26 22075 8192000000 9947123120 10269421568 1026534960 11.135 100 94 188 1 1059559472 8343680 24680832
23:11:51 8580 8192000000 9972847712 10303475712 1055947376 11.418 100 84 168 0 1087178784 10046704 21184704
23:13:04 13795 8192000000 10015944992 10363121664 1107370208 11.908 100 75 150 0 1136466752 11078480 18018064
23:14:23 13871 8192000000 10066230112 10424414208 1156153360 12.367 100 76 152 1 1185940480 12454496 17332624
23:15:42 6175 8192000000 10118541264 10489085952 1216405184 12.928 100 74 148 1 1246113584 13240928 16467472
23:16:50 3459 8192000000 10161883440 10538127360 1248585104 13.225 100 83 164 1 1279915744 12457664 18872976
23:17:54 2045 8192000000 10206003472 10586796032 1282813792 13.539 100 91 182 1 1317795264 13922576 21058896
23:18:58 1421 8192000000 10215739280 10599362560 1296226240 13.661 100 86 172 2 1327731504 12768832 18736432
23:20:00 9108 8192000000 10189542624 10600181760 1302236192 13.716 100 32 60 2 1315793888 5969040 7588656
23:21:11 6213 8192000000 10205020608 10618056704 1312109376 13.805 100 28 56 1 1328567488 10278416 6179696
23:22:19 9063 8192000000 10203981536 10621845504 1317886080 13.858 100 18 34 1 1327974848 6044592 4044176
23:23:31 21805 8192000000 10219564848 10644942848 1328240496 13.951 100 27 55 2 1344939072 10651840 6046736
23:25:34 2735 8192000000 10242134288 10657538048 1334966864 14.012 100 51 101 3 1358259168 11671120 11621184
23:26:37 9336 8192000000 10219957184 10642505728 1322176960 13.896 99 21 42 1 1333019456 5339856 5502640
23:27:48 9731 8192000000 10213867728 10630934528 1324099072 13.914 100 20 38 2 1333838816 5480352 4259392
23:28:58 27362 8192000000 10149100000 10559995904 1157780816 12.382 89 13 26 0 1165481792 4791312 2909664
23:30:27 4323 8192000000 10155781168 10551324672 1245501392 13.197 99 49 97 0 1265645360 8614992 11528976
23:31:33 8565 8192000000 10138689264 10533457920 1227914976 13.035 98 21 40 1 1237905296 5441232 4549088
23:32:42 57153 8192000000 10154740752 10564091904 1261371056 13.343 100 32 64 0 1282488448 13427568 7689824
23:34:39 8615 8192000000 10180253952 10593288192 1290225808 13.606 100 21 42 0 1300922240 5662912 5033520
23:35:52 5259 8192000000 10189156512 10607239168 1301718784 13.711 100 12 24 0 1309832880 5225456 2888640
23:36:58 9964 8192000000 10203362832 10619965440 1311710784 13.802 100 14 26 2 1320614480 5413552 3490144
23:38:08 60287 8192000000 10237896560 10649124864 1333054368 13.995 100 35 71 0 1348953632 8357664 7541600
23:40:11 13230 8192000000 10260601200 10672140288 1350982480 14.156 100 51 102 0 1378692576 16286688 11423408
23:41:27 2885 8192000000 10271574528 10690002944 1361818368 14.254 100 46 90 1 1388808624 16232480 10757776
23:42:32 9238 8192000000 10289213344 10708697088 1374147072 14.364 100 57 114 1 1405633616 17247920 14238624
23:43:44 5567 8192000000 10302543392 10720542720 1381984320 14.434 100 51 102 1 1416503920 21500816 13018784
23:44:56 10089 8192000000 10324312688 10744705024 1399045536 14.586 100 47 94 3 1436993760 26644288 11303936
23:46:13 5419 8192000000 10349908832 10767450112 1410831360 14.691 100 57 114 1 1458923728 33717536 14374832
23:47:23 6103 8192000000 10366732560 10787504128 1426666352 14.832 100 61 122 0 1475622112 33419856 15535904
23:48:38 8981 8192000000 10348252096 10763878400 1397298848 14.571 98 61 122 2 1451339712 37203840 16837024
23:49:56 6923 8192000000 10364691040 10782941184 1412074384 14.702 100 58 115 1 1472632080 45239584 15318112
23:51:06 7030 8192000000 10371767440 10801319936 1421425600 14.785 100 50 100 2 1478849872 44548640 12875632
23:52:16 2884 8192000000 10384495856 10795089920 1416418944 14.741 100 81 160 1 1481669744 44984128 20266672
23:53:30 3404 8192000000 10377231712 10794971136 1412116048 14.703 99 72 144 1 1477102464 46361984 18624432
23:54:38 9990 8192000000 10374584576 10793738240 1415757648 14.735 100 71 142 1 1480857440 47343280 17756512
23:56:07 8640 8192000000 10373095200 10803777536 1419257968 14.766 100 41 82 0 1480276416 49619264 11399184
23:57:19 6966 8192000000 10365107616 10790297600 1408218512 14.668 100 45 90 1 1467835888 47574864 12042512
23:58:29 5890 8192000000 10342227472 10770538496 1390333504 14.509 100 35 68 2 1450014304 50228176 9452624
23:59:43 29565 8192000000 10340193008 10764898304 1381011808 14.426 99 28 56 0 1431618704 42408800 8198096
00:01:20 13519 8192000000 10331879088 10762432512 1380009456 14.417 100 38 76 1 1437644480 46847824 10787200
00:02:35 36203 8192000000 10293028768 10741137408 1347341088 14.124 99 23 46 0 1400045600 45554672 7149840
00:04:45 23894 8192000000 10260776944 10710773760 1330424624 13.971 98 21 42 0 1352344336 17316512 4603200
00:06:14 50187 8192000000 10243130752 10669895680 1291590192 13.619 96 20 39 0 1307249216 11390416 4268608
00:08:11 74309 8192000000 10262434512 10702102528 1318397568 13.862 97 15 30 1 1340960032 19517360 3045104
00:10:32 206576 8192000000 10277648704 10728579072 1230680688 13.060 90 7 14 1 1247036272 14934912 1420672
00:14:59 92414 8192000000 10292089856 10721628160 1357349440 14.214 99 46 92 4 1377135232 9067600 10718192
00:17:40 32980 8192000000 10300138656 10734374912 1366492544 14.296 99 40 80 0 1386447040 10757984 9196512
00:19:26 24504 8192000000 10277858912 10708463616 1357287376 14.213 99 28 52 1 1368672400 5619168 5765856
00:20:51 18508 8192000000 10293437680 10728738816 1365166144 14.284 99 47 93 2 1386037248 10371296 10499808
00:22:18 25582 8192000000 10291319104 10736910336 1372464016 14.349 99 52 103 1 1396980352 12986384 11529952
00:24:05 11816 8192000000 10282621872 10742173696 1380071232 14.417 100 11 22 1 1391856464 9390368 2394864
00:25:18 48307 8192000000 10279044032 10741948416 1374312928 14.366 99 5 10 0 1383798608 8340608 1145072
00:27:07 64719 8192000000 10287821520 10748661760 1382430880 14.438 100 21 42 0 1403094976 15886560 4777536
00:29:12 101969 8192000000 10292797520 10763685888 1385509952 14.466 100 18 36 0 1406168336 15810240 4848144
00:31:54 59328 8192000000 10270873360 10736820224 1370566800 14.332 99 16 32 0 1382516672 8478480 3471392
00:33:54 57462 8192000000 10271639664 10734325760 1324442272 13.917 96 17 33 3 1343209328 15121264 3645792
00:35:53 45751 8192000000 10277390528 10733740032 1373580192 14.359 100 12 24 1 1388122928 11855200 2687536
00:37:40 57594 8192000000 10305172176 10751709184 1365512096 14.287 98 38 76 2 1385900384 11627232 8761056
00:39:41 76610 8192000000 10288853008 10751860736 1334868976 14.011 96 15 30 0 1349297984 11603696 2825312
00:41:58 49082 8192000000 10279532896 10730557440 1347840320 14.128 98 22 44 1 1368158480 15469280 4848880
00:43:51 58548 8192000000 10279568096 10725949440 1328668048 13.955 96 23 46 1 1345990992 11702848 5620096
00:45:52 94554 8192000000 10298934128 10746458112 1345513776 14.107 97 22 43 1 1365796640 14807952 5474912
00:48:47 99254 8192000000 10270823280 10724970496 1347264512 14.123 98 18 36 0 1363084096 12465536 3354048
00:51:34 42761 8192000000 10266114832 10720362496 1351715408 14.163 99 35 70 1 1371493392 12216224 7561760
00:53:24 52583 8192000000 10256268432 10710077440 1324713376 13.919 97 14 29 1 1341055456 12378752 3963328
00:55:18 55603 8192000000 10249835712 10695290880 1289161744 13.597 95 21 42 0 1306591072 11884448 5544880
00:57:19 40709 8192000000 10278105696 10726977536 1364528080 14.278 100 21 42 1 1391628880 21824256 5276544
00:59:16 42857 8192000000 10281316192 10744385536 1369490304 14.322 100 10 20 0 1395398992 23325104 2583584
01:01:00 57707 8192000000 10265709648 10721763328 1337987984 14.039 97 26 51 1 1362028768 17451200 6589584
01:02:58 25869 8192000000 10275428144 10724642816 1358306816 14.222 99 19 38 0 1371472464 9181888 3983760
01:04:24 60789 8192000000 10296320912 10750263296 1351809792 14.164 99 16 32 0 1370498912 14927776 3761344
01:06:41 29441 8192000000 10304290048 10740514816 1377588752 14.395 100 43 86 1 1397970192 11186928 9194512
01:08:12 138761 8192000000 10307347344 10781118464 1346058672 14.112 97 14 28 1 1371338896 22353008 2927216
01:11:31 16966 8192000000 10279953248 10720972800 1318291104 13.861 96 27 54 2 1337293856 12669088 6333664
01:12:49 133909 8192000000 10282706560 10737537024 1369649376 14.324 100 18 36 0 1383596960 9242800 4704784
01:16:03 41926 8192000000 10303001456 10749743104 1382122160 14.436 100 38 76 2 1402858224 12214960 8521104
01:18:04 94161 8192000000 10291943808 10760962048 1370917488 14.335 99 8 16 0 1388305088 15687024 1700576
01:20:46 60408 8192000000 10285041456 10745077760 1377209792 14.392 99 16 32 0 1393342512 12279488 3853232
01:22:47 63087 8192000000 10276129568 10745241600 1329034800 13.958 96 7 14 0 1347584688 16969280 1580608
01:24:52 87732 8192000000 10285317344 10743590912 1375001568 14.372 99 16 32 0 1390550256 10980416 4568272
01:27:22 65596 8192000000 10277857984 10730094592 1302613408 13.719 94 15 30 0 1315811696 9621392 3576896
01:29:32 60131 8192000000 10278929376 10729701376 1345176752 14.104 98 15 28 0 1357848176 10007568 2663856
01:31:37 53194 8192000000 10295718432 10763919360 1373437984 14.358 99 26 52 0 1398458656 18812864 6207808
01:34:08 36857 8192000000 10290604304 10734854144 1359080976 14.229 99 15 29 1 1372705568 10419424 3205168
01:36:49 76510 8192000000 9293907408 9585135616 446774608 5.171 33 8 16 0 456738368 8026640 1937120

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 26-10-2021 01:40:22 to 26-10-2021 01:40:36.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 160 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 26-10-2021 01:40:37 to 26-10-2021 01:40:39.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          245131
MON$OLDEST_ACTIVE               245132
MON$OLDEST_SNAPSHOT             245132
MON$NEXT_TRANSACTION            245132
MON$PAGE_BUFFERS                1000000
MON$SQL_DIALECT                 3
MON$SHUTDOWN_MODE               0
MON$SWEEP_INTERVAL              0
MON$READ_ONLY                   0
MON$FORCED_WRITES               1
MON$RESERVE_SPACE               1
MON$CREATION_DATE               2020-04-03 16:41:55.3470
MON$PAGES                       2056736
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33524 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33524 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33524 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33524 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:289: 
:290: mscs-test-161.budzdorov.ru    Tue Oct 26 01:38:52 2021
:291:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:292: 
--- end of diff output ---

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

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