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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-08-08 07:48:45
DTS_END                         2021-08-08 07:48:45.4990
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_AGGREGATE_PERF_DATA(20:9) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/33530
TRN_ID                          232027
ATT_ID                          606
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: 8604.13 23676 1032495
customer order: creation 167.70 399 20124
customer order: refuse 14.55 370 1746
order to supplier: creation 45.17 1396 5420
order to supplier: removal 3.33 101501 399
invoice (draft): creation 45.59 3741 5471
invoice (draft): removal 46.98 936 5637
invoice accept: apply 41.46 19926 4975
invoice accept: cancel 42.06 932 5047
customer reserve: creation 3809.39 197 457127
customer reserve: removal 4107.15 64 492858
realization accept: apply 81.41 192 9769
realization accept: cancel 71.37 115 8564
payment from customer: creation 59.48 133 7138
payment from customer: removal 5.53 116 664
payment to supplier: creation 47.91 161 5749
payment to supplier: removal 3.72 102 446
service: total inventory turnovers 4.06 2996 487
service: total monetary turnovers 6.27 414 752
service: aggregate perf. data 0.98 13993 118
service: refresh index statistics 0.03 325844 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-08-2021 07:50:49 to 08-08-2021 07:50:51.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5967
TEST_TIME 2 6841
TEST_TIME 3 6617
TEST_TIME 4 5521
TEST_TIME 5 6222
TEST_TIME 6 5994
TEST_TIME 7 5606
TEST_TIME 8 5493
TEST_TIME 9 6063
TEST_TIME 10 6283
TEST_TIME 11 6417
TEST_TIME 12 6413
TEST_TIME 13 6577
TEST_TIME 14 6647
TEST_TIME 15 6692
TEST_TIME 16 6812
TEST_TIME 17 7073
TEST_TIME 18 7464
TEST_TIME 19 7640
TEST_TIME 20 8153
TEST_TIME 21 8636
TEST_TIME 22 8708
TEST_TIME 23 9184
TEST_TIME 24 9405
TEST_TIME 25 10139
TEST_TIME 26 10711
TEST_TIME 27 10357
TEST_TIME 28 10119
TEST_TIME 29 9998
TEST_TIME 30 9863
TEST_TIME 31 9749
TEST_TIME 32 9854
TEST_TIME 33 9741
TEST_TIME 34 9698
TEST_TIME 35 9493
TEST_TIME 36 9302
TEST_TIME 37 9183
TEST_TIME 38 9268
TEST_TIME 39 9171
TEST_TIME 40 9089
TEST_TIME 41 8993
TEST_TIME 42 8911
TEST_TIME 43 8907
TEST_TIME 44 8875
TEST_TIME 45 9054
TEST_TIME 46 9079
TEST_TIME 47 9104
TEST_TIME 48 9109
TEST_TIME 49 9055
TEST_TIME 50 9099
TEST_TIME 51 9084
TEST_TIME 52 9220
TEST_TIME 53 9155
TEST_TIME 54 9201
TEST_TIME 55 9216
TEST_TIME 56 9154
TEST_TIME 57 9058
TEST_TIME 58 8996
TEST_TIME 59 8990
TEST_TIME 60 8877
TEST_TIME 61 8808
TEST_TIME 62 8842
TEST_TIME 63 8922
TEST_TIME 64 8918
TEST_TIME 65 8880
TEST_TIME 66 8978
TEST_TIME 67 8987
TEST_TIME 68 8985
TEST_TIME 69 9007
TEST_TIME 70 9003
TEST_TIME 71 9069
TEST_TIME 72 9101
TEST_TIME 73 9071
TEST_TIME 74 9151
TEST_TIME 75 9126
TEST_TIME 76 9023
TEST_TIME 77 8948
TEST_TIME 78 8915
TEST_TIME 79 8832
TEST_TIME 80 8841
TEST_TIME 81 8806
TEST_TIME 82 8874
TEST_TIME 83 8844
TEST_TIME 84 8806
TEST_TIME 85 8803
TEST_TIME 86 8938
TEST_TIME 87 8927
TEST_TIME 88 8890
TEST_TIME 89 8888
TEST_TIME 90 8903
TEST_TIME 91 8929
TEST_TIME 92 8917
TEST_TIME 93 8832
TEST_TIME 94 8808
TEST_TIME 95 8805
TEST_TIME 96 8763
TEST_TIME 97 8769
TEST_TIME 98 8720
TEST_TIME 99 8699
TEST_TIME 100 8714
TEST_TIME 101 8730
TEST_TIME 102 8811
TEST_TIME 103 8771
TEST_TIME 104 8774
TEST_TIME 105 8830
TEST_TIME 106 8811
TEST_TIME 107 8836
TEST_TIME 108 8874
TEST_TIME 109 8931
TEST_TIME 110 8899
TEST_TIME 111 8911
TEST_TIME 112 8868
TEST_TIME 113 8798
TEST_TIME 114 8792
TEST_TIME 115 8789
TEST_TIME 116 8745
TEST_TIME 117 8707
TEST_TIME 118 8663
TEST_TIME 119 8642
TEST_TIME 120 8606

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 08-08-2021 07:50:53 to 08-08-2021 07:50:56.

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 1453521 1453521 0 0.00 0 21848 7 0 0
doc_list_biud 1453519 1453519 0 0.00 0 81382 28 0 0
sp_add_invoice_to_stock 5080 4975 105 2.06 21 249315 19927 0 105
sp_cancel_adding_invoice 5047 5047 0 0.00 5 57113 933 0 0
sp_cancel_client_order 1746 1746 0 0.00 4 12000 371 0 0
sp_cancel_customer_reserve 492858 492858 0 0.00 1 23496 65 0 0
sp_cancel_pay_from_customer 664 664 0 0.00 3 9038 117 0 0
sp_cancel_pay_to_supplier 446 446 0 0.00 3 6409 102 0 0
sp_cancel_supplier_invoice 5645 5637 8 0.14 2 81384 937 0 8
sp_cancel_supplier_order 399 399 0 0.00 12 1162909 101501 0 0
sp_cancel_write_off 8564 8564 0 0.00 1 17932 115 0 0
sp_client_order 20124 20124 0 0.00 4 48016 400 0 0
sp_customer_reserve 457127 457127 0 0.00 0 122004 198 0 0
sp_fill_shopping_cart 31006 31006 0 0.00 1 21400 51 0 0
sp_fill_shopping_cart_clo_res 457122 457122 0 0.00 0 40212 16 0 0
sp_get_clo_for_invoice 4970 4970 0 0.00 0 31004 407 0 0
sp_kill_cost_storno 14721 14721 0 0.00 0 9856 60 0 0
sp_kill_qstorno_ret_qs2qd 1000316 1000316 0 0.00 0 81381 35 0 0
sp_kill_qty_storno 528989 528989 0 0.00 0 81382 77 0 0
sp_lock_selected_doc 20466 20466 0 0.00 0 11804 5 0 0
sp_make_cost_storno 27626 27626 0 0.00 0 19769 65 0 0
sp_multiply_rows_for_pdistr 27626 27626 0 0.00 0 8896 35 0 0
sp_multiply_rows_for_qdistr 20124 20124 0 0.00 0 31479 213 0 0
sp_pay_from_customer 7138 7138 0 0.00 4 14625 133 0 0
sp_pay_to_supplier 5749 5749 0 0.00 5 17266 162 0 0
sp_payment_common 12886 12886 0 0.00 1 13332 29 0 0
sp_qd_handle_on_cancel_clo 1746 1746 0 0.00 0 9912 337 0 0
sp_qd_handle_on_invoice_upd_sts 10016 10016 0 0.00 0 49272 366 0 0
sp_qd_handle_on_reserve_upd_sts 9769 9769 0 0.00 0 14977 51 0 0
sp_reserve_write_off 9769 9769 0 0.00 7 19855 193 0 0
sp_supplier_invoice 5471 5471 0 0.00 6 85394 3741 0 0
sp_supplier_order 5420 5420 0 0.00 7 33626 1397 0 0
srv_aggregate_perf_data 118 118 0 0.00 2817 242610 13994 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1666 149394 48836 0 0
srv_find_qd_qs_mism 984262 984262 0 0.00 0 21839 6 0 0
srv_make_invnt_saldo 487 487 0 0.00 47 113007 2997 0 0
srv_make_money_saldo 752 752 0 0.00 1 17652 415 0 0
srv_recalc_idx_stat 4 4 0 0.00 159660 510141 325844 0 0
t$perf-abend:sp_add_invoice_to_stock 105 105 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4975 4975 0 0.00 0 17064 183 0 0
t$perf-norm:sp_cancel_adding_invoice 439 439 0 0.00 0 7512 107 0 0
t$perf-norm:sp_cancel_client_order 1746 1746 0 0.00 0 3861 13 0 0
t$perf-norm:sp_cancel_customer_reserve 654 654 0 0.00 0 1101 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 664 664 0 0.00 0 1751 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 446 446 0 0.00 0 507 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 403 403 0 0.00 0 1322 14 0 0
t$perf-norm:sp_cancel_supplier_order 399 399 0 0.00 0 21294 1420 0 0
t$perf-norm:sp_cancel_write_off 977 977 0 0.00 0 2107 8 0 0
t$perf-norm:sp_client_order 20124 20124 0 0.00 0 12488 18 0 0
t$perf-norm:sp_customer_reserve 1040 1040 0 0.00 0 976 3 0 0
t$perf-norm:sp_pay_from_customer 7138 7138 0 0.00 0 9188 12 0 0
t$perf-norm:sp_pay_to_supplier 5749 5749 0 0.00 0 6867 11 0 0
t$perf-norm:sp_reserve_write_off 9769 9769 0 0.00 0 2748 8 0 0
t$perf-norm:sp_supplier_invoice 5471 5471 0 0.00 0 9173 19 0 0
t$perf-norm:sp_supplier_order 5420 5420 0 0.00 0 2893 12 0 0
t$perf-norm:srv_aggregate_perf_data 118 118 0 0.00 0 300 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 1270 22 0 0
t$perf-norm:srv_make_invnt_saldo 487 487 0 0.00 0 422 6 0 0
t$perf-norm:srv_make_money_saldo 752 752 0 0.00 0 1333 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 34 10 0 0
v_add_invoice_to_stock 10146 9936 210 2.06 0 7178 18 0 210
v_all_customers 36854 36854 0 0.00 0 23 1 0 0
v_all_suppliers 29720 29720 0 0.00 0 19 1 0 0
v_all_wares 40248 40248 0 0.00 0 27 1 0 0
v_cancel_adding_invoice 878 878 0 0.00 0 1322 8 0 0
v_cancel_client_order 3492 3492 0 0.00 0 7055 6 0 0
v_cancel_customer_prepayment 1328 1328 0 0.00 0 3874 12 0 0
v_cancel_customer_reserve 1308 1308 0 0.00 0 1376 6 0 0
v_cancel_payment_to_supplier 892 892 0 0.00 0 6401 13 0 0
v_cancel_supplier_invoice 822 806 16 1.94 0 531 11 0 16
v_cancel_supplier_order 798 798 0 0.00 0 1138 5 0 0
v_cancel_write_off 1954 1954 0 0.00 0 7765 20 0 0
v_max_id_clo_ord 5420 5420 0 0.00 0 385 1 0 0
v_max_id_clo_res 1040 1040 0 0.00 0 1609 13 0 0
v_max_id_ord_sup 5461 5461 0 0.00 0 7785 9 0 0
v_max_non_paid_invoice 1769 1769 0 0.00 0 1563 3 0 0
v_max_non_paid_realizn 3895 3895 0 0.00 0 5487 6 0 0
v_min_id_clo_ord 5420 5420 0 0.00 0 6800 8 0 0
v_min_id_clo_res 1040 1040 0 0.00 0 3866 9 0 0
v_min_id_ord_sup 5461 5461 0 0.00 0 3462 5 0 0
v_min_non_paid_invoice 5748 5748 0 0.00 0 8285 23 0 0
v_min_non_paid_realizn 7138 7138 0 0.00 0 13329 20 0 0
v_our_firm 9880 9880 0 0.00 0 16 1 0 0
v_reserve_write_off 19538 19538 0 0.00 0 6961 6 0 0
x_lock_depdocs_on_canc_invoice 10281 10281 0 0.00 0 22804 110 0 0
x_lock_depdocs_on_canc_sup_ord 399 399 0 0.00 0 16458 318 0 0
x_make_qstorno_1000_1200 5420 5420 0 0.00 2 33607 1319 0 0
x_make_qstorno_1200_2000 5471 5471 0 0.00 3 85383 3533 0 0
x_make_qstorno_2100_3300 457127 457127 0 0.00 0 122003 179 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-08-2021 07:50:57 to 08-08-2021 07:51:01.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
05:18:49 2 8192000000 8796668944 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:19:50 27840 8192000000 9725052496 10020646912 805211920 8.949 100 89 178 0 833788496 7374352 21202224
05:21:22 23949 8192000000 9772150112 10081452032 847665616 9.377 100 82 164 0 877941696 11209152 19066928
05:22:48 18644 8192000000 9811238672 10128146432 881042272 9.710 100 87 174 2 918885200 18200864 19642064
05:24:09 12278 8192000000 9869044976 10200256512 918214016 10.078 100 75 151 0 969406544 34334192 16858336
05:25:29 13026 8192000000 9916771824 10263961600 963254592 10.521 100 76 150 3 1025920352 46576560 16089200
05:26:45 6822 8192000000 9966829728 10316775424 999094432 10.870 100 86 172 1 1075508928 58766096 17648400
05:27:54 3574 8192000000 9984158656 10346627072 1021236256 11.084 100 81 161 0 1099073616 62129792 15707568
05:29:00 2774 8192000000 10006756992 10374844416 1042101136 11.285 100 85 168 1 1122407408 63273952 17032320
05:30:05 1678 8192000000 10040419696 10408943616 1061778576 11.474 100 96 192 0 1155581248 73847456 19955216
05:31:07 25592 8192000000 9988136048 10369916928 1071798832 11.569 100 79 158 2 1107165520 19889920 15476768
05:32:36 5232 8192000000 9993041840 10385506304 1078568560 11.634 100 43 86 0 1106076864 18666880 8841424
05:33:44 6737 8192000000 9999775232 10400399360 1087502576 11.719 100 50 98 1 1128429040 31236976 9689488
05:34:54 7790 8192000000 10008241120 10417057792 1091052960 11.753 100 34 69 0 1126138224 27726240 7359024
05:36:03 37834 8192000000 10023876624 10417963008 1094418640 11.785 100 47 94 0 1126252672 22246432 9587600
05:37:41 7806 8192000000 10012974624 10426458112 1095518960 11.795 100 35 70 1 1137697216 35279408 6898848
05:38:50 12007 8192000000 9995264032 10403942400 1048567024 11.347 96 33 66 0 1081002448 24660800 7774624
05:40:05 9726 8192000000 9983038752 10392711168 1042696864 11.291 97 26 53 1 1068937456 20272272 5968320
05:41:17 46547 8192000000 9937605808 10335367168 953865200 10.429 91 23 46 0 972726352 13792992 5068160
05:43:07 7924 8192000000 9928169840 10307788800 980121296 10.685 96 36 71 1 1002888288 15338624 7428368
05:44:15 10017 8192000000 9934807440 10333818880 1022958656 11.101 99 42 85 2 1052602880 20297248 9346976
05:45:25 4273 8192000000 9956569984 10343522304 1048838976 11.350 100 46 91 2 1073013392 13439328 10735088
05:46:30 43547 8192000000 9959228640 10365751296 1052564240 11.385 100 33 67 0 1093508240 32964992 7979008
05:48:14 8613 8192000000 9975646768 10382909440 1055332336 11.412 100 35 70 1 1096940192 33200752 8407104
05:49:32 7232 8192000000 10025233136 10416222208 1072367184 11.575 100 65 124 4 1132371520 46661824 13342512
05:50:50 11984 8192000000 10030206208 10432622592 1088749184 11.731 100 45 89 0 1130283696 31701776 9832736
05:52:09 53684 8192000000 10063332048 10470182912 1105187552 11.887 100 42 83 0 1160582960 46821952 8573456
05:55:03 14469 8192000000 10114140384 10525351936 1127277136 12.096 100 49 96 5 1223804144 85486240 11040768
05:56:22 19980 8192000000 10189819984 10599821312 1138177520 12.198 100 51 99 1 1291986304 143191584 10617200
05:57:45 17160 8192000000 10189680064 10607734784 1148304192 12.294 100 46 90 2 1301815664 143210576 10300896
05:59:06 4957 8192000000 10250682480 10660220928 1169549152 12.493 100 62 120 0 1355603856 173662816 12391888
06:00:15 6674 8192000000 10257046832 10673065984 1179646496 12.587 100 61 118 2 1356658576 164246400 12765680
06:01:29 1663 8192000000 10308190768 10712551424 1182855424 12.617 100 78 157 1 1408689088 208139440 17694224
06:02:35 7393 8192000000 10346395600 10755264512 1172206496 12.517 100 72 145 2 1443888720 255535792 16146432
06:03:48 3714 8192000000 10367956464 10774929408 1170623312 12.503 100 83 160 0 1464510704 275817632 18069760
06:04:56 2618 8192000000 10407254304 10806145024 1149421280 12.304 99 84 167 1 1494424096 325922752 19080064
06:06:03 3994 8192000000 10408041168 10831466496 1158667968 12.391 100 78 155 0 1508330496 331415536 18246992
06:07:11 14466 8192000000 10436440720 10870894592 1171416544 12.510 100 40 80 1 1535247952 353623088 10208320
06:08:37 13874 8192000000 10406819552 10839142400 1149785760 12.307 99 37 74 1 1488439664 329281728 9372176
06:09:55 14031 8192000000 10318500016 10762244096 1110770432 11.940 97 31 62 1 1397854640 279116160 7968048
06:11:12 17615 8192000000 10321488576 10781184000 1132108208 12.141 99 30 60 1 1418991024 278606656 8276160
06:12:50 26185 8192000000 10250914960 10746740736 1087115152 11.715 97 22 44 1 1358065456 264869984 6080320
06:14:21 33833 8192000000 10034216704 10452692992 1080320272 11.651 96 21 41 0 1118138480 33759632 4058576
06:16:08 15152 8192000000 10086219616 10511323136 1127529856 12.098 100 52 104 0 1184797136 46323856 10943424
06:17:26 18526 8192000000 10134005744 10569396224 1134743312 12.166 100 38 76 0 1241999424 99458928 7797184
06:18:45 149393 8192000000 10081049072 10545369088 1128232144 12.105 99 13 26 0 1178853568 48233232 2388192
06:22:16 82605 8192000000 10085103040 10545287168 1111737696 11.949 98 19 38 0 1153647008 37188992 4720320
06:24:50 43267 8192000000 10101197824 10540199936 1133861424 12.158 100 32 65 1 1205090736 64085792 7143520
06:26:56 65465 8192000000 10076212336 10540789760 1128822592 12.110 99 17 32 0 1170146304 37963152 3360560
06:29:33 72008 8192000000 10052662512 10530848768 1142885264 12.243 100 9 18 0 1181132960 36575952 1671744
06:31:48 90354 8192000000 10113398528 10582859776 1140158128 12.217 99 12 24 0 1218533440 75752224 2623088
06:34:19 44039 8192000000 10065264800 10495709184 1129535056 12.117 98 17 34 0 1151310480 18326112 3449312
06:36:03 48956 8192000000 10071031152 10520875008 1131433280 12.135 99 23 46 1 1182874208 46919696 4521232
06:37:58 78946 8192000000 10111338288 10579820544 1133486800 12.154 100 26 52 0 1207045104 68296000 5262304
06:40:52 48613 8192000000 10109688448 10563256320 1133861728 12.158 99 21 42 0 1184637328 46611552 4164048
06:42:42 68931 8192000000 10095553200 10560536576 1080287920 11.650 94 22 44 0 1112163392 27435104 4440368
06:44:55 96060 8192000000 10074514000 10511896576 1132697648 12.147 99 27 54 1 1174275456 35873520 5704288
06:47:54 68602 8192000000 10116630096 10584416256 1136242800 12.180 99 10 20 0 1209130736 70624800 2263136
06:51:24 54007 8192000000 10063204352 10520567808 1103324048 11.869 97 12 24 0 1143784128 37930944 2529136
06:53:20 42274 8192000000 10100391696 10531999744 1113851920 11.969 97 40 78 2 1176884192 54224976 8807296
06:55:13 78349 8192000000 10064915472 10529558528 1122051136 12.046 98 12 24 1 1165451264 40883776 2516352
06:57:32 88424 8192000000 10112840944 10568744960 1099079472 11.829 96 20 39 2 1161994704 59030512 3884720
07:00:04 68667 8192000000 10094809536 10569969664 1080069248 11.648 95 14 28 0 1126661504 43943216 2649040
07:02:19 90704 8192000000 10033627728 10478022656 1028265488 11.152 91 23 46 1 1064611760 31844384 4501888
07:04:54 79581 8192000000 10050997568 10501914624 1126555568 12.089 100 29 57 2 1167893808 34670576 6667664
07:07:16 71806 8192000000 10118463520 10573533184 1126253872 12.086 99 12 24 0 1204613600 75659056 2700672
07:09:32 62911 8192000000 10081795872 10558918656 1085542912 11.700 96 15 30 0 1165243568 75849616 3851040
07:11:36 45342 8192000000 10124831424 10560741376 1113847968 11.969 98 26 52 1 1201440496 81679680 5912848
07:13:27 54961 8192000000 10136837344 10611802112 1134498272 12.164 100 18 36 0 1256406608 117684160 4224176
07:15:37 55071 8192000000 10090660288 10522673152 1138302800 12.200 100 24 48 1 1188393344 44747664 5342880
07:17:48 22015 8192000000 10100349680 10535354368 1132367136 12.144 99 47 94 0 1184576432 43110992 9098304
07:19:11 35925 8192000000 10111098640 10566311936 1112748816 11.958 98 38 76 1 1199621600 79303040 7569744
07:20:47 133403 8192000000 10096800976 10550726656 1111741232 11.949 98 29 58 1 1190696080 73095792 5859056
07:24:02 38564 8192000000 10061340576 10564227072 1131701344 12.137 100 5 10 0 1168964848 36187520 1075984
07:25:41 65424 8192000000 10087584592 10528145408 1105866144 11.893 97 13 26 0 1152343008 43882208 2594656
07:27:59 81851 8192000000 10133635872 10588520448 1117234880 12.001 98 24 49 3 1174241296 52203488 4802928
07:30:22 133202 8192000000 10091079072 10561159168 1103618720 11.872 96 12 24 0 1173324736 67661376 2044640
07:33:35 33960 8192000000 10085501568 10558021632 1141670576 12.231 100 23 46 3 1192232192 46048944 4512672
07:35:09 48583 8192000000 10103404592 10552287232 1107034608 11.904 97 28 56 0 1165228480 52715184 5478688
07:36:59 47055 8192000000 10111742256 10591801344 1123158192 12.057 98 29 58 0 1200985648 71676832 6150624
07:38:47 21236 8192000000 10110896128 10541432832 1121444560 12.041 99 47 94 0 1167094784 36482160 9168064
07:40:14 112113 8192000000 10077739792 10521579520 1117627424 12.005 99 21 42 0 1181929072 59615920 4685728
07:43:41 47691 8192000000 10049668544 10507440128 1096547360 11.805 98 19 38 0 1139253344 38674672 4031312
07:45:29 68012 8192000000 10114226112 10572296192 1113695328 11.967 99 15 30 0 1184164080 66481008 3987744
07:48:33 31364 8192000000 9064495312 9374298112 202486048 2.412 19 4 6 1 252353952 49213152 654752

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 08-08-2021 07:51:03 to 08-08-2021 07:51:18.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 105 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 08-08-2021 07:51:19 to 08-08-2021 07:51:21.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          232026
MON$OLDEST_ACTIVE               232296
MON$OLDEST_SNAPSHOT             232296
MON$NEXT_TRANSACTION            232296
MON$PAGE_BUFFERS                1000000
MON$SQL_DIALECT                 3
MON$SHUTDOWN_MODE               0
MON$SWEEP_INTERVAL              0
MON$READ_ONLY                   0
MON$FORCED_WRITES               1
MON$RESERVE_SPACE               1
MON$CREATION_DATE               2020-04-30 07:30:08.6030 GMT
MON$PAGES                       2015744
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             798
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2564 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2564 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2564 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2564 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16:C"
on disk structure version 13.0

Database statistics

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

Database validation

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

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

--- start of diff output ---
:6: 
:7: mscs-test-161.budzdorov.ru    Sun Aug  8 07:49:09 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Sun Aug  8 07:49:10 2021
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Sun Aug  8 07:49:38 2021
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:17: 
--- end of diff output ---

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

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