Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.09.2021 19: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 15799472 kB
MemAvailable 30825204 kB
Buffers 0 kB
Cached 15901152 kB
SwapCached 488 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-09-19 19:37:50
DTS_END                         2021-09-19 19:37:50.5990
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/47586
TRN_ID                          239595
ATT_ID                          710
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: 9721.23 15385 1166548
customer order: creation 174.16 370 20899
customer order: refuse 15.39 361 1847
order to supplier: creation 45.92 1337 5510
order to supplier: removal 3.80 100638 456
invoice (draft): creation 45.48 3518 5458
invoice (draft): removal 56.54 885 6785
invoice accept: apply 42.50 18310 5100
invoice accept: cancel 50.76 793 6091
customer reserve: creation 3929.31 181 471517
customer reserve: removal 5056.52 57 606782
realization accept: apply 84.07 209 10088
realization accept: cancel 86.59 116 10391
payment from customer: creation 59.33 126 7120
payment from customer: removal 6.01 68 721
payment to supplier: creation 49.64 150 5957
payment to supplier: removal 3.29 113 395
service: total inventory turnovers 4.70 1243 564
service: total monetary turnovers 6.33 36 759
service: aggregate perf. data 0.87 20024 104
service: refresh index statistics 0.03 159166 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 19-09-2021 19:39:45 to 19-09-2021 19:39:47.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2172
TEST_TIME 2 2066
TEST_TIME 3 2550
TEST_TIME 4 2946
TEST_TIME 5 3445
TEST_TIME 6 3998
TEST_TIME 7 4317
TEST_TIME 8 4642
TEST_TIME 9 4637
TEST_TIME 10 5638
TEST_TIME 11 5787
TEST_TIME 12 5882
TEST_TIME 13 6048
TEST_TIME 14 5904
TEST_TIME 15 5850
TEST_TIME 16 6083
TEST_TIME 17 6153
TEST_TIME 18 6454
TEST_TIME 19 6676
TEST_TIME 20 6840
TEST_TIME 21 7408
TEST_TIME 22 7594
TEST_TIME 23 8380
TEST_TIME 24 8508
TEST_TIME 25 9049
TEST_TIME 26 9936
TEST_TIME 27 9991
TEST_TIME 28 9861
TEST_TIME 29 10503
TEST_TIME 30 10455
TEST_TIME 31 10302
TEST_TIME 32 10141
TEST_TIME 33 10167
TEST_TIME 34 10093
TEST_TIME 35 10227
TEST_TIME 36 10145
TEST_TIME 37 10213
TEST_TIME 38 10271
TEST_TIME 39 10128
TEST_TIME 40 10145
TEST_TIME 41 9984
TEST_TIME 42 10048
TEST_TIME 43 9895
TEST_TIME 44 9828
TEST_TIME 45 9876
TEST_TIME 46 9842
TEST_TIME 47 9763
TEST_TIME 48 9853
TEST_TIME 49 9709
TEST_TIME 50 9656
TEST_TIME 51 9754
TEST_TIME 52 9734
TEST_TIME 53 9752
TEST_TIME 54 9679
TEST_TIME 55 9658
TEST_TIME 56 9636
TEST_TIME 57 9569
TEST_TIME 58 9515
TEST_TIME 59 9758
TEST_TIME 60 9697
TEST_TIME 61 9749
TEST_TIME 62 9825
TEST_TIME 63 9848
TEST_TIME 64 9906
TEST_TIME 65 9984
TEST_TIME 66 9962
TEST_TIME 67 10009
TEST_TIME 68 10022
TEST_TIME 69 10022
TEST_TIME 70 9943
TEST_TIME 71 9941
TEST_TIME 72 9861
TEST_TIME 73 9847
TEST_TIME 74 9859
TEST_TIME 75 9805
TEST_TIME 76 9802
TEST_TIME 77 9849
TEST_TIME 78 9781
TEST_TIME 79 9765
TEST_TIME 80 9794
TEST_TIME 81 9781
TEST_TIME 82 9752
TEST_TIME 83 9752
TEST_TIME 84 9744
TEST_TIME 85 9727
TEST_TIME 86 9759
TEST_TIME 87 9782
TEST_TIME 88 9750
TEST_TIME 89 9869
TEST_TIME 90 9902
TEST_TIME 91 9879
TEST_TIME 92 9920
TEST_TIME 93 9961
TEST_TIME 94 9922
TEST_TIME 95 9846
TEST_TIME 96 9858
TEST_TIME 97 9785
TEST_TIME 98 9770
TEST_TIME 99 9783
TEST_TIME 100 9723
TEST_TIME 101 9657
TEST_TIME 102 9650
TEST_TIME 103 9568
TEST_TIME 104 9643
TEST_TIME 105 9660
TEST_TIME 106 9702
TEST_TIME 107 9679
TEST_TIME 108 9704
TEST_TIME 109 9667
TEST_TIME 110 9669
TEST_TIME 111 9672
TEST_TIME 112 9710
TEST_TIME 113 9708
TEST_TIME 114 9718
TEST_TIME 115 9752
TEST_TIME 116 9781
TEST_TIME 117 9772
TEST_TIME 118 9804
TEST_TIME 119 9736
TEST_TIME 120 9726

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 19-09-2021 19:39:48 to 19-09-2021 19:39:52.

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 1605558 1605558 0 0.00 0 27564 7 0 0
doc_list_biud 1605549 1605549 0 0.00 0 79047 28 0 0
sp_add_invoice_to_stock 5244 5100 144 2.74 30 179694 18310 0 144
sp_cancel_adding_invoice 6091 6091 0 0.00 4 84352 793 0 0
sp_cancel_client_order 1847 1847 0 0.00 4 11859 362 0 0
sp_cancel_customer_reserve 606782 606782 0 0.00 1 29002 58 0 0
sp_cancel_pay_from_customer 721 721 0 0.00 3 2714 68 0 0
sp_cancel_pay_to_supplier 395 395 0 0.00 3 8500 114 0 0
sp_cancel_supplier_invoice 6792 6785 7 0.10 2 79055 885 0 7
sp_cancel_supplier_order 456 456 0 0.00 7 1153419 100639 0 0
sp_cancel_write_off 10391 10391 0 0.00 1 16571 117 0 0
sp_client_order 20899 20899 0 0.00 4 20809 370 0 0
sp_customer_reserve 471517 471517 0 0.00 0 38577 182 0 0
sp_fill_shopping_cart 31865 31865 0 0.00 1 21008 38 0 0
sp_fill_shopping_cart_clo_res 471505 471505 0 0.00 0 27943 13 0 0
sp_get_clo_for_invoice 5088 5088 0 0.00 0 13504 215 0 0
sp_kill_cost_storno 17598 17598 0 0.00 0 8241 57 0 0
sp_kill_qstorno_ret_qs2qd 1231196 1231196 0 0.00 0 79044 32 0 0
sp_kill_qty_storno 647523 647523 0 0.00 0 79046 69 0 0
sp_lock_selected_doc 21132 21132 0 0.00 0 6879 3 0 0
sp_make_cost_storno 28253 28253 0 0.00 0 18215 63 0 0
sp_multiply_rows_for_pdistr 28244 28244 0 0.00 0 12072 31 0 0
sp_multiply_rows_for_qdistr 20899 20899 0 0.00 0 20278 150 0 0
sp_pay_from_customer 7120 7120 0 0.00 4 12078 127 0 0
sp_pay_to_supplier 5957 5957 0 0.00 4 12794 151 0 0
sp_payment_common 13073 13073 0 0.00 1 9468 31 0 0
sp_qd_handle_on_cancel_clo 1847 1847 0 0.00 0 8739 324 0 0
sp_qd_handle_on_invoice_upd_sts 11175 11175 0 0.00 0 22326 345 0 0
sp_qd_handle_on_reserve_upd_sts 10087 10087 0 0.00 0 22929 74 0 0
sp_reserve_write_off 10088 10088 0 0.00 6 22981 210 0 0
sp_supplier_invoice 5458 5458 0 0.00 6 99823 3519 0 0
sp_supplier_order 5510 5510 0 0.00 5 38698 1337 0 0
srv_aggregate_perf_data 104 104 0 0.00 2438 218976 20024 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 2103 144807 45219 0 0
srv_find_qd_qs_mism 1119442 1119442 0 0.00 0 27563 5 0 0
srv_make_invnt_saldo 564 564 0 0.00 55 139350 1243 0 0
srv_make_money_saldo 759 759 0 0.00 1 6671 37 0 0
srv_recalc_idx_stat 4 4 0 0.00 52359 278248 159166 0 0
t$perf-abend:sp_add_invoice_to_stock 144 144 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5100 5100 0 0.00 0 29536 181 0 0
t$perf-norm:sp_cancel_adding_invoice 404 404 0 0.00 0 4617 99 0 0
t$perf-norm:sp_cancel_client_order 1847 1847 0 0.00 0 6223 13 0 0
t$perf-norm:sp_cancel_customer_reserve 680 680 0 0.00 0 4482 21 0 0
t$perf-norm:sp_cancel_pay_from_customer 721 721 0 0.00 0 741 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 395 395 0 0.00 0 359 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 424 424 0 0.00 0 359 9 0 0
t$perf-norm:sp_cancel_supplier_order 456 456 0 0.00 0 29427 1740 0 0
t$perf-norm:sp_cancel_write_off 1036 1036 0 0.00 0 2672 12 0 0
t$perf-norm:sp_client_order 20900 20900 0 0.00 0 15956 19 0 0
t$perf-norm:sp_customer_reserve 964 964 0 0.00 0 5552 19 0 0
t$perf-norm:sp_pay_from_customer 7121 7121 0 0.00 0 5619 8 0 0
t$perf-norm:sp_pay_to_supplier 5957 5957 0 0.00 0 4885 10 0 0
t$perf-norm:sp_reserve_write_off 10088 10088 0 0.00 0 6218 12 0 0
t$perf-norm:sp_supplier_invoice 5458 5458 0 0.00 0 7895 23 0 0
t$perf-norm:sp_supplier_order 5510 5510 0 0.00 0 20477 22 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 179 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 48 2 0 0
t$perf-norm:srv_make_invnt_saldo 564 564 0 0.00 0 1053 19 0 0
t$perf-norm:srv_make_money_saldo 759 759 0 0.00 0 435 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 8 4 0 0
v_add_invoice_to_stock 10452 10164 288 2.75 0 4549 20 0 288
v_all_customers 37780 37780 0 0.00 0 27 1 0 0
v_all_suppliers 30622 30622 0 0.00 0 19 1 0 0
v_all_wares 41798 41798 0 0.00 0 32 1 0 0
v_cancel_adding_invoice 808 808 0 0.00 0 293 6 0 0
v_cancel_client_order 3694 3694 0 0.00 0 11605 13 0 0
v_cancel_customer_prepayment 1442 1442 0 0.00 0 490 4 0 0
v_cancel_customer_reserve 1360 1360 0 0.00 0 978 6 0 0
v_cancel_payment_to_supplier 790 790 0 0.00 0 8307 31 0 0
v_cancel_supplier_invoice 862 848 14 1.62 0 3002 26 0 14
v_cancel_supplier_order 912 912 0 0.00 0 610 4 0 0
v_cancel_write_off 2072 2072 0 0.00 0 2083 14 0 0
v_max_id_clo_ord 5510 5510 0 0.00 0 1027 2 0 0
v_max_id_clo_res 964 964 0 0.00 0 1567 13 0 0
v_max_id_ord_sup 5456 5456 0 0.00 0 3611 4 0 0
v_max_non_paid_invoice 1611 1611 0 0.00 0 389 3 0 0
v_max_non_paid_realizn 3867 3867 0 0.00 0 1329 4 0 0
v_min_id_clo_ord 5510 5510 0 0.00 0 4422 10 0 0
v_min_id_clo_res 964 964 0 0.00 0 1893 4 0 0
v_min_id_ord_sup 5456 5456 0 0.00 0 4882 7 0 0
v_min_non_paid_invoice 5956 5956 0 0.00 0 6869 19 0 0
v_min_non_paid_realizn 7117 7117 0 0.00 0 9464 20 0 0
v_our_firm 10518 10518 0 0.00 0 12 1 0 0
v_reserve_write_off 20174 20174 0 0.00 0 9545 9 0 0
x_lock_depdocs_on_canc_invoice 12452 12452 0 0.00 0 19744 135 0 0
x_lock_depdocs_on_canc_sup_ord 456 456 0 0.00 0 10439 327 0 0
x_make_qstorno_1000_1200 5510 5510 0 0.00 2 38689 1247 0 0
x_make_qstorno_1200_2000 5458 5458 0 0.00 2 88693 3388 0 0
x_make_qstorno_2100_3300 471517 471517 0 0.00 0 38414 166 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-09-2021 19:39:53 to 19-09-2021 19:39:57.

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
17:07:54 1 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
17:08:56 22562 8192000000 10367691232 10688274432 1421339248 14.785 100 86 171 1 1454722624 5926160 27457216
17:10:22 18132 8192000000 10422609888 10762162176 1487400720 15.366 100 86 172 1 1523868176 8400432 28067024
17:11:46 23534 8192000000 10482914704 10824359936 1538969376 15.815 100 84 168 0 1575371328 9984192 26417760
17:13:12 18978 8192000000 10576195536 10932744192 1620104128 16.511 100 84 168 0 1656663520 11385952 25173440
17:14:39 8723 8192000000 10638827872 11006291968 1695993568 17.152 100 82 164 1 1733624944 13543296 24088080
17:15:51 5243 8192000000 10705750496 11081863168 1768733136 17.757 100 80 158 2 1802892224 12015680 22143408
17:16:58 3007 8192000000 10748801904 11132125184 1818006032 18.161 100 91 181 0 1857181456 13802720 25372704
17:18:04 1595 8192000000 10800030736 11184402432 1856604736 18.476 100 90 181 2 1894887952 13290416 24992800
17:19:07 1718 8192000000 10827468336 11220246528 1885010320 18.706 100 93 187 3 1923452224 12314192 26127712
17:20:09 1422 8192000000 10825881328 11221757952 1887761872 18.728 100 84 170 4 1920028064 7482736 24783456
17:21:11 5956 8192000000 10793313024 11219910656 1886023136 18.714 100 37 74 1 1903647584 6306336 11318112
17:22:17 9624 8192000000 10810598144 11238125568 1891831088 18.761 100 16 32 1 1901488192 4800352 4856752
17:23:29 17476 8192000000 10804465856 11243671552 1899839344 18.825 100 22 45 0 1911351712 5977328 5535040
17:24:50 57044 8192000000 10834804704 11248230400 1891723216 18.760 99 47 94 1 1911161808 5012176 14426416
17:26:48 6177 8192000000 10820209680 11256377344 1909679264 18.904 100 32 64 2 1924891216 5962800 9249152
17:27:54 10086 8192000000 10818923312 11252301824 1886138800 18.715 99 20 40 1 1898868432 6192496 6537136
17:29:08 27468 8192000000 10763070272 11185442816 1815188192 18.138 97 58 117 3 1839433008 7458448 16786368
17:30:40 6338 8192000000 10693051024 11102523392 1742632448 17.540 97 23 47 0 1755122960 5937552 6552960
17:31:48 11864 8192000000 10723058032 11137626112 1775844640 17.815 98 18 36 1 1790689520 9533424 5311456
17:33:00 9503 8192000000 10765422640 11166715904 1829892928 18.258 99 29 58 1 1844418896 5517792 9008176
17:34:10 11493 8192000000 10744009728 11179278336 1822013600 18.194 99 13 26 2 1830729344 5415392 3300352
17:35:22 6900 8192000000 10774533344 11194916864 1855144416 18.464 100 22 45 1 1867612864 6546832 5921616
17:36:31 7709 8192000000 10780666976 11210625024 1864834720 18.542 100 21 42 0 1875862944 5120736 5907488
17:37:43 28458 8192000000 10838976416 11256819712 1907531920 18.887 100 37 73 0 1923062496 5495232 10035344
17:39:20 83725 8192000000 10860165152 11297525760 1930130544 19.068 100 32 64 0 1948057360 8341824 9584992
17:41:54 17981 8192000000 10898449584 11326074880 1954895424 19.265 100 38 74 1 1978059856 12282960 10881472
17:43:19 13688 8192000000 10906299056 11340341248 1969236560 19.379 100 47 94 1 1998164032 14793008 14134464
17:44:37 4886 8192000000 10940020416 11368513536 1989295600 19.538 100 69 137 1 2029491616 20287616 19908400
17:45:45 29730 8192000000 10937458768 11371552768 1985105088 19.505 100 51 102 1 2024931872 24267664 15559120
17:47:39 2102 8192000000 10945930736 11382611968 1998747408 19.613 100 56 106 1 2037716384 22847568 16121408
17:48:47 7837 8192000000 10940295136 11378569216 1981239792 19.475 99 41 82 0 2017836992 23931248 12665952
17:50:06 12212 8192000000 10977238624 11412697088 2021376416 19.791 100 54 107 3 2064126944 26116320 16634208
17:51:30 12905 8192000000 11002518208 11437375488 2036578736 19.910 100 66 132 0 2088019936 30879136 20562064
17:52:46 4091 8192000000 10991758000 11423801344 2007410864 19.681 99 63 125 0 2063547936 36553600 19583472
17:53:53 4208 8192000000 10986275312 11408166912 2002331296 19.641 100 77 153 0 2066924544 40884208 23709040
17:55:04 4895 8192000000 11013645536 11433914368 2024031344 19.812 100 87 169 0 2100362512 49569600 26761568
17:56:14 2861 8192000000 11007411664 11424260096 2005672608 19.667 100 88 176 1 2086534128 53283744 27577776
17:57:22 5668 8192000000 10990506784 11420377088 2002760656 19.644 100 71 141 1 2078402416 51945120 23696640
17:58:34 7975 8192000000 10983536672 11407355904 1975027024 19.425 99 65 128 0 2048061792 52192768 20842000
17:59:49 10988 8192000000 10958167040 11404382208 1962681328 19.327 99 41 82 0 2034370656 57184528 14504800
18:01:10 14566 8192000000 10905770784 11346538496 1898399632 18.813 98 26 52 1 1955173744 47568256 9205856
18:02:36 22437 8192000000 10897613760 11357417472 1927417232 19.046 100 28 56 0 1991716688 53471552 10827904
18:04:00 18715 8192000000 10905078576 11353272320 1950933392 19.234 100 25 50 0 1984458688 25835456 7689840
18:05:20 64342 8192000000 10864663184 11342262272 1911101552 18.915 98 11 22 0 1951645152 36545392 3998208
18:07:27 50462 8192000000 10857902016 11288797184 1893103072 18.771 97 38 73 3 1910097456 6386896 10607488
18:09:18 37918 8192000000 10882919600 11319488512 1890649088 18.751 97 40 80 3 1907741744 6948352 10144304
18:11:00 57044 8192000000 10876116816 11330789376 1893290864 18.772 97 9 16 1 1905837472 10417760 2128848
18:12:59 81873 8192000000 10928078000 11358965760 1915710160 18.952 98 16 32 0 1930779552 10403584 4665808
18:15:26 76865 8192000000 10915494704 11378728960 1975609264 19.430 100 10 20 1 1994557152 15557184 3390704
18:17:43 57829 8192000000 10922275648 11362983936 1946886496 19.202 98 34 68 5 1964736608 6837664 11012448
18:19:42 55114 8192000000 10894766240 11361075200 1969903472 19.385 100 26 52 1 1986660992 8948640 7808880
18:21:39 17085 8192000000 10924619328 11370180608 1979977616 19.465 100 48 96 1 2005386544 11549632 13859296
18:23:01 26741 8192000000 10928274336 11387777024 1993835232 19.574 100 54 107 0 2018505072 9841088 14828752
18:24:30 29631 8192000000 10925022656 11385794560 1980045296 19.465 99 43 84 0 2004066784 11806528 12214960
18:26:00 36833 8192000000 10915363680 11393978368 1996100272 19.592 100 11 22 1 2008083280 8701296 3281712
18:27:42 56965 8192000000 10926032224 11413372928 2003133584 19.647 100 21 42 0 2026361136 16257920 6969632
18:29:39 69853 8192000000 10907018976 11390717952 1953335552 19.253 98 17 35 0 1972325520 13763984 5225984
18:32:08 45652 8192000000 10908335120 11390959616 1991560096 19.556 100 15 30 1 2004525328 8634080 4331152
18:33:54 61718 8192000000 10915002304 11373166592 1960674800 19.311 99 15 30 0 1975027504 10430960 3921744
18:35:56 46795 8192000000 10886914352 11387179008 1951831632 19.241 99 15 30 0 1977987584 21061456 5094496
18:37:53 54418 8192000000 10846395632 11312656384 1815290720 18.139 92 16 32 0 1832924016 13277248 4356048
18:39:57 38914 8192000000 10892233360 11359453184 1935768992 19.113 99 38 76 0 1961092880 13401152 11922736
18:42:03 69372 8192000000 10886933824 11361935360 1901495168 18.838 97 15 30 0 1919887264 13757152 4634944
18:44:12 78254 8192000000 10873603648 11342336000 1828896992 18.250 93 21 43 1 1843260480 8392624 5970864
18:46:31 25411 8192000000 10860521808 11304275968 1886702592 18.719 97 39 73 3 1905468640 8986752 9779296
18:47:57 103802 8192000000 10871667232 11341185024 1919869008 18.986 99 30 60 1 1951738336 23030672 8838656
18:50:46 52525 8192000000 10878311904 11344953344 1919422064 18.982 98 38 76 0 1949628160 19266768 10939328
18:52:55 75896 8192000000 10865014512 11340423168 1907639680 18.888 98 15 29 2 1932296464 20203072 4453712
18:55:11 56381 8192000000 10889864208 11356782592 1953710448 19.256 100 26 51 0 1978601216 17733824 7156944
18:57:07 53308 8192000000 10854583072 11330416640 1906540032 18.879 98 16 32 1 1921340176 10735792 4064352
18:59:01 38691 8192000000 10830464976 11291033600 1825911152 18.226 95 19 36 3 1838318416 6768400 5638864
19:00:41 73858 8192000000 10868263248 11341205504 1907331184 18.885 99 19 38 1 1925224288 12434864 5458240
19:03:02 59963 8192000000 10885464576 11366350848 1941942560 19.162 99 18 35 0 1960247168 12691520 5613088
19:05:13 65993 8192000000 10908320384 11386380288 1951470048 19.238 99 10 20 1 1972828240 18611520 2746672
19:07:46 72072 8192000000 10914688224 11383689216 1966673520 19.359 99 18 36 0 1984432720 13031344 4727856
19:10:54 51540 8192000000 10926059152 11394490368 1967737120 19.367 99 16 32 1 1979580624 8079712 3763792
19:12:51 59550 8192000000 10931037232 11401269248 1993392496 19.571 100 38 76 0 2017362496 13387936 10582064
19:14:59 37611 8192000000 10915798752 11387138048 1974037168 19.417 99 43 79 0 1997346560 11742816 11566576
19:16:37 75213 8192000000 10919909744 11410149376 1958863792 19.297 98 13 26 0 1976070464 13336960 3869712
19:18:52 41228 8192000000 10932328272 11407376384 1970197744 19.387 98 15 30 0 1997326416 22209312 4919360
19:20:34 67971 8192000000 10927262592 11417186304 1974557104 19.422 99 22 44 0 2014384080 32457472 7369504
19:22:46 50773 8192000000 10919266656 11402092544 1935669072 19.112 97 19 38 1 1958650496 17278016 5703408
19:24:56 61026 8192000000 10896387808 11377471488 1931164976 19.076 97 8 16 0 1941970000 8543232 2261792
19:26:57 23421 8192000000 10938855056 11392569344 1977506032 19.445 99 38 76 2 1998444784 10218208 10720544
19:28:23 58864 8192000000 10905705264 11385090048 1934921120 19.106 97 29 58 0 1958438960 15428176 8089664
19:30:31 40356 8192000000 10905744016 11360780288 1906731168 18.880 97 34 68 0 1926212592 10483568 8997856
19:32:12 125240 8192000000 10884163360 11370049536 1859284016 18.497 95 16 30 1 1880743296 16844800 4614480
19:35:18 144807 8192000000 10885727824 11367038976 1938478640 19.135 99 25 50 0 1965062848 19180000 7404208

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 19-09-2021 19:39:58 to 19-09-2021 19:40:13.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 144 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 19-09-2021 19:40:14 to 19-09-2021 19:40:16.

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          239594
MON$OLDEST_ACTIVE               240454
MON$OLDEST_SNAPSHOT             240454
MON$NEXT_TRANSACTION            240454
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                       2062304
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.33498 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33498 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33498 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33498 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 ---
:159: 
:160: mscs-test-161.budzdorov.ru    Sun Sep 19 19:37:53 2021
:161:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:162: 
:163: 
:164: mscs-test-161.budzdorov.ru    Sun Sep 19 19:37:59 2021
:165:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:166: 
:167: 
:168: mscs-test-161.budzdorov.ru    Sun Sep 19 19:38:32 2021
:169:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:170: 
--- end of diff output ---

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

19.09.21 19:40:17. 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-09-19 19:40:20 No severe PSQL-related problems occured
19.09.21 19:40:21. End of report.