Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 01.02.2021 13:27
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 e0e7b46250ba4f9795ceb55e0face73f
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 2020-09-25 13:42
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.00
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 16554776 kB
MemAvailable 31215672 kB
Buffers 0 kB
Cached 15091816 kB
SwapCached 4876 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.0
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-02-01 13:26:56
DTS_END                         2021-02-01 13:26:56.6480
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/46308
TRN_ID                          231390
ATT_ID                          663
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: 8954.50 24239 1074540
customer order: creation 162.42 383 19490
customer order: refuse 13.25 326 1590
order to supplier: creation 43.69 1414 5243
order to supplier: removal 3.52 112042 422
invoice (draft): creation 42.89 3397 5147
invoice (draft): removal 51.50 836 6180
invoice accept: apply 40.21 18205 4825
invoice accept: cancel 46.18 830 5542
customer reserve: creation 3708.03 178 444964
customer reserve: removal 4562.63 69 547516
realization accept: apply 78.17 189 9380
realization accept: cancel 80.97 117 9717
payment from customer: creation 55.65 112 6678
payment from customer: removal 5.72 102 686
payment to supplier: creation 45.55 131 5466
payment to supplier: removal 3.21 82 385
service: total inventory turnovers 3.72 3385 446
service: total monetary turnovers 6.31 450 757
service: aggregate perf. data 0.84 19236 101
service: refresh index statistics 0.04 323295 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 01-02-2021 13:27:59 to 01-02-2021 13:28:02.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6759
TEST_TIME 2 4037
TEST_TIME 3 3468
TEST_TIME 4 4566
TEST_TIME 5 5128
TEST_TIME 6 4991
TEST_TIME 7 5006
TEST_TIME 8 5146
TEST_TIME 9 5212
TEST_TIME 10 5344
TEST_TIME 11 5316
TEST_TIME 12 5654
TEST_TIME 13 5910
TEST_TIME 14 6183
TEST_TIME 15 6328
TEST_TIME 16 6447
TEST_TIME 17 6998
TEST_TIME 18 7055
TEST_TIME 19 7424
TEST_TIME 20 7791
TEST_TIME 21 7769
TEST_TIME 22 7972
TEST_TIME 23 8590
TEST_TIME 24 9105
TEST_TIME 25 9369
TEST_TIME 26 9641
TEST_TIME 27 9758
TEST_TIME 28 10776
TEST_TIME 29 10922
TEST_TIME 30 10912
TEST_TIME 31 10682
TEST_TIME 32 10592
TEST_TIME 33 10569
TEST_TIME 34 10314
TEST_TIME 35 10125
TEST_TIME 36 10021
TEST_TIME 37 9939
TEST_TIME 38 10035
TEST_TIME 39 9990
TEST_TIME 40 9941
TEST_TIME 41 9850
TEST_TIME 42 9787
TEST_TIME 43 9720
TEST_TIME 44 9607
TEST_TIME 45 9920
TEST_TIME 46 9872
TEST_TIME 47 9822
TEST_TIME 48 9814
TEST_TIME 49 9894
TEST_TIME 50 9845
TEST_TIME 51 9863
TEST_TIME 52 9909
TEST_TIME 53 9832
TEST_TIME 54 9792
TEST_TIME 55 10003
TEST_TIME 56 9931
TEST_TIME 57 10087
TEST_TIME 58 9976
TEST_TIME 59 9814
TEST_TIME 60 9703
TEST_TIME 61 9656
TEST_TIME 62 9537
TEST_TIME 63 9496
TEST_TIME 64 9437
TEST_TIME 65 9386
TEST_TIME 66 9380
TEST_TIME 67 9348
TEST_TIME 68 9361
TEST_TIME 69 9408
TEST_TIME 70 9442
TEST_TIME 71 9546
TEST_TIME 72 9493
TEST_TIME 73 9467
TEST_TIME 74 9525
TEST_TIME 75 9497
TEST_TIME 76 9414
TEST_TIME 77 9331
TEST_TIME 78 9288
TEST_TIME 79 9263
TEST_TIME 80 9284
TEST_TIME 81 9227
TEST_TIME 82 9196
TEST_TIME 83 9146
TEST_TIME 84 9177
TEST_TIME 85 9304
TEST_TIME 86 9360
TEST_TIME 87 9306
TEST_TIME 88 9339
TEST_TIME 89 9329
TEST_TIME 90 9374
TEST_TIME 91 9409
TEST_TIME 92 9432
TEST_TIME 93 9333
TEST_TIME 94 9269
TEST_TIME 95 9222
TEST_TIME 96 9165
TEST_TIME 97 9134
TEST_TIME 98 9139
TEST_TIME 99 9098
TEST_TIME 100 9097
TEST_TIME 101 9120
TEST_TIME 102 9103
TEST_TIME 103 9081
TEST_TIME 104 9079
TEST_TIME 105 9068
TEST_TIME 106 9024
TEST_TIME 107 9102
TEST_TIME 108 9164
TEST_TIME 109 9166
TEST_TIME 110 9184
TEST_TIME 111 9186
TEST_TIME 112 9107
TEST_TIME 113 9074
TEST_TIME 114 9021
TEST_TIME 115 8980
TEST_TIME 116 8989
TEST_TIME 117 8970
TEST_TIME 118 8943
TEST_TIME 119 8953
TEST_TIME 120 8958

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 01-02-2021 13:28:03 to 01-02-2021 13:28:07.

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 1486556 1486556 0 0.00 0 31493 7 0 0
doc_list_biud 1486554 1486554 0 0.00 0 22263 32 0 0
sp_add_invoice_to_stock 4951 4825 126 2.54 37 274838 18206 0 126
sp_cancel_adding_invoice 5542 5542 0 0.00 3 96123 830 0 0
sp_cancel_client_order 1590 1590 0 0.00 4 8876 326 0 0
sp_cancel_customer_reserve 547516 547516 0 0.00 1 22266 70 0 0
sp_cancel_pay_from_customer 686 686 0 0.00 2 9312 102 0 0
sp_cancel_pay_to_supplier 385 385 0 0.00 3 5682 82 0 0
sp_cancel_supplier_invoice 6197 6180 17 0.27 2 11752 836 0 17
sp_cancel_supplier_order 422 422 0 0.00 6 1187695 112042 0 0
sp_cancel_write_off 9717 9717 0 0.00 1 8782 118 0 0
sp_client_order 19490 19490 0 0.00 4 20772 384 0 0
sp_customer_reserve 444964 444964 0 0.00 0 122367 178 0 0
sp_fill_shopping_cart 29874 29874 0 0.00 1 30407 45 0 0
sp_fill_shopping_cart_clo_res 444958 444958 0 0.00 0 22011 15 0 0
sp_get_clo_for_invoice 4819 4819 0 0.00 0 23830 385 0 0
sp_kill_cost_storno 16330 16330 0 0.00 0 7217 60 0 0
sp_kill_qstorno_ret_qs2qd 1111351 1111351 0 0.00 0 22211 37 0 0
sp_kill_qty_storno 585165 585165 0 0.00 0 22262 79 0 0
sp_lock_selected_doc 19668 19668 0 0.00 0 8904 5 0 0
sp_make_cost_storno 26343 26343 0 0.00 0 31488 65 0 0
sp_multiply_rows_for_pdistr 26342 26342 0 0.00 0 9629 36 0 0
sp_multiply_rows_for_qdistr 19490 19490 0 0.00 0 20763 210 0 0
sp_pay_from_customer 6678 6678 0 0.00 4 23508 113 0 0
sp_pay_to_supplier 5466 5466 0 0.00 4 10679 132 0 0
sp_payment_common 12144 12144 0 0.00 1 12435 23 0 0
sp_qd_handle_on_cancel_clo 1590 1590 0 0.00 0 8863 298 0 0
sp_qd_handle_on_invoice_upd_sts 10360 10360 0 0.00 0 20408 370 0 0
sp_qd_handle_on_reserve_upd_sts 9380 9380 0 0.00 0 15140 56 0 0
sp_reserve_write_off 9380 9380 0 0.00 6 15261 190 0 0
sp_supplier_invoice 5147 5147 0 0.00 6 94746 3397 0 0
sp_supplier_order 5243 5243 0 0.00 7 28248 1414 0 0
srv_aggregate_perf_data 101 101 0 0.00 2741 209028 19236 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1213 145600 47158 0 0
srv_find_qd_qs_mism 1029258 1029258 0 0.00 0 19757 6 0 0
srv_make_invnt_saldo 446 446 0 0.00 53 84775 3385 0 0
srv_make_money_saldo 757 757 0 0.00 1 15143 451 0 0
srv_recalc_idx_stat 5 5 0 0.00 144361 526191 323295 0 0
t$perf-abend:sp_add_invoice_to_stock 126 126 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 17 17 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4826 4826 0 0.00 0 15911 206 0 0
t$perf-norm:sp_cancel_adding_invoice 379 379 0 0.00 0 9642 203 0 0
t$perf-norm:sp_cancel_client_order 1590 1590 0 0.00 0 3106 11 0 0
t$perf-norm:sp_cancel_customer_reserve 654 654 0 0.00 0 1378 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 686 686 0 0.00 0 4846 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 385 385 0 0.00 0 626 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 366 366 0 0.00 0 3645 29 0 0
t$perf-norm:sp_cancel_supplier_order 422 422 0 0.00 0 17721 1451 0 0
t$perf-norm:sp_cancel_write_off 988 988 0 0.00 0 6064 11 0 0
t$perf-norm:sp_client_order 19490 19490 0 0.00 0 23149 26 0 0
t$perf-norm:sp_customer_reserve 980 980 0 0.00 0 2643 8 0 0
t$perf-norm:sp_pay_from_customer 6679 6679 0 0.00 0 5146 12 0 0
t$perf-norm:sp_pay_to_supplier 5466 5466 0 0.00 0 7812 13 0 0
t$perf-norm:sp_reserve_write_off 9380 9380 0 0.00 0 39579 17 0 0
t$perf-norm:sp_supplier_invoice 5147 5147 0 0.00 0 39102 30 0 0
t$perf-norm:sp_supplier_order 5243 5243 0 0.00 0 5406 21 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 273 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 18 1 0 0
t$perf-norm:srv_make_invnt_saldo 446 446 0 0.00 0 545 11 0 0
t$perf-norm:srv_make_money_saldo 757 757 0 0.00 0 1838 12 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 17 5 0 0
v_add_invoice_to_stock 9888 9636 252 2.54 0 4934 15 0 252
v_all_customers 35612 35612 0 0.00 0 21 1 0 0
v_all_suppliers 28550 28550 0 0.00 0 21 1 0 0
v_all_wares 38980 38980 0 0.00 0 136 1 0 0
v_cancel_adding_invoice 758 758 0 0.00 0 80 5 0 0
v_cancel_client_order 3180 3180 0 0.00 0 4829 6 0 0
v_cancel_customer_prepayment 1372 1372 0 0.00 0 9117 17 0 0
v_cancel_customer_reserve 1308 1308 0 0.00 0 2966 6 0 0
v_cancel_payment_to_supplier 770 770 0 0.00 0 458 5 0 0
v_cancel_supplier_invoice 766 732 34 4.43 0 2006 12 0 34
v_cancel_supplier_order 844 844 0 0.00 0 1595 5 0 0
v_cancel_write_off 1976 1976 0 0.00 0 3960 20 0 0
v_max_id_clo_ord 5242 5242 0 0.00 0 1942 2 0 0
v_max_id_clo_res 980 980 0 0.00 0 4009 23 0 0
v_max_id_ord_sup 5142 5142 0 0.00 0 4885 5 0 0
v_max_non_paid_invoice 1575 1575 0 0.00 0 6281 6 0 0
v_max_non_paid_realizn 3449 3449 0 0.00 0 2995 4 0 0
v_min_id_clo_ord 5242 5242 0 0.00 0 3627 9 0 0
v_min_id_clo_res 980 980 0 0.00 0 1870 5 0 0
v_min_id_ord_sup 5142 5142 0 0.00 0 8927 21 0 0
v_min_non_paid_invoice 5466 5466 0 0.00 0 6195 12 0 0
v_min_non_paid_realizn 6678 6678 0 0.00 0 12433 20 0 0
v_our_firm 9826 9826 0 0.00 0 124 1 0 0
v_reserve_write_off 18760 18760 0 0.00 0 2551 4 0 0
x_lock_depdocs_on_canc_invoice 11356 11356 0 0.00 0 30437 114 0 0
x_lock_depdocs_on_canc_sup_ord 422 422 0 0.00 0 11860 364 0 0
x_make_qstorno_1000_1200 5243 5243 0 0.00 2 28233 1332 0 0
x_make_qstorno_1200_2000 5147 5147 0 0.00 3 94736 3218 0 0
x_make_qstorno_2100_3300 444964 444964 0 0.00 0 122366 161 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 01-02-2021 13:28:08 to 01-02-2021 13:28:12.

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
10:57:00 2 8192000000 8796640128 8807108608 3168304 0.038 1 1 2 0 3265920 61840 35776
10:58:01 34101 8192000000 9733766112 10031050752 814366288 9.042 100 89 178 0 843135456 7521280 21247888
10:59:39 17114 8192000000 9781086368 10087051264 847143552 9.371 100 88 176 1 881266464 13380288 20742624
11:01:01 20226 8192000000 9809471696 10129514496 882284672 9.722 100 88 176 0 923795120 21459424 20051024
11:02:27 13422 8192000000 9882395824 10215649280 928243312 10.177 100 76 150 1 989311072 44991856 16075904
11:03:42 10181 8192000000 9927404336 10270420992 952629248 10.417 100 80 160 0 1038143264 68716208 16797808
11:04:56 5118 8192000000 9940848944 10298138624 987192080 10.754 100 74 148 0 1055174736 52960176 15022480
11:06:04 2781 8192000000 10004912000 10363465728 1020919312 11.081 100 78 157 1 1113633840 77327728 15386800
11:07:09 1287 8192000000 10036064192 10405027840 1046085136 11.323 100 88 176 1 1149054160 84537200 18431824
11:08:12 16369 8192000000 9970859424 10373263360 1056057184 11.419 100 32 64 1 1117029744 54366112 6606448
11:09:30 3093 8192000000 9989766320 10376527872 1070736000 11.559 100 42 79 3 1113599184 34083888 8779296
11:10:33 6482 8192000000 9995234848 10388303872 1075660016 11.606 100 41 82 1 1106741696 22654224 8427456
11:11:40 39493 8192000000 10008077968 10399363072 1082619072 11.672 100 53 107 2 1106152912 11796704 11737136
11:13:27 3758 8192000000 10014319200 10401984512 1088508320 11.728 100 62 123 1 1122102224 21026176 12567728
11:14:32 6973 8192000000 10014040528 10420621312 1094627552 11.787 100 29 58 1 1124445760 23026912 6791296
11:15:44 8994 8192000000 10020433120 10428035072 1094932592 11.790 100 27 54 3 1126466816 26127632 5406592
11:16:54 56963 8192000000 10006054720 10401509376 1096158448 11.801 100 40 82 0 1118832112 13756688 8916976
11:18:56 9824 8192000000 9957607168 10356256768 1012677856 11.001 95 22 44 1 1051814736 34392064 4744816
11:20:08 5090 8192000000 9940683520 10325753856 1018852944 11.061 100 40 77 2 1044817488 17219680 8744864
11:21:14 11715 8192000000 9934279024 10320584704 1007384992 10.950 98 32 64 0 1040056464 25808112 6863360
11:22:26 45086 8192000000 9936393264 10328477696 1006693072 10.943 97 25 50 0 1032670944 20243280 5734592
11:24:11 6654 8192000000 9961926160 10343194624 1038219008 11.248 100 44 88 1 1072510464 24947856 9343600
11:25:18 6439 8192000000 9973487936 10373324800 1053986480 11.399 100 37 72 3 1077587104 15177024 8423600
11:26:27 9667 8192000000 9966154848 10363875328 1061677728 11.473 100 26 53 3 1083794960 16324272 5792960
11:27:41 74979 8192000000 10003106928 10396008448 1080462976 11.652 100 49 100 2 1101063536 9739456 10861104
11:30:02 11879 8192000000 10072476032 10468134912 1093958512 11.780 100 52 104 2 1177140288 72280928 10900848
11:31:17 2756 8192000000 10066550640 10477371392 1106950128 11.904 100 47 90 1 1178294608 62040288 9304192
11:32:26 10001 8192000000 10077763648 10477604864 1125152064 12.076 100 55 107 1 1184245872 47773824 11319984
11:33:45 16523 8192000000 10135387632 10538401792 1134894720 12.167 100 57 114 3 1249213552 101538208 12780624
11:35:10 15235 8192000000 10182233472 10588692480 1137290960 12.190 99 57 109 1 1273541376 124298432 11951984
11:36:32 13352 8192000000 10202402544 10617712640 1159912528 12.402 100 33 66 0 1313276784 146068640 7295616
11:37:52 5414 8192000000 10262856496 10676584448 1169820736 12.495 100 60 119 0 1366940208 183254128 13865344
11:39:12 5181 8192000000 10287126304 10692997120 1174551920 12.539 100 71 139 2 1389074016 199591088 14931008
11:40:21 3829 8192000000 10323775760 10722336768 1172775280 12.523 99 84 166 2 1424344848 233650784 17918784
11:41:31 2677 8192000000 10351298832 10746212352 1165216304 12.452 100 83 165 0 1450189552 266554560 18418688
11:42:42 1863 8192000000 10358658976 10750586880 1140687904 12.222 99 91 179 2 1459930496 299194576 20048016
11:43:48 1210 8192000000 10352119120 10753576960 1157676240 12.381 100 94 184 2 1448373104 270082304 20614560
11:44:54 5011 8192000000 10393542784 10786672640 1152316384 12.331 100 83 163 1 1486651024 314686496 19648144
11:46:02 17969 8192000000 10365270656 10813677568 1158538672 12.390 100 44 88 1 1492813232 323056208 11218352
11:47:28 10012 8192000000 10389450192 10813239296 1123958512 12.064 99 46 92 0 1494045280 358183152 11903616
11:48:42 10260 8192000000 10436107920 10868781056 1137616112 12.193 99 55 109 1 1527431248 376104224 13710912
11:49:57 8044 8192000000 10398881488 10817875968 1143590672 12.249 100 39 78 1 1501904272 347891648 10421952
11:51:06 11849 8192000000 10344362880 10813820928 1157604512 12.381 100 32 64 1 1451779152 286354496 7820144
11:52:26 36754 8192000000 10278013168 10755612672 1129691216 12.118 99 23 46 2 1421514112 285927120 5895776
11:54:03 22967 8192000000 10271418464 10767478784 1122663072 12.052 100 19 38 0 1409365728 281143152 5559504
11:55:30 24179 8192000000 10073458416 10511343616 1113523696 11.966 99 12 24 1 1155594096 39148304 2922096
11:56:55 31840 8192000000 10073208560 10493767680 1109535760 11.928 99 33 64 0 1159231808 42824752 6871296
11:58:58 145600 8192000000 10054487152 10520104960 1108836960 11.921 99 37 72 1 1139050784 23046336 7167488
12:02:26 44344 8192000000 10097386992 10548006912 1121736256 12.043 99 26 52 0 1186445312 59016080 5692976
12:04:14 24211 8192000000 10102848752 10574651392 1128392048 12.106 100 42 81 2 1206954496 68414528 10147920
12:05:42 118555 8192000000 10062324416 10504781824 1134399248 12.163 99 20 40 1 1169175472 30376208 4400016
12:08:43 109993 8192000000 10159760592 10631864320 1141819216 12.233 99 10 20 0 1226247872 82456080 1972576
12:11:39 47164 8192000000 10073951408 10501550080 1114403504 11.974 97 26 52 1 1154699696 35130160 5166032
12:13:29 52379 8192000000 10069599392 10523308032 1106421056 11.899 97 26 52 2 1144539856 32974112 5144688
12:15:27 64408 8192000000 10077358992 10519343104 1117084896 11.999 98 22 43 2 1155200208 33752944 4362368
12:17:40 45831 8192000000 10078588496 10529394688 1127352208 12.096 99 15 30 0 1182328272 51896768 3079296
12:19:26 82305 8192000000 10153743600 10600407040 1095031120 11.790 96 20 40 1 1185827504 86085200 4711184
12:21:49 73847 8192000000 10113500048 10547183616 1074587680 11.596 95 6 12 0 1122870928 47082800 1200448
12:24:04 55607 8192000000 10061606688 10549821440 1122646128 12.052 100 10 20 1 1161941376 37073952 2221296
12:26:41 49106 8192000000 10070259136 10519486464 1116762656 11.996 99 10 20 0 1165399056 46665504 1970896
12:28:30 31726 8192000000 10062064144 10538299392 1127766368 12.100 100 3 6 0 1163092320 34824096 501856
12:30:02 54734 8192000000 10094396512 10548928512 1101826080 11.855 97 21 43 1 1143539104 37277664 4435360
12:32:13 59731 8192000000 10130344928 10591862784 1111194880 11.944 98 16 31 0 1217257712 102306112 3756720
12:34:18 42777 8192000000 10116853584 10541346816 1098385984 11.822 97 27 52 1 1187713920 83361232 5966704
12:36:14 66323 8192000000 10091288720 10562764800 1107604544 11.910 98 17 34 0 1200426624 88877216 3944864
12:38:27 28361 8192000000 10071115888 10492817408 1065452480 11.509 95 42 84 1 1120722688 46973248 8296960
12:40:04 65156 8192000000 10083485136 10514911232 1074969120 11.600 97 38 76 1 1150536352 68107296 7459936
12:42:29 50714 8192000000 10040946160 10489942016 1116161984 11.991 100 10 20 0 1150007504 32027760 1817760
12:44:21 78682 8192000000 10074757456 10559066112 1128754896 12.110 100 9 18 1 1184283664 53766096 1762672
12:46:49 76471 8192000000 10085410544 10552311808 1095145360 11.792 97 12 23 0 1137310592 39772384 2392848
12:49:55 90022 8192000000 10164824832 10628579328 1085390720 11.699 96 28 56 2 1199196832 107825456 5980656
12:52:43 133650 8192000000 10087955328 10549653504 1078270144 11.631 96 10 20 0 1113713648 33460576 1982928
12:56:03 26047 8192000000 10133691664 10573901824 1128630880 12.108 100 47 95 1 1200849616 62965776 9252960
12:57:35 114351 8192000000 10069428768 10585366528 1110669712 11.939 98 9 18 0 1155391584 43037936 1683936
13:00:30 82660 8192000000 10052167152 10519252992 1135747744 12.176 100 13 26 0 1173761920 35261824 2752352
13:03:07 45848 8192000000 10081889808 10569646080 1128709264 12.109 99 5 10 0 1171935040 42199168 1026608
13:05:35 55869 8192000000 10069992512 10534158336 1103290960 11.869 97 17 33 3 1130962800 23863296 3808544
13:07:51 56195 8192000000 10082717840 10526773248 1122279408 12.049 99 14 28 2 1167010048 41656800 3073840
13:09:56 53380 8192000000 10089657008 10545111040 1116168272 11.991 98 19 38 0 1153310400 33652464 3489664
13:11:53 34705 8192000000 10140201600 10569293824 1139378528 12.210 99 33 66 1 1202134944 55975280 6781136
13:13:27 49144 8192000000 10126811744 10551599104 1118663520 12.014 97 37 74 0 1187840080 62139600 7036960
13:15:19 65771 8192000000 10124499664 10554773504 1128391728 12.106 98 16 32 0 1182895968 51377984 3126256
13:17:25 118495 8192000000 10094830736 10566864896 1120676208 12.033 97 13 26 0 1171914480 48788976 2449296
13:20:41 50659 8192000000 10091286832 10565750784 1138319120 12.200 100 8 16 1 1166961760 26843648 1798992
13:22:56 50495 8192000000 10082387344 10527043584 1125789008 12.082 99 25 50 1 1155839648 24503248 5547392
13:24:47 84652 8192000000 10083022240 10584489984 1120990240 12.036 98 15 30 0 1202243136 77819024 3433872

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 01-02-2021 13:28:13 to 01-02-2021 13:28:28.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 126 v_add_invoice_to_stock 335544517
except 17 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 01-02-2021 13:28:29 to 01-02-2021 13:28:31.

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          229231
MON$OLDEST_ACTIVE               231618
MON$OLDEST_SNAPSHOT             231618
MON$NEXT_TRANSACTION            231618
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                       2101376
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             792
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2353 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2353 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2353 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.0.2353 Firebird 4.0 Release Candidate 1/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 ---
:124: 
:125: mscs-test-161.budzdorov.ru    Mon Feb  1 13:27:02 2021
:126:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:127: 
:128: 
:129: mscs-test-161.budzdorov.ru    Mon Feb  1 13:27:02 2021
:130:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:131: 
:132: 
:133: mscs-test-161.budzdorov.ru    Mon Feb  1 13:27:02 2021
:134:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:135: 
--- end of diff output ---

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

01.02.21 13:28:32. 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-02-01 13:28:35 No severe PSQL-related problems occured
01.02.21 13:28:36. End of report.