Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.11.2021 04:37
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 15826976 kB
MemAvailable 30735660 kB
Buffers 20 kB
Cached 15808456 kB
SwapCached 2444 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-11-11 04:37:09
DTS_END                         2021-11-11 04:37:09.2710
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/51058
TRN_ID                          232201
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: 9035.04 23660 1084205
customer order: creation 170.43 418 20451
customer order: refuse 14.35 326 1722
order to supplier: creation 45.59 1478 5471
order to supplier: removal 3.26 120690 391
invoice (draft): creation 44.38 3733 5326
invoice (draft): removal 50.08 971 6010
invoice accept: apply 41.58 20034 4990
invoice accept: cancel 45.71 897 5485
customer reserve: creation 3799.09 201 455891
customer reserve: removal 4527.99 68 543359
realization accept: apply 83.53 194 10024
realization accept: cancel 83.19 112 9983
payment from customer: creation 58.78 114 7053
payment from customer: removal 5.63 68 675
payment to supplier: creation 47.47 149 5696
payment to supplier: removal 3.44 95 413
service: total inventory turnovers 3.57 4021 428
service: total monetary turnovers 6.07 607 728
service: aggregate perf. data 0.88 15933 105
service: refresh index statistics 0.03 303089 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-11-2021 04:38:00 to 11-11-2021 04:38:02.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 12638
TEST_TIME 2 7580
TEST_TIME 3 6727
TEST_TIME 4 6164
TEST_TIME 5 7502
TEST_TIME 6 6879
TEST_TIME 7 6837
TEST_TIME 8 6975
TEST_TIME 9 7065
TEST_TIME 10 6982
TEST_TIME 11 6994
TEST_TIME 12 7023
TEST_TIME 13 7196
TEST_TIME 14 7459
TEST_TIME 15 7768
TEST_TIME 16 7651
TEST_TIME 17 7830
TEST_TIME 18 7776
TEST_TIME 19 8426
TEST_TIME 20 8794
TEST_TIME 21 9109
TEST_TIME 22 9300
TEST_TIME 23 9297
TEST_TIME 24 9460
TEST_TIME 25 10042
TEST_TIME 26 10376
TEST_TIME 27 11426
TEST_TIME 28 11718
TEST_TIME 29 11447
TEST_TIME 30 11377
TEST_TIME 31 11210
TEST_TIME 32 10996
TEST_TIME 33 11039
TEST_TIME 34 10821
TEST_TIME 35 10792
TEST_TIME 36 10788
TEST_TIME 37 10523
TEST_TIME 38 10448
TEST_TIME 39 10459
TEST_TIME 40 10368
TEST_TIME 41 10309
TEST_TIME 42 10188
TEST_TIME 43 10140
TEST_TIME 44 10087
TEST_TIME 45 9900
TEST_TIME 46 9918
TEST_TIME 47 9965
TEST_TIME 48 9899
TEST_TIME 49 9963
TEST_TIME 50 9896
TEST_TIME 51 9829
TEST_TIME 52 9793
TEST_TIME 53 9899
TEST_TIME 54 9822
TEST_TIME 55 9877
TEST_TIME 56 9836
TEST_TIME 57 9831
TEST_TIME 58 9794
TEST_TIME 59 9652
TEST_TIME 60 9607
TEST_TIME 61 9526
TEST_TIME 62 9459
TEST_TIME 63 9385
TEST_TIME 64 9448
TEST_TIME 65 9411
TEST_TIME 66 9387
TEST_TIME 67 9318
TEST_TIME 68 9314
TEST_TIME 69 9396
TEST_TIME 70 9419
TEST_TIME 71 9347
TEST_TIME 72 9415
TEST_TIME 73 9408
TEST_TIME 74 9422
TEST_TIME 75 9459
TEST_TIME 76 9456
TEST_TIME 77 9502
TEST_TIME 78 9493
TEST_TIME 79 9471
TEST_TIME 80 9369
TEST_TIME 81 9393
TEST_TIME 82 9341
TEST_TIME 83 9286
TEST_TIME 84 9258
TEST_TIME 85 9193
TEST_TIME 86 9196
TEST_TIME 87 9151
TEST_TIME 88 9093
TEST_TIME 89 9142
TEST_TIME 90 9146
TEST_TIME 91 9141
TEST_TIME 92 9197
TEST_TIME 93 9158
TEST_TIME 94 9146
TEST_TIME 95 9181
TEST_TIME 96 9211
TEST_TIME 97 9265
TEST_TIME 98 9273
TEST_TIME 99 9273
TEST_TIME 100 9339
TEST_TIME 101 9334
TEST_TIME 102 9310
TEST_TIME 103 9260
TEST_TIME 104 9250
TEST_TIME 105 9233
TEST_TIME 106 9178
TEST_TIME 107 9153
TEST_TIME 108 9155
TEST_TIME 109 9125
TEST_TIME 110 9078
TEST_TIME 111 9032
TEST_TIME 112 9060
TEST_TIME 113 9036
TEST_TIME 114 9054
TEST_TIME 115 9033
TEST_TIME 116 9062
TEST_TIME 117 9039
TEST_TIME 118 9141
TEST_TIME 119 9116
TEST_TIME 120 9042

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 11-11-2021 04:38:03 to 11-11-2021 04:38: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 1505877 1505877 0 0.00 0 16043 7 0 0
doc_list_biud 1505876 1505876 0 0.00 0 95030 31 0 0
sp_add_invoice_to_stock 5108 4990 118 2.31 25 217707 20034 0 118
sp_cancel_adding_invoice 5485 5485 0 0.00 5 87741 897 0 0
sp_cancel_client_order 1722 1722 0 0.00 5 8043 326 0 0
sp_cancel_customer_reserve 543359 543359 0 0.00 1 24415 68 0 0
sp_cancel_pay_from_customer 675 675 0 0.00 3 3292 69 0 0
sp_cancel_pay_to_supplier 413 413 0 0.00 3 4375 96 0 0
sp_cancel_supplier_invoice 6024 6010 14 0.23 2 95035 972 0 14
sp_cancel_supplier_order 391 391 0 0.00 9 1169961 120691 0 0
sp_cancel_write_off 9983 9983 0 0.00 1 12500 113 0 0
sp_client_order 20451 20451 0 0.00 4 22244 419 0 0
sp_customer_reserve 455891 455891 0 0.00 0 47380 202 0 0
sp_fill_shopping_cart 31241 31241 0 0.00 1 27856 46 0 0
sp_fill_shopping_cart_clo_res 455878 455878 0 0.00 0 23858 17 0 0
sp_get_clo_for_invoice 4977 4977 0 0.00 0 23591 343 0 0
sp_kill_cost_storno 16556 16556 0 0.00 0 7336 57 0 0
sp_kill_qstorno_ret_qs2qd 1103102 1103102 0 0.00 0 95030 37 0 0
sp_kill_qty_storno 581951 581951 0 0.00 0 95030 79 0 0
sp_lock_selected_doc 20555 20555 0 0.00 0 8807 5 0 0
sp_make_cost_storno 27750 27750 0 0.00 0 13660 60 0 0
sp_multiply_rows_for_pdistr 27750 27750 0 0.00 0 12950 33 0 0
sp_multiply_rows_for_qdistr 20451 20451 0 0.00 0 21218 211 0 0
sp_pay_from_customer 7053 7053 0 0.00 4 13096 114 0 0
sp_pay_to_supplier 5696 5696 0 0.00 5 15305 150 0 0
sp_payment_common 12748 12748 0 0.00 1 13083 22 0 0
sp_qd_handle_on_cancel_clo 1722 1722 0 0.00 0 8037 297 0 0
sp_qd_handle_on_invoice_upd_sts 10462 10462 0 0.00 0 30996 369 0 0
sp_qd_handle_on_reserve_upd_sts 10024 10024 0 0.00 0 21570 57 0 0
sp_reserve_write_off 10024 10024 0 0.00 7 21631 195 0 0
sp_supplier_invoice 5326 5326 0 0.00 7 140016 3734 0 0
sp_supplier_order 5471 5471 0 0.00 6 101093 1479 0 0
srv_aggregate_perf_data 105 105 0 0.00 2778 176642 15934 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1567 148877 47935 0 0
srv_find_qd_qs_mism 1037005 1037005 0 0.00 0 16043 6 0 0
srv_make_invnt_saldo 428 428 0 0.00 53 96779 4021 0 0
srv_make_money_saldo 728 728 0 0.00 1 35626 608 0 0
srv_recalc_idx_stat 4 4 0 0.00 107881 481886 303089 0 0
t$perf-abend:sp_add_invoice_to_stock 118 118 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4990 4990 0 0.00 0 30640 167 0 0
t$perf-norm:sp_cancel_adding_invoice 390 390 0 0.00 0 3647 79 0 0
t$perf-norm:sp_cancel_client_order 1722 1722 0 0.00 0 3652 10 0 0
t$perf-norm:sp_cancel_customer_reserve 657 657 0 0.00 0 1934 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 675 675 0 0.00 0 2090 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 413 413 0 0.00 0 1082 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 377 377 0 0.00 0 483 15 0 0
t$perf-norm:sp_cancel_supplier_order 391 391 0 0.00 0 32840 1531 0 0
t$perf-norm:sp_cancel_write_off 929 929 0 0.00 0 1089 6 0 0
t$perf-norm:sp_client_order 20451 20451 0 0.00 0 12310 21 0 0
t$perf-norm:sp_customer_reserve 986 986 0 0.00 0 5521 11 0 0
t$perf-norm:sp_pay_from_customer 7053 7053 0 0.00 0 12012 13 0 0
t$perf-norm:sp_pay_to_supplier 5696 5696 0 0.00 0 5260 11 0 0
t$perf-norm:sp_reserve_write_off 10024 10024 0 0.00 0 6794 12 0 0
t$perf-norm:sp_supplier_invoice 5326 5326 0 0.00 0 8415 17 0 0
t$perf-norm:sp_supplier_order 5471 5471 0 0.00 0 4744 14 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 507 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 224 4 0 0
t$perf-norm:srv_make_invnt_saldo 428 428 0 0.00 0 597 5 0 0
t$perf-norm:srv_make_money_saldo 728 728 0 0.00 0 3877 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 80 22 0 0
v_add_invoice_to_stock 10190 9954 236 2.31 0 5808 19 0 236
v_all_customers 37478 37478 0 0.00 0 17 1 0 0
v_all_suppliers 29620 29620 0 0.00 0 17 1 0 0
v_all_wares 40902 40902 0 0.00 0 303 1 0 0
v_cancel_adding_invoice 780 780 0 0.00 0 2557 13 0 0
v_cancel_client_order 3444 3444 0 0.00 0 2791 9 0 0
v_cancel_customer_prepayment 1350 1350 0 0.00 0 326 4 0 0
v_cancel_customer_reserve 1314 1314 0 0.00 0 2704 8 0 0
v_cancel_payment_to_supplier 826 826 0 0.00 0 4367 15 0 0
v_cancel_supplier_invoice 782 754 28 3.58 0 4332 20 0 28
v_cancel_supplier_order 782 782 0 0.00 0 1997 7 0 0
v_cancel_write_off 1858 1858 0 0.00 0 1419 14 0 0
v_max_id_clo_ord 5470 5470 0 0.00 0 2072 2 0 0
v_max_id_clo_res 986 986 0 0.00 0 6287 26 0 0
v_max_id_ord_sup 5319 5319 0 0.00 0 11451 6 0 0
v_max_non_paid_invoice 1675 1675 0 0.00 0 323 2 0 0
v_max_non_paid_realizn 3701 3701 0 0.00 0 3284 4 0 0
v_min_id_clo_ord 5470 5470 0 0.00 0 14011 16 0 0
v_min_id_clo_res 986 986 0 0.00 0 1585 4 0 0
v_min_id_ord_sup 5319 5319 0 0.00 0 5714 8 0 0
v_min_non_paid_invoice 5696 5696 0 0.00 0 9705 14 0 0
v_min_non_paid_realizn 7052 7052 0 0.00 0 3788 13 0 0
v_our_firm 10126 10126 0 0.00 0 14 1 0 0
v_reserve_write_off 20048 20048 0 0.00 0 2562 7 0 0
x_lock_depdocs_on_canc_invoice 11118 11118 0 0.00 0 21079 134 0 0
x_lock_depdocs_on_canc_sup_ord 391 391 0 0.00 0 23092 426 0 0
x_make_qstorno_1000_1200 5471 5471 0 0.00 2 98091 1379 0 0
x_make_qstorno_1200_2000 5326 5326 0 0.00 3 139989 3569 0 0
x_make_qstorno_2100_3300 455891 455891 0 0.00 0 40960 182 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-11-2021 04:38:08 to 11-11-2021 04:38: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
02:07:13 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:14 23609 8192000000 9716478368 10014101504 792890928 8.824 100 88 176 1 822118848 7253840 21974080
02:09:43 22251 8192000000 9759293904 10064490496 833325280 9.233 100 92 184 1 867430432 12367424 21737728
02:11:09 23998 8192000000 9806801520 10126532608 869830192 9.598 100 83 166 0 911139648 21931824 19377632
02:12:37 11991 8192000000 9857299184 10189877248 916243504 10.059 100 80 160 1 965714576 32306176 17164896
02:13:51 8051 8192000000 9944265440 10290704384 968580592 10.573 100 80 160 2 1052466368 67646528 16239248
02:15:00 3511 8192000000 9998639568 10353041408 1006256960 10.939 100 83 166 1 1101995888 79035120 16703808
02:16:06 4324 8192000000 10002339008 10373611520 1035790160 11.224 100 73 147 1 1107824768 57694032 14340576
02:17:14 1790 8192000000 10049689072 10417745920 1058244176 11.440 100 88 176 0 1164230240 88351536 17634528
02:18:19 6458 8192000000 10036183632 10409705472 1068273328 11.536 100 76 153 0 1151448704 67045552 16129824
02:19:26 3957 8192000000 9998607616 10389422080 1080068848 11.648 100 47 95 0 1114683840 24280416 10334576
02:20:32 7120 8192000000 9991830320 10391257088 1092263168 11.764 100 42 83 2 1119959248 17269904 10426176
02:21:42 3811 8192000000 10022153824 10415222784 1095673280 11.797 100 38 74 0 1141433968 37979968 7780720
02:22:47 25532 8192000000 10024619328 10408747008 1096966096 11.809 100 65 130 0 1122329696 12160288 13203312
02:24:13 7866 8192000000 10020253584 10425409536 1102867408 11.865 100 43 87 0 1140505760 27937728 9700624
02:25:22 5811 8192000000 10022100336 10429452288 1092167648 11.763 99 37 69 1 1134601984 35349024 7085312
02:26:32 35211 8192000000 10023023760 10440183808 1072454032 11.576 97 24 48 0 1103743296 26193456 5095808
02:28:09 4010 8192000000 9995018768 10389934080 1067168144 11.525 98 56 111 1 1091214144 11685264 12360736
02:29:13 8643 8192000000 9954120320 10342768640 961739344 10.506 92 41 84 1 1001799056 30801168 9258544
02:30:22 4416 8192000000 9949167616 10337701888 1013738848 11.012 99 28 56 0 1051384672 32197520 5448304
02:31:27 27142 8192000000 9937242272 10327486464 968175968 10.569 94 23 46 1 991517696 18684112 4657616
02:32:55 5704 8192000000 9952161008 10342637568 1031067232 11.179 100 49 100 1 1081355840 38284032 12004576
02:34:02 7412 8192000000 9964918016 10359582720 1052781392 11.387 100 41 79 0 1086401248 25003200 8616656
02:35:12 61733 8192000000 9967097520 10374455296 1060899104 11.465 100 25 50 1 1080768864 14094944 5774816
02:37:17 11326 8192000000 10006755984 10393104384 1082065744 11.667 100 56 111 0 1110210448 15763248 12381456
02:38:36 8076 8192000000 10050448720 10445565952 1101254592 11.850 100 59 118 1 1163546176 50648144 11643440
02:39:45 53596 8192000000 10064566320 10469433344 1114101968 11.971 100 36 72 1 1174620656 53235136 7283552
02:41:45 39707 8192000000 10151156896 10572693504 1131391840 12.134 100 56 112 0 1243649136 99765888 12491408
02:43:29 9163 8192000000 10138905104 10540453888 1144391792 12.257 100 55 110 0 1234685552 78094560 12199200
02:44:47 8074 8192000000 10162847840 10588332032 1156331376 12.369 100 60 118 0 1284534544 115698464 12504704
02:46:04 3334 8192000000 10194949408 10599628800 1159547072 12.399 100 60 120 0 1293250480 120406064 13297344
02:47:10 8217 8192000000 10250090400 10653646848 1163678800 12.438 100 70 139 0 1339486912 160441680 15366432
02:48:23 5764 8192000000 10276799680 10685304832 1169237360 12.490 100 67 132 0 1372146800 188203040 14706400
02:49:35 8719 8192000000 10320897504 10732462080 1179305072 12.584 100 83 164 0 1422000800 224748096 17947632
02:50:49 3288 8192000000 10348101952 10751926272 1183239840 12.620 100 74 145 0 1440594272 240620896 16733536
02:51:58 1783 8192000000 10363311664 10772537344 1138540720 12.202 100 89 176 1 1462406256 303516960 20348576
02:53:03 4460 8192000000 10365144112 10770534400 1140410352 12.219 100 61 121 2 1465461392 310370160 14680880
02:54:13 1919 8192000000 10457821664 10861178880 1161512944 12.417 100 85 171 2 1552961920 371094848 20354128
02:55:20 4434 8192000000 10463461392 10871074816 1164629440 12.447 100 74 148 0 1566395360 383868608 17897312
02:56:29 1565 8192000000 10406817248 10811260928 1136488640 12.182 99 82 164 0 1503832464 348181088 19162736
02:57:36 3973 8192000000 10434610752 10847694848 1157556400 12.380 100 60 121 1 1530731680 358387376 14787904
02:58:42 6920 8192000000 10433487040 10858823680 1168512576 12.483 100 43 84 1 1533969248 354640976 10815696
02:59:57 13751 8192000000 10416633392 10857701376 1150255648 12.312 99 37 74 1 1530616640 370481936 9879056
03:01:19 28052 8192000000 10458221264 10899914752 1136391312 12.182 100 31 62 1 1557342080 412655888 8294880
03:03:17 26797 8192000000 10346800240 10814869504 1096714656 11.806 98 15 30 0 1453960368 352818544 4427168
03:04:46 34505 8192000000 10091107312 10601795584 1118806672 12.016 100 9 18 0 1185398864 64977792 1614400
03:06:22 14459 8192000000 10088500064 10516606976 1125866736 12.082 100 56 109 1 1178314608 41281520 11166352
03:07:43 78368 8192000000 10101144960 10548809728 1115489472 11.984 98 25 51 0 1182519680 61640608 5389600
03:10:09 91413 8192000000 10108201472 10552295424 1118113296 12.009 98 21 42 1 1212074944 89576912 4384736
03:12:42 148877 8192000000 10108986512 10554343424 1134274816 12.162 100 35 70 1 1210555088 68323904 7956368
03:16:13 23680 8192000000 10061868240 10529611776 1141666176 12.231 100 29 57 1 1199251312 50969136 6616000
03:17:44 49673 8192000000 10094649296 10533408768 1122265408 12.048 98 22 43 1 1177178880 50219440 4694032
03:19:39 51687 8192000000 10073744464 10541203456 1114003168 11.970 98 15 30 2 1178960240 61494320 3462752
03:21:33 78618 8192000000 10111402800 10564079616 1138560560 12.202 100 31 61 0 1214443136 69132096 6750480
03:23:56 63675 8192000000 10086394256 10579746816 1129982976 12.121 99 11 22 1 1188936576 56196656 2756944
03:26:20 49487 8192000000 10090876384 10557243392 1139602960 12.212 99 24 48 0 1167053424 22595600 4854864
03:28:17 62318 8192000000 10129901024 10573070336 1111906192 11.950 96 25 50 0 1173349216 56234256 5208768
03:30:22 52698 8192000000 10114704192 10551656448 1136291120 12.181 99 18 37 0 1203066880 63040016 3735744
03:32:16 35381 8192000000 10087461360 10528505856 1148636720 12.297 100 24 48 0 1213685168 60344512 4703936
03:33:52 11621 8192000000 10092511168 10516680704 1113711376 11.968 98 55 110 0 1161060192 36339056 11009760
03:35:07 32482 8192000000 10071058080 10516697088 1109723248 11.930 99 36 71 2 1171490288 54449552 7317488
03:36:45 62931 8192000000 10054784928 10501308416 1111635424 11.948 99 38 76 0 1157254432 37453616 8165392
03:39:06 58063 8192000000 10095304640 10556719104 1136320144 12.181 100 13 26 0 1199369888 60497376 2552368
03:41:07 67470 8192000000 10104069904 10579062784 1117254768 12.001 98 11 22 0 1184609920 64911472 2443680
03:43:34 92889 8192000000 10157884800 10595606528 1133983552 12.159 99 28 54 2 1208853664 68809552 6060560
03:46:07 88515 8192000000 10065239968 10551861248 1101950416 11.856 97 9 18 0 1195772272 91663760 2158096
03:48:36 100923 8192000000 10081519312 10591297536 1094745264 11.788 96 14 28 0 1203115120 105338624 3031232
03:52:06 72028 8192000000 10085871088 10539630592 1111988624 11.951 98 17 34 0 1147381120 32150320 3242176
03:54:20 125289 8192000000 10095021264 10550267904 1117864064 12.007 98 22 43 0 1160225136 37907360 4453712
03:57:25 71990 8192000000 10114625488 10564964352 1126820096 12.091 99 34 68 0 1205229392 71160592 7248704
03:59:54 29120 8192000000 10051334288 10492039168 1112947088 11.960 98 12 24 0 1155931536 40471168 2513280
04:01:24 56032 8192000000 10068391376 10522230784 1105396256 11.889 97 11 22 0 1159163888 51446336 2321296
04:03:22 53299 8192000000 10080350544 10555883520 1119211856 12.020 99 11 22 0 1184235072 62615712 2407504
04:05:15 34196 8192000000 10113597984 10566807552 1138861840 12.205 100 12 24 0 1221718752 80264880 2592032
04:07:03 44743 8192000000 10081055920 10537000960 1133776272 12.157 99 14 28 0 1174684432 38174464 2733696
04:08:55 94078 8192000000 10071558608 10524200960 1124673840 12.071 97 20 40 0 1171442512 43053504 3715168
04:11:35 22619 8192000000 10120953984 10548817920 1135950944 12.177 98 40 80 2 1191827488 46954112 8922432
04:13:01 80478 8192000000 10136670896 10617909248 1134022016 12.159 98 13 26 0 1194704128 58065056 2617056
04:15:21 88469 8192000000 10101751808 10575052800 1154926576 12.356 100 18 38 0 1214631104 55924352 3780176
04:17:53 99479 8192000000 10081987536 10538717184 1135457024 12.173 98 11 22 0 1174898192 37231536 2209632
04:20:33 135473 8192000000 10057478000 10514755584 1090603872 11.748 95 23 46 0 1162552720 67341664 4607184
04:24:11 61171 8192000000 10059269872 10512945152 1111165232 11.943 99 17 33 1 1171246512 56724128 3357152
04:26:12 72627 8192000000 10053997520 10503716864 1084153040 11.687 96 20 39 2 1120959552 32200864 4605648
04:28:31 77633 8192000000 10084689712 10589597696 1123384656 12.059 99 10 20 1 1198961440 73379920 2196864
04:30:57 61630 8192000000 10069353680 10522611712 1093547680 11.776 97 25 49 2 1152565120 53320432 5697008
04:33:06 68544 8192000000 10114677488 10573029376 1107816064 11.912 98 23 46 3 1220666688 107872560 4978064
04:35:17 68341 8192000000 10047030288 10497277952 1071385728 11.565 96 16 32 0 1147902688 73229376 3287584

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 11-11-2021 04:38:14 to 11-11-2021 04:38:28.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 118 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 11-11-2021 04:38:29 to 11-11-2021 04:38: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          231420
MON$OLDEST_ACTIVE               233763
MON$OLDEST_SNAPSHOT             233763
MON$NEXT_TRANSACTION            233763
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                       2049376
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             803
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2656 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2656 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2656 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2656 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 ---
:25: 
:26: mscs-test-161.budzdorov.ru    Thu Nov 11 04:37:10 2021
:27:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:28: 
:29: 
:30: mscs-test-161.budzdorov.ru    Thu Nov 11 04:37:10 2021
:31:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:32: 
:33: 
:34: mscs-test-161.budzdorov.ru    Thu Nov 11 04:37:11 2021
:35:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:36: 
:37: 
:38: mscs-test-161.budzdorov.ru    Thu Nov 11 04:37:14 2021
:39:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:40: 
:41: 
:42: mscs-test-161.budzdorov.ru    Thu Nov 11 04:37:14 2021
:43:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:44: 
--- end of diff output ---

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

11.11.21 04:38: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-11-11 04:38:35 No severe PSQL-related problems occured
11.11.21 04:38:36. End of report.