Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.10.2021 16: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 15786256 kB
MemAvailable 30703360 kB
Buffers 0 kB
Cached 15923728 kB
SwapCached 3856 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-11 16:37:53
DTS_END                         2021-10-11 16:37:53.3800
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/49300
TRN_ID                          238865
ATT_ID                          693
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: 9617.23 26018 1154067
customer order: creation 165.49 337 19859
customer order: refuse 13.79 329 1655
order to supplier: creation 45.57 1317 5468
order to supplier: removal 3.83 90157 460
invoice (draft): creation 44.11 3561 5293
invoice (draft): removal 57.86 732 6943
invoice accept: apply 39.82 16375 4778
invoice accept: cancel 52.21 764 6265
customer reserve: creation 3752.83 158 450340
customer reserve: removal 5144.76 52 617371
realization accept: apply 81.03 173 9723
realization accept: cancel 89.42 101 10730
payment from customer: creation 57.81 101 6937
payment from customer: removal 5.66 78 679
payment to supplier: creation 47.61 123 5713
payment to supplier: removal 3.63 53 435
service: total inventory turnovers 4.67 1273 560
service: total monetary turnovers 6.29 27 755
service: aggregate perf. data 0.83 21754 99
service: refresh index statistics 0.03 382892 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-10-2021 16:40:13 to 11-10-2021 16:40:15.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5033
TEST_TIME 2 4469
TEST_TIME 3 4700
TEST_TIME 4 4768
TEST_TIME 5 4641
TEST_TIME 6 4648
TEST_TIME 7 5071
TEST_TIME 8 5314
TEST_TIME 9 5193
TEST_TIME 10 6131
TEST_TIME 11 6207
TEST_TIME 12 6302
TEST_TIME 13 6416
TEST_TIME 14 6487
TEST_TIME 15 6463
TEST_TIME 16 6870
TEST_TIME 17 7009
TEST_TIME 18 7691
TEST_TIME 19 8385
TEST_TIME 20 8701
TEST_TIME 21 9002
TEST_TIME 22 9329
TEST_TIME 23 9162
TEST_TIME 24 9516
TEST_TIME 25 9672
TEST_TIME 26 9702
TEST_TIME 27 10275
TEST_TIME 28 10930
TEST_TIME 29 10771
TEST_TIME 30 10669
TEST_TIME 31 10628
TEST_TIME 32 10537
TEST_TIME 33 10640
TEST_TIME 34 10563
TEST_TIME 35 10359
TEST_TIME 36 10382
TEST_TIME 37 10286
TEST_TIME 38 10194
TEST_TIME 39 10093
TEST_TIME 40 10079
TEST_TIME 41 10126
TEST_TIME 42 10242
TEST_TIME 43 10148
TEST_TIME 44 10001
TEST_TIME 45 10024
TEST_TIME 46 9924
TEST_TIME 47 9870
TEST_TIME 48 9795
TEST_TIME 49 9699
TEST_TIME 50 9626
TEST_TIME 51 9680
TEST_TIME 52 9714
TEST_TIME 53 9697
TEST_TIME 54 9656
TEST_TIME 55 9728
TEST_TIME 56 9755
TEST_TIME 57 9669
TEST_TIME 58 9878
TEST_TIME 59 9990
TEST_TIME 60 9916
TEST_TIME 61 10054
TEST_TIME 62 10068
TEST_TIME 63 10063
TEST_TIME 64 10218
TEST_TIME 65 10193
TEST_TIME 66 10259
TEST_TIME 67 10139
TEST_TIME 68 10097
TEST_TIME 69 10111
TEST_TIME 70 10063
TEST_TIME 71 10027
TEST_TIME 72 9982
TEST_TIME 73 9938
TEST_TIME 74 10019
TEST_TIME 75 10009
TEST_TIME 76 10027
TEST_TIME 77 9958
TEST_TIME 78 9921
TEST_TIME 79 9981
TEST_TIME 80 9978
TEST_TIME 81 9957
TEST_TIME 82 10055
TEST_TIME 83 10053
TEST_TIME 84 10007
TEST_TIME 85 9902
TEST_TIME 86 9963
TEST_TIME 87 9940
TEST_TIME 88 9876
TEST_TIME 89 9850
TEST_TIME 90 9862
TEST_TIME 91 9848
TEST_TIME 92 9791
TEST_TIME 93 9764
TEST_TIME 94 9717
TEST_TIME 95 9767
TEST_TIME 96 9761
TEST_TIME 97 9742
TEST_TIME 98 9717
TEST_TIME 99 9756
TEST_TIME 100 9804
TEST_TIME 101 9761
TEST_TIME 102 9770
TEST_TIME 103 9870
TEST_TIME 104 9819
TEST_TIME 105 9830
TEST_TIME 106 9836
TEST_TIME 107 9781
TEST_TIME 108 9921
TEST_TIME 109 9941
TEST_TIME 110 9869
TEST_TIME 111 9801
TEST_TIME 112 9792
TEST_TIME 113 9748
TEST_TIME 114 9739
TEST_TIME 115 9711
TEST_TIME 116 9739
TEST_TIME 117 9693
TEST_TIME 118 9687
TEST_TIME 119 9629
TEST_TIME 120 9619

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-10-2021 16:40:17 to 11-10-2021 16:40:20.

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 1571962 1571962 0 0.00 0 15440 5 0 0
doc_list_biud 1571961 1571961 0 0.00 0 59774 26 0 0
sp_add_invoice_to_stock 4925 4778 147 2.98 36 166910 16375 0 147
sp_cancel_adding_invoice 6265 6265 0 0.00 3 49683 764 0 0
sp_cancel_client_order 1655 1655 0 0.00 4 8385 330 0 0
sp_cancel_customer_reserve 617371 617371 0 0.00 1 45141 52 0 0
sp_cancel_pay_from_customer 679 679 0 0.00 3 5973 79 0 0
sp_cancel_pay_to_supplier 435 435 0 0.00 2 1726 54 0 0
sp_cancel_supplier_invoice 6953 6943 10 0.14 1 59801 732 0 10
sp_cancel_supplier_order 460 460 0 0.00 10 970421 90158 0 0
sp_cancel_write_off 10730 10730 0 0.00 1 14541 101 0 0
sp_client_order 19859 19859 0 0.00 3 18267 338 0 0
sp_customer_reserve 450340 450340 0 0.00 0 92856 158 0 0
sp_fill_shopping_cart 30600 30600 0 0.00 1 12643 36 0 0
sp_fill_shopping_cart_clo_res 450336 450336 0 0.00 0 12480 13 0 0
sp_get_clo_for_invoice 4774 4774 0 0.00 0 31109 299 0 0
sp_kill_cost_storno 18109 18109 0 0.00 0 13595 53 0 0
sp_kill_qstorno_ret_qs2qd 1252875 1252875 0 0.00 0 59770 28 0 0
sp_kill_qty_storno 657920 657920 0 0.00 0 59773 62 0 0
sp_lock_selected_doc 20191 20191 0 0.00 0 8848 7 0 0
sp_make_cost_storno 27147 27147 0 0.00 0 13410 53 0 0
sp_multiply_rows_for_pdistr 27146 27146 0 0.00 0 8344 27 0 0
sp_multiply_rows_for_qdistr 19859 19859 0 0.00 0 12292 158 0 0
sp_pay_from_customer 6937 6937 0 0.00 4 10523 101 0 0
sp_pay_to_supplier 5713 5713 0 0.00 5 13519 124 0 0
sp_payment_common 12650 12650 0 0.00 1 9626 27 0 0
sp_qd_handle_on_cancel_clo 1655 1655 0 0.00 0 7143 293 0 0
sp_qd_handle_on_invoice_upd_sts 11039 11039 0 0.00 0 37142 330 0 0
sp_qd_handle_on_reserve_upd_sts 9722 9722 0 0.00 0 15317 55 0 0
sp_reserve_write_off 9723 9723 0 0.00 6 17426 173 0 0
sp_supplier_invoice 5293 5293 0 0.00 6 101714 3561 0 0
sp_supplier_order 5468 5468 0 0.00 4 38827 1317 0 0
srv_aggregate_perf_data 99 99 0 0.00 3576 275406 21755 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 3434 136799 51686 0 0
srv_find_qd_qs_mism 1108069 1108069 0 0.00 0 15440 4 0 0
srv_make_invnt_saldo 560 560 0 0.00 41 88561 1273 0 0
srv_make_money_saldo 755 755 0 0.00 1 2556 27 0 0
srv_recalc_idx_stat 4 4 0 0.00 61493 658995 382892 0 0
t$perf-abend:sp_add_invoice_to_stock 147 147 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 4778 4778 0 0.00 0 15937 188 0 0
t$perf-norm:sp_cancel_adding_invoice 402 402 0 0.00 0 10975 148 0 0
t$perf-norm:sp_cancel_client_order 1655 1655 0 0.00 0 9090 19 0 0
t$perf-norm:sp_cancel_customer_reserve 655 655 0 0.00 0 3063 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 679 679 0 0.00 0 2035 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 435 435 0 0.00 0 533 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 404 404 0 0.00 0 3897 26 0 0
t$perf-norm:sp_cancel_supplier_order 460 460 0 0.00 0 20640 1590 0 0
t$perf-norm:sp_cancel_write_off 1005 1005 0 0.00 0 5237 18 0 0
t$perf-norm:sp_client_order 19859 19859 0 0.00 0 9098 20 0 0
t$perf-norm:sp_customer_reserve 950 950 0 0.00 0 9342 23 0 0
t$perf-norm:sp_pay_from_customer 6937 6937 0 0.00 0 9208 14 0 0
t$perf-norm:sp_pay_to_supplier 5713 5713 0 0.00 0 7286 11 0 0
t$perf-norm:sp_reserve_write_off 9723 9723 0 0.00 0 9082 18 0 0
t$perf-norm:sp_supplier_invoice 5293 5293 0 0.00 0 7254 22 0 0
t$perf-norm:sp_supplier_order 5468 5468 0 0.00 0 8998 19 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 168 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 254 4 0 0
t$perf-norm:srv_make_invnt_saldo 560 560 0 0.00 0 4923 26 0 0
t$perf-norm:srv_make_money_saldo 755 755 0 0.00 0 5531 25 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 58 17 0 0
v_add_invoice_to_stock 9842 9548 294 2.98 0 8289 21 0 294
v_all_customers 36252 36252 0 0.00 0 117 0 0 0
v_all_suppliers 30150 30150 0 0.00 0 131 1 0 0
v_all_wares 39718 39718 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 804 804 0 0.00 0 62 5 0 0
v_cancel_client_order 3310 3310 0 0.00 0 4066 8 0 0
v_cancel_customer_prepayment 1358 1358 0 0.00 0 2747 7 0 0
v_cancel_customer_reserve 1310 1310 0 0.00 0 1749 6 0 0
v_cancel_payment_to_supplier 870 870 0 0.00 0 77 2 0 0
v_cancel_supplier_invoice 828 808 20 2.41 0 4222 24 0 20
v_cancel_supplier_order 920 920 0 0.00 0 1689 5 0 0
v_cancel_write_off 2010 2010 0 0.00 0 1213 11 0 0
v_max_id_clo_ord 5459 5459 0 0.00 0 5100 3 0 0
v_max_id_clo_res 950 950 0 0.00 0 9396 24 0 0
v_max_id_ord_sup 5282 5282 0 0.00 0 2695 3 0 0
v_max_non_paid_invoice 1379 1379 0 0.00 0 4072 7 0 0
v_max_non_paid_realizn 3667 3667 0 0.00 0 6399 6 0 0
v_min_id_clo_ord 5459 5459 0 0.00 0 4515 7 0 0
v_min_id_clo_res 950 950 0 0.00 0 5632 9 0 0
v_min_id_ord_sup 5282 5282 0 0.00 0 6293 9 0 0
v_min_non_paid_invoice 5713 5713 0 0.00 0 9620 22 0 0
v_min_non_paid_realizn 6937 6937 0 0.00 0 8884 18 0 0
v_our_firm 10006 10006 0 0.00 0 99 1 0 0
v_reserve_write_off 19444 19444 0 0.00 0 4479 6 0 0
x_lock_depdocs_on_canc_invoice 12804 12804 0 0.00 0 14697 108 0 0
x_lock_depdocs_on_canc_sup_ord 460 460 0 0.00 0 19164 363 0 0
x_make_qstorno_1000_1200 5468 5468 0 0.00 2 38610 1239 0 0
x_make_qstorno_1200_2000 5293 5293 0 0.00 2 101703 3437 0 0
x_make_qstorno_2100_3300 450340 450340 0 0.00 0 92851 143 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-10-2021 16:40:21 to 11-10-2021 16:40:25.

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
14:07:57 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:08:58 37008 8192000000 9910503536 10223501312 985973024 10.742 100 88 176 0 1015795632 6259568 23563040
14:10:38 16411 8192000000 9946569792 10268200960 1026820768 11.138 100 89 178 0 1058365776 8740096 22804912
14:12:02 10011 8192000000 9972508720 10304106496 1055837792 11.417 100 89 178 0 1088185136 9885008 22462336
14:13:17 12345 8192000000 10025335120 10371530752 1110336896 11.936 100 81 163 0 1141372128 11362832 19672400
14:14:33 8734 8192000000 10072753664 10433114112 1158357616 12.388 100 79 158 1 1189247024 12752672 18136736
14:15:45 4958 8192000000 10121529040 10495098880 1212740288 12.894 100 74 147 0 1241333744 12534336 16059120
14:16:52 2894 8192000000 10168993808 10547687424 1256674048 13.300 100 82 165 1 1288185904 13095680 18416176
14:17:58 2071 8192000000 10207869280 10591846400 1289908464 13.603 100 82 166 2 1320686288 12225936 18551888
14:19:01 36982 8192000000 10215346272 10606911488 1306164880 13.751 100 71 140 1 1329478896 6402464 16911552
14:20:39 5415 8192000000 10201088464 10612932608 1308172224 13.769 100 39 79 1 1323558640 6981440 8404976
14:21:47 9858 8192000000 10201589792 10621100032 1314631088 13.828 100 25 50 1 1326621664 6246336 5744240
14:22:58 13893 8192000000 10206135984 10629079040 1323472800 13.908 100 14 28 0 1332175200 5698688 3003712
14:24:14 61839 8192000000 10232179648 10645065728 1330101280 13.968 100 47 94 1 1346695968 6987968 9606720
14:26:16 5111 8192000000 10237885072 10655846400 1336654848 14.027 100 38 74 2 1356683728 12106480 7922400
14:27:22 28963 8192000000 10217040256 10635632640 1314768640 13.829 99 34 67 0 1327989232 6351392 6869200
14:28:51 7437 8192000000 10192204352 10595319808 1283500640 13.545 98 31 61 2 1295550960 4931712 7118608
14:29:59 11805 8192000000 10147291968 10548834304 1179604944 12.587 92 15 30 0 1188147056 4662144 3879968
14:31:12 46825 8192000000 10145736336 10543890432 1197994576 12.758 95 36 71 1 1212426864 5266032 9166256
14:33:03 7309 8192000000 10152145312 10560475136 1257616064 13.308 99 29 58 1 1270800976 6078336 7106576
14:34:10 4831 8192000000 10176323424 10590105600 1288347552 13.589 100 29 59 1 1302310624 5987984 7975088
14:35:17 17559 8192000000 10181764320 10597163008 1292914816 13.631 100 17 34 0 1302423648 5792656 3716176
14:36:35 13439 8192000000 10185725232 10605502464 1298664064 13.683 100 19 38 1 1308480896 5652784 4164048
14:37:56 4620 8192000000 10223961648 10634145792 1322367152 13.898 100 46 92 0 1338742944 5026080 11349712
14:39:06 28247 8192000000 10253577200 10669977600 1345228800 14.105 100 53 106 1 1366866432 9576096 12061536
14:40:39 9817 8192000000 10272920272 10690301952 1364032960 14.274 100 51 102 1 1389315888 13223712 12059216
14:41:52 61757 8192000000 10282891856 10708447232 1370773664 14.334 100 40 80 1 1393426368 13008080 9644624
14:43:57 10353 8192000000 10292466640 10714939392 1369965504 14.327 99 49 98 2 1400079936 18031184 12083248
14:45:14 13188 8192000000 10302689488 10721275904 1383922880 14.452 100 55 107 2 1414820592 18354176 12543536
14:46:31 17170 8192000000 10338357680 10760798208 1405789472 14.647 100 57 114 0 1448465472 28570928 14105072
14:48:03 13702 8192000000 10338696080 10764881920 1413203152 14.712 100 54 108 0 1452869904 26633504 13033248
14:49:20 25666 8192000000 10331672448 10761326592 1405236976 14.642 100 41 82 2 1440527872 24755392 10535504
14:50:49 5553 8192000000 10343143408 10773958656 1414525488 14.724 100 48 96 0 1455664720 28756960 12382272
14:52:00 4168 8192000000 10362928272 10780430336 1413732816 14.717 100 74 146 0 1463659840 32053360 17873664
14:53:08 3432 8192000000 10348828672 10769203200 1405835376 14.647 100 67 133 1 1457548928 34519408 17194144
14:54:19 12223 8192000000 10299092992 10720063488 1350715088 14.154 99 52 104 2 1400721552 35983360 14023104
14:55:36 5083 8192000000 10324840192 10732396544 1364092288 14.274 100 75 149 1 1427261376 44028736 19140352
14:56:44 18594 8192000000 10286123920 10709426176 1349313728 14.141 100 33 66 0 1393453568 34864624 9275216
14:58:08 15345 8192000000 10298461040 10722934784 1357673344 14.216 100 34 70 1 1401654672 34580864 9400464
14:59:45 6335 8192000000 10287819936 10710581248 1344370608 14.097 98 45 90 1 1385392096 29582432 11439056
15:00:57 11306 8192000000 10274520560 10697392128 1322937088 13.903 98 35 70 1 1369435312 36434576 10063648
15:02:18 16433 8192000000 10286984208 10720739328 1339289872 14.051 99 28 56 4 1384107424 37663712 7153840
15:03:58 22029 8192000000 10276593952 10705985536 1331166944 13.978 99 18 36 1 1376006336 39448480 5390912
15:05:23 78960 8192000000 10271278400 10706059264 1336528816 14.026 99 26 51 0 1364325584 21711936 6084832
15:07:44 30111 8192000000 10273498016 10709016576 1353642896 14.180 100 10 20 1 1368294096 12354048 2297152
15:09:21 73890 8192000000 10282265568 10720944128 1323765456 13.911 96 25 50 0 1346848368 17756256 5326656
15:11:39 101943 8192000000 10286287600 10724233216 1369892672 14.326 100 30 60 0 1391986032 14280192 7813168
15:14:24 109766 8192000000 10289974144 10737369088 1357555808 14.215 98 15 30 0 1372063120 10972320 3534992
15:17:15 34392 8192000000 10316477440 10757169152 1393859152 14.540 100 46 92 1 1419836032 15106896 10869984
15:18:55 40495 8192000000 10298653232 10769551360 1400478112 14.599 100 12 24 0 1415348528 11821584 3048832
15:20:38 34513 8192000000 10309338912 10770247680 1409946736 14.683 100 12 24 0 1421802400 9386384 2469280
15:22:14 31887 8192000000 10311031056 10776813568 1411716064 14.699 100 11 22 1 1426537952 12426960 2394928
15:23:47 66656 8192000000 10300273056 10777890816 1412691904 14.708 100 11 22 0 1423223776 7590640 2941232
15:25:55 64068 8192000000 10314582336 10780131328 1416557312 14.742 100 18 36 0 1431905664 11103520 4244832
15:28:00 48627 8192000000 10318432960 10802638848 1423801216 14.806 100 11 22 1 1449201440 22763008 2637216
15:29:50 131627 8192000000 10325482624 10775568384 1362820624 14.263 96 17 34 0 1378269024 12330592 3117808
15:33:08 55636 8192000000 10312032432 10767253504 1381209056 14.427 98 21 42 0 1402954144 16378864 5366224
15:35:05 73750 8192000000 10305136208 10771460096 1381707632 14.432 98 17 34 0 1408218880 22457920 4053328
15:37:20 48140 8192000000 10299342384 10751709184 1356679584 14.208 97 16 32 0 1375807952 14997120 4131248
15:39:10 101014 8192000000 10279410176 10734878720 1309836704 13.785 94 9 18 1 1323787840 12325232 1625904
15:41:51 106834 8192000000 10285269152 10750226432 1313110144 13.814 95 11 21 1 1331582096 16322016 2149936
15:44:38 63466 8192000000 10259055488 10706337792 1334587216 14.009 98 30 60 1 1353595600 11666112 7342272
15:46:43 66435 8192000000 10267716656 10737876992 1344778912 14.100 99 5 10 0 1363494864 17840032 875920
15:48:50 93416 8192000000 10272585184 10715807744 1309308272 13.780 96 22 43 0 1326320976 11910528 5102176
15:51:26 85830 8192000000 10246930544 10685321216 1278277696 13.497 95 20 40 0 1291181232 8381344 4522192
15:54:07 44266 8192000000 10262282480 10687967232 1309489632 13.781 97 49 98 0 1328461984 7979168 10993184
15:55:53 35422 8192000000 10254863680 10693312512 1302170240 13.715 97 40 79 0 1327581488 16743120 8668128
15:57:29 88218 8192000000 10271827184 10705866752 1311594208 13.801 97 25 51 3 1327993584 10420064 5979312
16:00:00 115096 8192000000 10286668464 10736496640 1357210880 14.212 99 19 38 0 1372188000 10093824 4883296
16:02:55 80244 8192000000 10285607648 10740203520 1378455904 14.403 100 24 48 2 1401234928 16697536 6081488
16:05:23 22339 8192000000 10274429504 10726580224 1363814576 14.272 99 41 82 1 1381620544 8592416 9213552
16:07:20 62311 8192000000 10292846160 10759585792 1392686560 14.530 100 10 20 0 1411656912 16499568 2470784
16:09:23 68921 8192000000 10307192800 10768379904 1397455440 14.572 99 13 26 0 1410493344 9986704 3051200
16:11:53 92491 8192000000 10308438784 10786930688 1396669104 14.565 99 10 20 0 1412894384 13860160 2365120
16:14:27 67583 8192000000 10334521872 10796900352 1422322784 14.793 100 29 58 1 1441137456 12340576 6474096
16:16:35 103415 8192000000 10328474960 10801840128 1380456608 14.421 97 13 26 1 1397947216 14355184 3135424
16:19:21 42455 8192000000 10333711232 10786144256 1411684144 14.699 100 25 51 1 1437292000 19901536 5706320
16:21:14 53431 8192000000 10323582512 10767319040 1333659872 14.000 94 23 46 1 1350261792 11218944 5382976
16:23:44 29273 8192000000 10296375552 10746949632 1373556656 14.359 99 21 42 0 1389576080 11027568 4991856
16:25:16 136799 8192000000 10290978800 10757656576 1338965232 14.048 96 12 23 0 1357514784 15917776 2631776
16:29:46 54571 8192000000 10295814384 10762522624 1364303184 14.276 97 15 30 1 1383494720 15880656 3310880
16:31:53 69382 8192000000 10290342432 10763591680 1380349120 14.420 99 13 25 0 1399814416 16462672 3002624
16:34:02 63831 8192000000 10277738656 10734854144 1355385376 14.196 98 21 42 1 1367288352 7618512 4284464
16:36:41 76219 8192000000 9946332992 10359808000 1047282704 11.335 76 27 54 0 1066583936 13195680 6105552

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 11-10-2021 16:40:27 to 11-10-2021 16:40:41.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 147 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:02, from 11-10-2021 16:40:42 to 11-10-2021 16:40:44.

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          236484
MON$OLDEST_ACTIVE               239029
MON$OLDEST_SNAPSHOT             239029
MON$NEXT_TRANSACTION            239029
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                       2049632
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.33520 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33520 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33520 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33520 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 ---
:134: 
:135: mscs-test-161.budzdorov.ru    Mon Oct 11 16:38:08 2021
:136:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:137: 
:138: 
:139: mscs-test-161.budzdorov.ru    Mon Oct 11 16:38:11 2021
:140:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:141: 
:142: 
:143: mscs-test-161.budzdorov.ru    Mon Oct 11 16:38:25 2021
:144:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:145: 
:146: 
:147: mscs-test-161.budzdorov.ru    Mon Oct 11 16:38:25 2021
:148:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:149: 
--- end of diff output ---

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

11.10.21 16:40:45. 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-11 16:40:48 No severe PSQL-related problems occured
11.10.21 16:40:49. End of report.