Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.07.2022 07:48
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 2a7b53d1c5ed44b9bb2712fffac5d8c6
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-11-11 18:28
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 4794.33
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 15957700 kB
MemAvailable 31063188 kB
Buffers 0 kB
Cached 15757592 kB
SwapCached 2500 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.2
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 2022-07-21 07:47:30
DTS_END                         2022-07-21 07:47:30.3890
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/59662
TRN_ID                          215328
ATT_ID                          586
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: 8657.41 25210 1038889
customer order: creation 166.42 519 19970
customer order: refuse 13.58 447 1629
order to supplier: creation 43.10 1379 5172
order to supplier: removal 3.30 191063 396
invoice (draft): creation 44.00 3395 5280
invoice (draft): removal 47.21 1070 5665
invoice accept: apply 40.78 29037 4893
invoice accept: cancel 42.61 1449 5113
customer reserve: creation 3794.87 275 455384
customer reserve: removal 4189.73 119 502767
realization accept: apply 79.90 310 9588
realization accept: cancel 67.28 219 8073
payment from customer: creation 56.97 181 6836
payment from customer: removal 5.55 129 666
payment to supplier: creation 46.55 228 5586
payment to supplier: removal 3.29 137 395
service: total inventory turnovers 3.87 4423 464
service: total monetary turnovers 7.60 429 912
service: aggregate perf. data 0.79 20710 95
service: refresh index statistics 0.04 248676 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-07-2022 07:48:57 to 21-07-2022 07:48:59.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6763
TEST_TIME 2 7846
TEST_TIME 3 7035
TEST_TIME 4 6921
TEST_TIME 5 7948
TEST_TIME 6 7814
TEST_TIME 7 7529
TEST_TIME 8 7420
TEST_TIME 9 7123
TEST_TIME 10 6956
TEST_TIME 11 7008
TEST_TIME 12 6963
TEST_TIME 13 7012
TEST_TIME 14 7222
TEST_TIME 15 7207
TEST_TIME 16 7260
TEST_TIME 17 7311
TEST_TIME 18 7288
TEST_TIME 19 7278
TEST_TIME 20 7151
TEST_TIME 21 7110
TEST_TIME 22 6972
TEST_TIME 23 7034
TEST_TIME 24 7058
TEST_TIME 25 6993
TEST_TIME 26 7174
TEST_TIME 27 7352
TEST_TIME 28 7490
TEST_TIME 29 8107
TEST_TIME 30 8729
TEST_TIME 31 9029
TEST_TIME 32 9340
TEST_TIME 33 9396
TEST_TIME 34 9926
TEST_TIME 35 10315
TEST_TIME 36 10424
TEST_TIME 37 10372
TEST_TIME 38 10150
TEST_TIME 39 10055
TEST_TIME 40 9877
TEST_TIME 41 9900
TEST_TIME 42 9835
TEST_TIME 43 9825
TEST_TIME 44 9763
TEST_TIME 45 9638
TEST_TIME 46 9573
TEST_TIME 47 9494
TEST_TIME 48 9408
TEST_TIME 49 9352
TEST_TIME 50 9275
TEST_TIME 51 9112
TEST_TIME 52 9119
TEST_TIME 53 9239
TEST_TIME 54 9127
TEST_TIME 55 9226
TEST_TIME 56 9289
TEST_TIME 57 9220
TEST_TIME 58 9185
TEST_TIME 59 9168
TEST_TIME 60 9242
TEST_TIME 61 9212
TEST_TIME 62 9257
TEST_TIME 63 9243
TEST_TIME 64 9303
TEST_TIME 65 9250
TEST_TIME 66 9355
TEST_TIME 67 9296
TEST_TIME 68 9261
TEST_TIME 69 9211
TEST_TIME 70 9232
TEST_TIME 71 9175
TEST_TIME 72 9085
TEST_TIME 73 9050
TEST_TIME 74 8987
TEST_TIME 75 8933
TEST_TIME 76 8883
TEST_TIME 77 8859
TEST_TIME 78 8844
TEST_TIME 79 8826
TEST_TIME 80 8939
TEST_TIME 81 8912
TEST_TIME 82 8947
TEST_TIME 83 8908
TEST_TIME 84 8979
TEST_TIME 85 8992
TEST_TIME 86 9005
TEST_TIME 87 9049
TEST_TIME 88 9079
TEST_TIME 89 9053
TEST_TIME 90 9022
TEST_TIME 91 8963
TEST_TIME 92 8972
TEST_TIME 93 8932
TEST_TIME 94 8905
TEST_TIME 95 8856
TEST_TIME 96 8864
TEST_TIME 97 8855
TEST_TIME 98 8840
TEST_TIME 99 8832
TEST_TIME 100 8790
TEST_TIME 101 8779
TEST_TIME 102 8836
TEST_TIME 103 8905
TEST_TIME 104 8909
TEST_TIME 105 8910
TEST_TIME 106 8965
TEST_TIME 107 8954
TEST_TIME 108 8956
TEST_TIME 109 8886
TEST_TIME 110 8875
TEST_TIME 111 8856
TEST_TIME 112 8812
TEST_TIME 113 8770
TEST_TIME 114 8744
TEST_TIME 115 8785
TEST_TIME 116 8760
TEST_TIME 117 8743
TEST_TIME 118 8702
TEST_TIME 119 8669
TEST_TIME 120 8682

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 21-07-2022 07:49:00 to 21-07-2022 07:49:04.

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 1457516 1457516 0 0.00 0 22928 15 0 0
doc_list_biud 1457506 1457506 0 0.00 0 44455 47 0 0
sp_add_invoice_to_stock 5028 4893 135 2.68 34 245985 29038 0 135
sp_cancel_adding_invoice 5113 5113 0 0.00 3 97854 1449 0 0
sp_cancel_client_order 1629 1629 0 0.00 6 13054 448 0 0
sp_cancel_customer_reserve 502767 502767 0 0.00 1 29977 119 0 0
sp_cancel_pay_from_customer 666 666 0 0.00 3 3548 130 0 0
sp_cancel_pay_to_supplier 395 395 0 0.00 4 3108 138 0 0
sp_cancel_supplier_invoice 5677 5665 12 0.21 3 44598 1070 0 12
sp_cancel_supplier_order 396 396 0 0.00 9 1681235 191063 0 0
sp_cancel_write_off 8073 8073 0 0.00 2 29680 219 0 0
sp_client_order 19970 19970 0 0.00 6 20075 520 0 0
sp_customer_reserve 455384 455384 0 0.00 0 55113 276 0 0
sp_fill_shopping_cart 30412 30412 0 0.00 1 11854 88 0 0
sp_fill_shopping_cart_clo_res 455357 455357 0 0.00 0 21440 21 0 0
sp_get_clo_for_invoice 4858 4858 0 0.00 0 29095 326 0 0
sp_kill_cost_storno 14247 14247 0 0.00 0 11263 87 0 0
sp_kill_qstorno_ret_qs2qd 1019668 1019668 0 0.00 0 44439 52 0 0
sp_kill_qty_storno 538088 538088 0 0.00 0 44449 119 0 0
sp_lock_selected_doc 19907 19907 0 0.00 0 9725 6 0 0
sp_make_cost_storno 26867 26867 0 0.00 0 20694 83 0 0
sp_multiply_rows_for_pdistr 26866 26866 0 0.00 0 7717 40 0 0
sp_multiply_rows_for_qdistr 19969 19969 0 0.00 0 10485 202 0 0
sp_pay_from_customer 6836 6836 0 0.00 5 16103 181 0 0
sp_pay_to_supplier 5586 5586 0 0.00 5 17002 228 0 0
sp_payment_common 12418 12418 0 0.00 2 10709 47 0 0
sp_qd_handle_on_cancel_clo 1629 1629 0 0.00 0 13045 356 0 0
sp_qd_handle_on_invoice_upd_sts 9970 9970 0 0.00 0 19050 590 0 0
sp_qd_handle_on_reserve_upd_sts 9588 9588 0 0.00 0 14764 78 0 0
sp_reserve_write_off 9588 9588 0 0.00 8 22953 310 0 0
sp_supplier_invoice 5280 5280 0 0.00 8 109425 3396 0 0
sp_supplier_order 5172 5172 0 0.00 8 59718 1380 0 0
srv_aggregate_perf_data 95 95 0 0.00 2585 160310 20710 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 773 116273 34767 0 0
srv_find_qd_qs_mism 991068 991068 0 0.00 0 15187 12 0 0
srv_make_invnt_saldo 464 464 0 0.00 54 78776 4423 0 0
srv_make_money_saldo 912 912 0 0.00 1 21554 430 0 0
srv_recalc_idx_stat 5 5 0 0.00 50509 492656 248676 0 0
t$perf-abend:sp_add_invoice_to_stock 135 135 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4897 4897 0 0.00 0 30803 1085 0 0
t$perf-norm:sp_cancel_adding_invoice 394 394 0 0.00 1 7669 524 0 0
t$perf-norm:sp_cancel_client_order 1629 1629 0 0.00 0 2875 32 0 0
t$perf-norm:sp_cancel_customer_reserve 632 632 0 0.00 0 784 22 0 0
t$perf-norm:sp_cancel_pay_from_customer 666 666 0 0.00 0 495 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 395 395 0 0.00 0 862 19 0 0
t$perf-norm:sp_cancel_supplier_invoice 389 389 0 0.00 0 1262 48 0 0
t$perf-norm:sp_cancel_supplier_order 396 396 0 0.00 0 76149 7007 0 0
t$perf-norm:sp_cancel_write_off 963 963 0 0.00 0 586 20 0 0
t$perf-norm:sp_client_order 19970 19970 0 0.00 0 10443 42 0 0
t$perf-norm:sp_customer_reserve 1001 1001 0 0.00 0 1732 18 0 0
t$perf-norm:sp_pay_from_customer 6836 6836 0 0.00 0 8053 21 0 0
t$perf-norm:sp_pay_to_supplier 5586 5586 0 0.00 0 6681 23 0 0
t$perf-norm:sp_reserve_write_off 9588 9588 0 0.00 0 3080 24 0 0
t$perf-norm:sp_supplier_invoice 5280 5280 0 0.00 0 3853 33 0 0
t$perf-norm:sp_supplier_order 5172 5172 0 0.00 0 8896 38 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 575 26 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 446 12 0 0
t$perf-norm:srv_make_invnt_saldo 464 464 0 0.00 0 843 15 0 0
t$perf-norm:srv_make_money_saldo 912 912 0 0.00 0 536 7 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 178 108 0 0
v_add_invoice_to_stock 9982 9712 270 2.70 0 13586 29 0 270
v_all_customers 36190 36190 0 0.00 0 103 4 0 0
v_all_suppliers 29060 29060 0 0.00 0 111 5 0 0
v_all_wares 39936 39936 0 0.00 0 106 4 0 0
v_cancel_adding_invoice 788 788 0 0.00 0 978 17 0 0
v_cancel_client_order 3258 3258 0 0.00 0 6715 20 0 0
v_cancel_customer_prepayment 1332 1332 0 0.00 0 3425 18 0 0
v_cancel_customer_reserve 1264 1264 0 0.00 1 5540 25 0 0
v_cancel_payment_to_supplier 790 790 0 0.00 0 680 14 0 0
v_cancel_supplier_invoice 802 778 24 2.99 0 1455 23 0 24
v_cancel_supplier_order 792 792 0 0.00 0 1793 17 0 0
v_cancel_write_off 1926 1926 0 0.00 0 3757 31 0 0
v_max_id_clo_ord 5169 5169 0 0.00 0 4826 6 0 0
v_max_id_clo_res 1001 1001 0 0.00 0 3144 20 0 0
v_max_id_ord_sup 5272 5272 0 0.00 0 2687 7 0 0
v_max_non_paid_invoice 1495 1495 0 0.00 0 278 8 0 0
v_max_non_paid_realizn 3664 3664 0 0.00 0 1750 9 0 0
v_min_id_clo_ord 5169 5169 0 0.00 0 4856 10 0 0
v_min_id_clo_res 1001 1001 0 0.00 0 4084 18 0 0
v_min_id_ord_sup 5271 5271 0 0.00 0 3182 8 0 0
v_min_non_paid_invoice 5585 5585 0 0.00 0 9527 20 0 0
v_min_non_paid_realizn 6833 6833 0 0.00 0 6804 15 0 0
v_our_firm 10084 10084 0 0.00 0 104 5 0 0
v_reserve_write_off 19171 19171 0 0.00 0 12352 15 0 0
x_lock_depdocs_on_canc_invoice 10389 10389 0 0.00 0 33240 161 0 0
x_lock_depdocs_on_canc_sup_ord 396 396 0 0.00 0 3550 373 0 0
x_make_qstorno_1000_1200 5172 5172 0 0.00 3 59679 1209 0 0
x_make_qstorno_1200_2000 5280 5280 0 0.00 3 109369 3144 0 0
x_make_qstorno_2100_3300 455383 455383 0 0.00 0 55107 240 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-07-2022 07:49:05 to 21-07-2022 07:49:09.

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:17:34 2 8192000000 8802226448 8814845952 6674160 0.081 3 1 2 0 6775392 65456 35776
05:18:34 23732 8192000000 9712154352 10008866816 786020896 8.754 100 85 170 0 814069376 6952272 21096208
05:20:02 17758 8192000000 9748821936 10053152768 823658432 9.135 100 90 180 0 856745296 11291872 21794992
05:21:23 16309 8192000000 9797166144 10113040384 857306256 9.473 100 86 168 1 895612256 18427104 19878896
05:22:46 7234 8192000000 9857111952 10189705216 916316976 10.060 100 83 166 0 972339744 37372208 18650560
05:24:00 6311 8192000000 9910460576 10255749120 952097232 10.412 100 85 168 1 1026605808 55953504 18555072
05:25:11 2051 8192000000 9970830176 10322833408 991738032 10.798 100 92 177 1 1087665440 77811744 18115664
05:26:15 1531 8192000000 9989635120 10350526464 1012594288 11.000 100 89 173 0 1112189104 82198496 17396320
05:27:19 695 8192000000 10000053712 10370375680 1039977520 11.264 100 96 188 1 1122511792 64080512 18453760
05:28:21 894 8192000000 10045499184 10422407168 1053393408 11.393 100 95 180 2 1163486000 92664544 17428048
05:29:23 994 8192000000 10036968272 10417213440 1061792032 11.474 100 95 178 1 1163560480 83661104 18107344
05:30:25 926 8192000000 10039683536 10418020352 1068305280 11.536 100 83 162 3 1157864864 73648672 15910912
05:31:29 5357 8192000000 10022766560 10409824256 1077525632 11.624 100 71 129 1 1147682032 56825520 13330880
05:32:35 5848 8192000000 10021702208 10412769280 1088073632 11.724 100 77 142 2 1147501856 44394656 15033568
05:33:41 1021 8192000000 10061688672 10446839808 1092759680 11.769 100 93 178 0 1186285936 74984688 18541568
05:34:43 1021 8192000000 10096187600 10487201792 1093757456 11.778 100 97 184 1 1218897856 105618752 19521648
05:35:45 2003 8192000000 10041675600 10436907008 1097210416 11.811 100 89 165 1 1170391168 56196880 16983872
05:36:49 989 8192000000 10038783952 10427039744 1092374464 11.765 100 91 175 0 1162347264 52245936 17726864
05:37:51 4835 8192000000 10025594496 10426593280 1096486752 11.804 100 51 89 1 1153960960 48596656 8877552
05:38:56 959 8192000000 10032566736 10423672832 1086203904 11.707 100 96 180 1 1153758976 48506992 19048080
05:39:57 2933 8192000000 10040181024 10431004672 1091438544 11.756 100 73 134 1 1161646848 56563216 13645088
05:41:01 1959 8192000000 10033206176 10419892224 1080052464 11.648 100 76 148 0 1158177744 62015600 16109680
05:42:04 1151 8192000000 10007588064 10388901888 1062295792 11.478 100 84 161 1 1129778816 52312656 15170368
05:43:06 1635 8192000000 10001874288 10382323712 1061699968 11.473 100 74 147 1 1127422624 50505600 15217056
05:44:12 954 8192000000 10039099200 10418245632 1053081664 11.390 100 94 180 0 1162959872 91603440 18274768
05:45:14 1820 8192000000 9995170096 10372988928 1041594800 11.280 100 81 161 3 1121498048 63244736 16658512
05:46:17 1218 8192000000 9988406816 10359611392 1042555312 11.289 100 96 185 0 1108530240 47665984 18308944
05:47:19 1769 8192000000 9996709968 10380402688 1051806880 11.378 100 78 145 1 1124598368 56614448 16177040
05:48:30 14486 8192000000 10007808480 10395426816 1064597920 11.500 100 72 136 1 1125860352 47940256 13322176
05:49:49 1646 8192000000 10055759664 10446315520 1083998256 11.686 100 94 172 1 1181417552 79311856 18107440
05:50:56 8411 8192000000 10066220272 10458816512 1101069776 11.848 100 68 131 3 1190598400 76486608 13042016
05:52:10 1364 8192000000 10112545504 10497314816 1118488480 12.013 100 91 173 2 1227660544 90605728 18566336
05:53:15 2843 8192000000 10132467504 10527150080 1131879856 12.139 100 78 148 1 1253572496 106597632 15095008
05:54:21 2138 8192000000 10146033456 10548101120 1139504176 12.211 100 92 181 0 1266199456 107966640 18728640
05:55:31 5111 8192000000 10176752992 10591862784 1150523072 12.314 100 66 130 0 1297251696 132573088 14155536
05:56:43 5156 8192000000 10166308832 10565869568 1146304320 12.275 99 83 154 2 1275310096 112775792 16229984
05:57:56 5382 8192000000 10230355824 10632806400 1170822000 12.505 100 80 150 0 1344038960 156398400 16818560
05:59:06 4770 8192000000 10227848576 10633576448 1166001120 12.459 100 72 143 1 1345996864 163763904 16231840
06:00:13 1490 8192000000 10302896000 10700337152 1175801696 12.551 100 93 180 2 1413228336 217772416 19654224
06:01:18 1065 8192000000 10305334768 10718523392 1173546288 12.530 100 94 184 3 1420562448 226148144 20868016
06:02:24 757 8192000000 10345918128 10747723776 1184752144 12.634 100 98 188 2 1456048832 250680352 20616336
06:03:27 1379 8192000000 10364108816 10781904896 1187418816 12.659 100 86 169 2 1472066960 264952864 19695280
06:04:33 1092 8192000000 10360608992 10787557376 1187525392 12.660 99 89 177 0 1467128592 258438784 21164416
06:05:40 884 8192000000 10414883296 10814545920 1198263056 12.760 100 97 187 3 1521255904 301323552 21669296
06:06:44 2438 8192000000 10452444784 10853752832 1182119936 12.610 99 92 181 0 1551403584 348556096 20727552
06:07:54 1034 8192000000 10521987584 10924658688 1202292832 12.798 100 96 192 0 1625614064 399702080 23619152
06:08:59 765 8192000000 10576897328 10983297024 1211564112 12.884 100 97 184 0 1682904464 448369504 22970848
06:10:05 813 8192000000 10633247584 11047645184 1203626016 12.810 99 96 187 0 1737316416 509779776 23910624
06:11:11 1207 8192000000 10660940000 11086209024 1202218656 12.797 100 90 172 0 1772908272 549740896 20948720
06:12:16 827 8192000000 10668434080 11090784256 1196561536 12.744 100 91 173 1 1773031488 554824624 21645328
06:13:21 817 8192000000 10692091888 11144859648 1194914704 12.729 100 97 191 0 1798243136 579827392 23501040
06:14:24 963 8192000000 10697927968 11125604352 1180559760 12.595 99 95 189 1 1803640864 598064224 25016880
06:15:30 1673 8192000000 10660406176 11153625088 1173622592 12.531 100 88 165 1 1763158048 567203312 22332144
06:16:35 6402 8192000000 10570627776 11087835136 1179620800 12.587 100 53 103 0 1675913664 482184832 14108032
06:17:49 4957 8192000000 10537166144 11000934400 1192857168 12.710 100 47 95 0 1654957488 450457536 11642784
06:19:03 21013 8192000000 10453144432 10997084160 1179478176 12.585 100 27 54 0 1583544720 396911344 7155200
06:20:39 16106 8192000000 10352510224 10852175872 1155571520 12.362 100 21 42 0 1486517552 324941920 6004112
06:22:34 33875 8192000000 10099548448 10589548544 1151726864 12.326 100 31 58 0 1239769408 82402176 5640368
06:24:10 52949 8192000000 10139116656 10595196928 1140518528 12.220 99 67 127 1 1225334896 72580400 12235968
06:26:30 105737 8192000000 10117835440 10567749632 1104801712 11.883 96 28 56 0 1191943376 81253904 5887760
06:29:20 68912 8192000000 10088121248 10554236928 1146150400 12.273 100 33 65 0 1223511872 70403184 6958288
06:31:30 75334 8192000000 10082783040 10532438016 1124486704 12.069 98 18 36 0 1156247120 28095168 3665248
06:33:46 29825 8192000000 10086719504 10562412544 1148584096 12.296 100 31 62 0 1189134368 33953104 6597168
06:35:24 27055 8192000000 10108673472 10552356864 1119796592 12.025 97 31 61 0 1189634640 63411248 6426800
06:36:52 52029 8192000000 10136171088 10602242048 1134803152 12.167 99 25 48 2 1225207680 85172160 5232368
06:39:24 89993 8192000000 10140014928 10671251456 1138226720 12.199 100 33 59 2 1237286192 91750448 7309024
06:42:06 71668 8192000000 10100691968 10586083328 1107281920 11.907 97 22 44 0 1224075056 111877744 4915392
06:44:21 37848 8192000000 10088710832 10532933632 1110442624 11.937 98 38 74 1 1165837952 47866640 7528688
06:46:03 32654 8192000000 10084371808 10532876288 1106596208 11.900 98 42 85 1 1160134112 44853712 8684192
06:47:40 55431 8192000000 10090424320 10545078272 1128545472 12.108 100 21 42 0 1201239472 68728512 3965488
06:49:38 44361 8192000000 10091870672 10544099328 1076997376 11.619 95 31 62 0 1127518480 44745664 5775440
06:51:29 68960 8192000000 10113076320 10560606208 1101393520 11.851 98 26 52 1 1152794032 45978000 5422512
06:53:40 53406 8192000000 10051773056 10474876928 1118776368 12.015 99 41 72 1 1145672000 19330736 7564896
06:55:35 116271 8192000000 10100099600 10550628352 1117479168 12.003 99 31 62 0 1223183808 99618176 6086464
06:58:41 53574 8192000000 10056810576 10517450752 1103799648 11.874 98 24 49 0 1140380112 31865760 4714704
07:00:36 47001 8192000000 10102964944 10551975936 1128820464 12.110 100 20 41 0 1203411424 70229712 4361248
07:02:24 52333 8192000000 10130574336 10567712768 1078901248 11.637 95 28 54 0 1189781296 104549792 6330256
07:04:21 100233 8192000000 10127156480 10627792896 1088712416 11.730 97 15 30 0 1233736032 141518320 3505296
07:08:10 37540 8192000000 10116275008 10562220032 1125436384 12.078 100 20 40 0 1219176640 89081504 4658752
07:09:59 40956 8192000000 10132394640 10564419584 1099442928 11.832 96 25 48 0 1187140464 82141264 5556272
07:11:46 33476 8192000000 10128890256 10614042624 1124530416 12.070 100 33 64 0 1231692192 100565232 6596544
07:13:20 46477 8192000000 10077406112 10535297024 1116905776 11.998 100 42 80 0 1181032992 56056416 8070800
07:15:13 36685 8192000000 10089322064 10559299584 1136285648 12.181 100 37 74 0 1203522688 59897376 7339664
07:16:52 20129 8192000000 10117811216 10541576192 1138363104 12.200 100 37 74 0 1193832784 48410016 7059664
07:18:14 109825 8192000000 10081857024 10550571008 1118202784 12.010 98 42 82 1 1201149280 74676032 8270464
07:21:05 72135 8192000000 10097869280 10559852544 1127340272 12.096 99 10 20 0 1201754976 72392384 2022320
07:23:17 82180 8192000000 10076038736 10593316864 1127984288 12.102 99 12 23 0 1179260624 49452784 1823552
07:26:11 49819 8192000000 10117110800 10567548928 1124533952 12.070 98 21 42 1 1195651104 66708384 4408768
07:28:07 22965 8192000000 10166970928 10605613056 1147358080 12.285 100 40 80 0 1274890848 119277792 8254976
07:29:32 80293 8192000000 10106083696 10571751424 1109713488 11.930 97 24 48 0 1226019440 110333232 5972720
07:31:53 24150 8192000000 10095610368 10523930624 1113492304 11.965 98 48 96 1 1191975312 68000720 10482288
07:33:18 99895 8192000000 10113389072 10555645952 1134704240 12.166 99 31 61 0 1191089232 49262160 7122832
07:38:20 58895 8192000000 10123063728 10594086912 1140138240 12.217 99 17 34 0 1207535600 63825904 3571456
07:40:45 80521 8192000000 10103241152 10633482240 1138619600 12.203 99 19 30 0 1226371376 83841248 3910528
07:43:09 35339 8192000000 10064190560 10510032896 1112511664 11.956 97 26 52 0 1149658752 31461040 5686048
07:44:47 36035 8192000000 10104277536 10548334592 1126983184 12.093 99 28 54 0 1185159264 52562000 5614080
07:46:36 44825 8192000000 10144569968 10626560000 1138581472 12.202 100 30 60 0 1265463136 119796224 7085440

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 21-07-2022 07:49:11 to 21-07-2022 07:49:27.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 135 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 21-07-2022 07:49:28 to 21-07-2022 07:49:29.

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          211150
MON$OLDEST_ACTIVE               215450
MON$OLDEST_SNAPSHOT             215450
MON$NEXT_TRANSACTION            215450
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                       2051936
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             767
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

Database statistics

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

Database validation

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

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

--- start of diff output ---
:6: 
:7: mscs-test-161.budzdorov.ru    Thu Jul 21 07:47:40 2022
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Thu Jul 21 07:47:40 2022
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Thu Jul 21 07:47:41 2022
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:17: 
:18: 
:19: mscs-test-161.budzdorov.ru    Thu Jul 21 07:47:41 2022
:20:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:21: 
--- end of diff output ---

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

21.07.22 07:49:31. 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
2022-07-21 07:49:33 No severe PSQL-related problems occured
21.07.22 07:49:34. End of report.