Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 31.10.2021 07:47
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 16122828 kB
MemAvailable 30836600 kB
Buffers 0 kB
Cached 15574632 kB
SwapCached 4368 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-10-31 07:47:32
DTS_END                         2021-10-31 07:47:32.8380
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/51642
TRN_ID                          231807
ATT_ID                          584
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: 9189.82 25994 1102778
customer order: creation 167.67 440 20120
customer order: refuse 13.88 386 1666
order to supplier: creation 45.76 1361 5491
order to supplier: removal 3.42 120665 410
invoice (draft): creation 46.41 3630 5569
invoice (draft): removal 52.88 968 6345
invoice accept: apply 41.90 21124 5028
invoice accept: cancel 47.63 839 5716
customer reserve: creation 3838.41 209 460609
customer reserve: removal 4640.60 69 556872
realization accept: apply 83.38 196 10006
realization accept: cancel 79.34 139 9521
payment from customer: creation 58.19 102 6983
payment from customer: removal 5.85 96 702
payment to supplier: creation 49.13 130 5895
payment to supplier: removal 3.78 99 454
service: total inventory turnovers 4.08 3493 489
service: total monetary turnovers 6.53 535 783
service: aggregate perf. data 0.95 16855 114
service: refresh index statistics 0.04 348548 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 31-10-2021 07:48:11 to 31-10-2021 07:48:13.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5814
TEST_TIME 2 6683
TEST_TIME 3 6445
TEST_TIME 4 6402
TEST_TIME 5 6095
TEST_TIME 6 6762
TEST_TIME 7 6435
TEST_TIME 8 6381
TEST_TIME 9 6305
TEST_TIME 10 6430
TEST_TIME 11 6839
TEST_TIME 12 7025
TEST_TIME 13 6942
TEST_TIME 14 7151
TEST_TIME 15 7234
TEST_TIME 16 7570
TEST_TIME 17 7696
TEST_TIME 18 7586
TEST_TIME 19 7760
TEST_TIME 20 8394
TEST_TIME 21 8444
TEST_TIME 22 8437
TEST_TIME 23 8230
TEST_TIME 24 8314
TEST_TIME 25 8382
TEST_TIME 26 8402
TEST_TIME 27 8435
TEST_TIME 28 8512
TEST_TIME 29 8915
TEST_TIME 30 10339
TEST_TIME 31 10486
TEST_TIME 32 10495
TEST_TIME 33 10318
TEST_TIME 34 10066
TEST_TIME 35 10056
TEST_TIME 36 10096
TEST_TIME 37 10087
TEST_TIME 38 9908
TEST_TIME 39 9807
TEST_TIME 40 9783
TEST_TIME 41 9709
TEST_TIME 42 9795
TEST_TIME 43 9766
TEST_TIME 44 9658
TEST_TIME 45 9527
TEST_TIME 46 9388
TEST_TIME 47 9459
TEST_TIME 48 9692
TEST_TIME 49 9625
TEST_TIME 50 9657
TEST_TIME 51 9587
TEST_TIME 52 9686
TEST_TIME 53 9730
TEST_TIME 54 9783
TEST_TIME 55 9759
TEST_TIME 56 9688
TEST_TIME 57 9719
TEST_TIME 58 9728
TEST_TIME 59 9854
TEST_TIME 60 9807
TEST_TIME 61 9844
TEST_TIME 62 9790
TEST_TIME 63 9659
TEST_TIME 64 9601
TEST_TIME 65 9513
TEST_TIME 66 9471
TEST_TIME 67 9452
TEST_TIME 68 9404
TEST_TIME 69 9390
TEST_TIME 70 9340
TEST_TIME 71 9304
TEST_TIME 72 9272
TEST_TIME 73 9257
TEST_TIME 74 9337
TEST_TIME 75 9342
TEST_TIME 76 9344
TEST_TIME 77 9405
TEST_TIME 78 9404
TEST_TIME 79 9406
TEST_TIME 80 9432
TEST_TIME 81 9452
TEST_TIME 82 9512
TEST_TIME 83 9482
TEST_TIME 84 9397
TEST_TIME 85 9354
TEST_TIME 86 9327
TEST_TIME 87 9320
TEST_TIME 88 9252
TEST_TIME 89 9259
TEST_TIME 90 9194
TEST_TIME 91 9178
TEST_TIME 92 9218
TEST_TIME 93 9232
TEST_TIME 94 9201
TEST_TIME 95 9212
TEST_TIME 96 9214
TEST_TIME 97 9236
TEST_TIME 98 9224
TEST_TIME 99 9199
TEST_TIME 100 9228
TEST_TIME 101 9251
TEST_TIME 102 9396
TEST_TIME 103 9355
TEST_TIME 104 9289
TEST_TIME 105 9230
TEST_TIME 106 9226
TEST_TIME 107 9178
TEST_TIME 108 9111
TEST_TIME 109 9125
TEST_TIME 110 9154
TEST_TIME 111 9122
TEST_TIME 112 9166
TEST_TIME 113 9169
TEST_TIME 114 9176
TEST_TIME 115 9182
TEST_TIME 116 9138
TEST_TIME 117 9157
TEST_TIME 118 9220
TEST_TIME 119 9241
TEST_TIME 120 9193

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 31-10-2021 07:48:14 to 31-10-2021 07:48:18.

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 1528005 1528005 0 0.00 0 17570 8 0 0
doc_list_biud 1528005 1528005 0 0.00 0 39021 32 0 0
sp_add_invoice_to_stock 5189 5028 161 3.10 27 193745 21124 0 161
sp_cancel_adding_invoice 5716 5716 0 0.00 6 70453 840 0 0
sp_cancel_client_order 1666 1666 0 0.00 4 17567 387 0 0
sp_cancel_customer_reserve 556872 556872 0 0.00 1 39023 69 0 0
sp_cancel_pay_from_customer 702 702 0 0.00 3 11465 96 0 0
sp_cancel_pay_to_supplier 454 454 0 0.00 3 4563 100 0 0
sp_cancel_supplier_invoice 6357 6345 12 0.18 2 22819 969 0 12
sp_cancel_supplier_order 410 410 0 0.00 6 1244648 120665 0 0
sp_cancel_write_off 9521 9521 0 0.00 1 12697 140 0 0
sp_client_order 20120 20120 0 0.00 4 27201 440 0 0
sp_customer_reserve 460609 460609 0 0.00 0 41415 210 0 0
sp_fill_shopping_cart 31159 31159 0 0.00 1 36855 41 0 0
sp_fill_shopping_cart_clo_res 460597 460597 0 0.00 0 26589 15 0 0
sp_get_clo_for_invoice 5016 5016 0 0.00 0 30722 367 0 0
sp_kill_cost_storno 16393 16393 0 0.00 0 8385 69 0 0
sp_kill_qstorno_ret_qs2qd 1130020 1130020 0 0.00 0 39012 38 0 0
sp_kill_qty_storno 595552 595552 0 0.00 0 39021 81 0 0
sp_lock_selected_doc 20843 20843 0 0.00 0 8746 4 0 0
sp_make_cost_storno 27900 27900 0 0.00 0 12591 54 0 0
sp_multiply_rows_for_pdistr 27900 27900 0 0.00 0 11287 36 0 0
sp_multiply_rows_for_qdistr 20120 20120 0 0.00 0 24294 218 0 0
sp_pay_from_customer 6983 6983 0 0.00 4 12780 102 0 0
sp_pay_to_supplier 5895 5895 0 0.00 5 11344 130 0 0
sp_payment_common 12878 12878 0 0.00 1 9116 20 0 0
sp_qd_handle_on_cancel_clo 1666 1666 0 0.00 0 17560 348 0 0
sp_qd_handle_on_invoice_upd_sts 10732 10732 0 0.00 0 17111 370 0 0
sp_qd_handle_on_reserve_upd_sts 10006 10006 0 0.00 0 26084 66 0 0
sp_reserve_write_off 10006 10006 0 0.00 7 26435 196 0 0
sp_supplier_invoice 5569 5569 0 0.00 7 87817 3630 0 0
sp_supplier_order 5491 5491 0 0.00 5 26825 1362 0 0
srv_aggregate_perf_data 114 114 0 0.00 2494 190757 16856 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1993 117513 41907 0 0
srv_find_qd_qs_mism 1054773 1054773 0 0.00 0 17569 7 0 0
srv_make_invnt_saldo 489 489 0 0.00 42 95183 3494 0 0
srv_make_money_saldo 783 783 0 0.00 1 11458 536 0 0
srv_recalc_idx_stat 5 5 0 0.00 155425 533507 348548 0 0
t$perf-abend:sp_add_invoice_to_stock 161 161 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 5029 5029 0 0.00 0 18021 202 0 0
t$perf-norm:sp_cancel_adding_invoice 409 409 0 0.00 1 2179 73 0 0
t$perf-norm:sp_cancel_client_order 1666 1666 0 0.00 0 2943 13 0 0
t$perf-norm:sp_cancel_customer_reserve 710 710 0 0.00 0 1945 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 702 702 0 0.00 0 4761 31 0 0
t$perf-norm:sp_cancel_pay_to_supplier 454 454 0 0.00 0 1212 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 429 429 0 0.00 0 879 13 0 0
t$perf-norm:sp_cancel_supplier_order 410 410 0 0.00 0 22269 1521 0 0
t$perf-norm:sp_cancel_write_off 1042 1042 0 0.00 0 3388 9 0 0
t$perf-norm:sp_client_order 20123 20123 0 0.00 0 9453 20 0 0
t$perf-norm:sp_customer_reserve 1031 1031 0 0.00 0 6073 12 0 0
t$perf-norm:sp_pay_from_customer 6983 6983 0 0.00 0 7757 7 0 0
t$perf-norm:sp_pay_to_supplier 5895 5895 0 0.00 0 8681 12 0 0
t$perf-norm:sp_reserve_write_off 10006 10006 0 0.00 0 11114 13 0 0
t$perf-norm:sp_supplier_invoice 5569 5569 0 0.00 0 10367 21 0 0
t$perf-norm:sp_supplier_order 5491 5491 0 0.00 0 4001 21 0 0
t$perf-norm:srv_aggregate_perf_data 114 114 0 0.00 0 398 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 67 2 0 0
t$perf-norm:srv_make_invnt_saldo 489 489 0 0.00 0 3601 16 0 0
t$perf-norm:srv_make_money_saldo 783 783 0 0.00 0 1359 8 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 39 11 0 0
v_add_invoice_to_stock 10352 10030 322 3.11 0 5314 19 0 322
v_all_customers 36362 36362 0 0.00 0 18 1 0 0
v_all_suppliers 30558 30558 0 0.00 0 20 1 0 0
v_all_wares 40240 40240 0 0.00 0 70 1 0 0
v_cancel_adding_invoice 818 818 0 0.00 0 1904 8 0 0
v_cancel_client_order 3332 3332 0 0.00 0 4327 8 0 0
v_cancel_customer_prepayment 1404 1404 0 0.00 0 9290 14 0 0
v_cancel_customer_reserve 1420 1420 0 0.00 0 757 4 0 0
v_cancel_payment_to_supplier 908 908 0 0.00 0 1489 6 0 0
v_cancel_supplier_invoice 882 858 24 2.72 0 838 14 0 24
v_cancel_supplier_order 820 820 0 0.00 0 2601 8 0 0
v_cancel_write_off 2084 2084 0 0.00 0 7551 24 0 0
v_max_id_clo_ord 5487 5487 0 0.00 0 384 1 0 0
v_max_id_clo_res 1031 1031 0 0.00 0 2263 13 0 0
v_max_id_ord_sup 5552 5552 0 0.00 0 4773 4 0 0
v_max_non_paid_invoice 1655 1655 0 0.00 0 585 3 0 0
v_max_non_paid_realizn 3870 3870 0 0.00 0 1943 4 0 0
v_min_id_clo_ord 5487 5487 0 0.00 0 6421 12 0 0
v_min_id_clo_res 1031 1031 0 0.00 0 23037 29 0 0
v_min_id_ord_sup 5552 5552 0 0.00 0 4928 8 0 0
v_min_non_paid_invoice 5895 5895 0 0.00 0 9111 16 0 0
v_min_non_paid_realizn 6983 6983 0 0.00 0 3296 11 0 0
v_our_firm 10104 10104 0 0.00 0 15 1 0 0
v_reserve_write_off 20012 20012 0 0.00 0 5327 7 0 0
x_lock_depdocs_on_canc_invoice 11632 11632 0 0.00 0 14169 138 0 0
x_lock_depdocs_on_canc_sup_ord 410 410 0 0.00 0 12937 359 0 0
x_make_qstorno_1000_1200 5491 5491 0 0.00 2 26815 1269 0 0
x_make_qstorno_1200_2000 5569 5569 0 0.00 3 83028 3481 0 0
x_make_qstorno_2100_3300 460609 460609 0 0.00 0 41414 191 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 31-10-2021 07:48:19 to 31-10-2021 07:48:23.

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:36 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:18:37 31216 8192000000 9723103136 10021576704 797102016 8.867 100 86 172 0 825643664 7982640 20559008
05:20:13 20494 8192000000 9761286768 10068672512 840023216 9.300 100 92 184 0 873934912 12096128 21815568
05:21:38 21943 8192000000 9799879888 10118795264 869674560 9.597 100 84 168 0 907876672 19166320 19035792
05:23:12 14433 8192000000 9868969712 10204004352 915108160 10.048 100 77 152 1 975924272 43897344 16918768
05:24:32 8133 8192000000 9929042000 10271490048 949370320 10.385 100 81 162 0 1046779104 80068960 17339824
05:25:43 4518 8192000000 9948388784 10303164416 975134864 10.637 100 74 145 1 1060908256 70874304 14899088
05:26:49 3387 8192000000 9963355088 10323742720 1008895104 10.965 100 74 148 1 1077931152 53408064 15627984
05:27:54 2742 8192000000 10005344800 10370732032 1035543056 11.222 100 83 165 2 1120416784 67691408 17182320
05:29:00 2028 8192000000 10022830656 10390937600 1057447312 11.432 100 87 174 2 1126893744 51623840 17822592
05:30:03 27990 8192000000 10014032784 10393624576 1061452544 11.470 100 71 141 0 1124515040 47410624 15651872
05:31:32 4390 8192000000 9999824064 10386616320 1070492864 11.557 100 43 86 0 1098200464 18337616 9369984
05:32:38 7551 8192000000 10002254816 10402992128 1079268816 11.641 100 37 72 1 1117935696 31408304 7258576
05:33:46 6836 8192000000 10002975008 10395119616 1084697632 11.692 100 41 80 5 1117079680 23338400 9043648
05:34:54 23174 8192000000 10019555424 10407882752 1088981152 11.733 100 61 118 2 1119746800 17963984 12801664
05:36:18 6070 8192000000 10016307984 10424266752 1094586576 11.786 100 41 83 0 1128744880 24345648 9812656
05:37:26 6889 8192000000 10013915408 10411745280 1086210320 11.707 99 39 75 0 1118652496 23441408 9000768
05:38:34 27848 8192000000 10004092224 10408452096 1066169360 11.515 97 28 56 3 1089054288 17294992 5589936
05:40:04 5761 8192000000 9959691376 10351980544 1002133056 10.899 92 41 81 2 1031244784 20659328 8452400
05:41:11 6456 8192000000 9946526352 10329305088 987951168 10.762 94 40 81 0 1011677504 15277248 8449088
05:42:19 5706 8192000000 9941390672 10323345408 1014410288 11.018 98 40 80 0 1039387760 16134048 8843424
05:43:25 11850 8192000000 9950114384 10348302336 1017944272 11.052 98 21 42 0 1050580208 28536480 4099456
05:44:42 6600 8192000000 9967951152 10350813184 1039455488 11.259 100 22 42 1 1089427200 45324880 4646832
05:45:50 6659 8192000000 9979703712 10376822784 1060737600 11.464 100 31 62 1 1089254992 21932128 6585264
05:47:00 65149 8192000000 10012827520 10400612352 1077287744 11.622 100 44 86 0 1107872128 21603552 8980832
05:49:08 4422 8192000000 10052798432 10449129472 1096313952 11.803 100 48 96 1 1158707696 51842976 10550768
05:50:14 12850 8192000000 10089312272 10491105280 1108703888 11.920 100 58 117 1 1195912720 75070160 12138672
05:51:34 31875 8192000000 10110119824 10519003136 1119032160 12.018 100 50 98 2 1223231312 93210688 10988464
05:53:15 13351 8192000000 10090256944 10500689920 1133899840 12.158 100 40 78 1 1207838640 64972368 8966432
05:54:39 10508 8192000000 10151577344 10558017536 1144477568 12.258 100 57 109 0 1249826000 93092576 12255856
05:55:59 24119 8192000000 10203095968 10623774720 1154674832 12.353 100 57 114 0 1305156064 137751456 12729776
05:57:34 4646 8192000000 10233014304 10636812288 1170674800 12.503 100 81 159 1 1331924944 143817184 17432960
05:58:42 3223 8192000000 10246388608 10662047744 1182938944 12.618 100 82 156 2 1352873648 153611216 16323488
05:59:50 3820 8192000000 10272150576 10684878848 1194615840 12.726 100 68 134 1 1377305040 169201856 13487344
06:01:00 4493 8192000000 10295216528 10707070976 1195034128 12.730 100 70 138 1 1401301088 190355312 15911648
06:02:07 2966 8192000000 10317109472 10725511168 1187628784 12.661 99 84 166 1 1412416496 206353168 18434544
06:03:17 2795 8192000000 10371608848 10787368960 1205576992 12.828 100 88 177 0 1477474032 251886304 20010736
06:04:25 1990 8192000000 10362999536 10769219584 1185171888 12.638 100 88 175 0 1458292768 253594336 19526544
06:05:34 3211 8192000000 10404336288 10815037440 1199493248 12.772 100 84 163 3 1496933552 278597584 18842720
06:06:43 3178 8192000000 10431058208 10850058240 1186894240 12.654 99 92 180 0 1524041840 316099680 21047920
06:07:50 3628 8192000000 10415376528 10851573760 1191340096 12.696 100 87 174 1 1511988320 298982992 21665232
06:09:01 5229 8192000000 10390740080 10799337472 1154373728 12.351 99 61 123 2 1478283840 309807680 14102432
06:10:15 5023 8192000000 10393115808 10822545408 1163764528 12.439 100 43 85 1 1493416752 319559600 10092624
06:11:27 6285 8192000000 10439204992 10855501824 1157762304 12.382 100 59 115 3 1539891088 368486240 13642544
06:12:54 10128 8192000000 10433209392 10889981952 1159582400 12.399 100 42 85 0 1542740512 372731392 10426720
06:14:08 18556 8192000000 10459127872 10921836544 1152595568 12.334 99 28 56 1 1548801824 388575616 7630640
06:15:28 29685 8192000000 10450545488 10903887872 1124898528 12.073 99 24 48 1 1536610096 404823552 6888016
06:16:59 36819 8192000000 10286355168 10798280704 1100793088 11.845 98 19 38 0 1408595248 302529232 5272928
06:18:51 18996 8192000000 10078246352 10512220160 1116692000 11.996 100 21 41 1 1170990784 49924896 4373888
06:20:12 18136 8192000000 10094589728 10504593408 1129450896 12.116 100 50 102 1 1198975424 59547568 9976960
06:21:40 58996 8192000000 10155173568 10576039936 1132861232 12.148 100 44 86 0 1248131456 105784016 9486208
06:23:39 117513 8192000000 10070927264 10537144320 1116512752 11.994 98 29 57 0 1170147552 47924528 5710272
06:26:38 27170 8192000000 10125607168 10550816768 1124365392 12.068 99 32 63 0 1208867968 77729072 6773504
06:28:19 16195 8192000000 10132514992 10577330176 1135800736 12.176 100 34 67 0 1220948128 77214080 7933312
06:29:45 49277 8192000000 10099769968 10584023040 1121121968 12.038 99 19 38 0 1231741136 106279776 4339392
06:31:36 45404 8192000000 10128547712 10575380480 1121905936 12.045 99 14 28 0 1214965936 90024640 3035360
06:33:34 65384 8192000000 10165743328 10625720320 1115057056 11.980 100 31 63 1 1288097136 166023760 7016320
06:36:04 26199 8192000000 10060184192 10477862912 1128484464 12.107 100 32 61 2 1149324928 15352048 5488416
06:37:36 35363 8192000000 10093144448 10545586176 1145206432 12.264 100 26 52 0 1186396656 35532704 5657520
06:39:16 76354 8192000000 10093530224 10569650176 1131229952 12.133 99 22 44 0 1181533664 44874512 5429200
06:41:33 69584 8192000000 10084846384 10541514752 1149102544 12.301 100 15 31 1 1173580352 21157392 3320416
06:43:43 40074 8192000000 10076698544 10547441664 1125846720 12.082 98 37 72 1 1175236432 41597040 7792672
06:45:24 55431 8192000000 10120631952 10614992896 1125830432 12.082 98 21 42 0 1201417568 71670512 3916624
06:47:55 44531 8192000000 10129065488 10584027136 1139687344 12.213 99 22 44 1 1177738624 33110288 4940992
06:49:40 90676 8192000000 10073908224 10522177536 1141086240 12.226 99 29 57 1 1173762736 26135600 6540896
06:52:13 42325 8192000000 10085300720 10524721152 1127380240 12.097 98 25 50 0 1167001808 34952384 4669184
06:54:21 58652 8192000000 10053970640 10496196608 1105900544 11.894 98 9 18 0 1152379568 44439904 2039120
06:56:20 51119 8192000000 10075813456 10535227392 1114257136 11.973 98 16 32 0 1173338976 55314720 3767120
06:58:27 63948 8192000000 10128538224 10563719168 1110569888 11.938 98 16 33 0 1187839824 73773408 3496528
07:01:15 56960 8192000000 10097375024 10550378496 1086653296 11.711 96 13 26 1 1172988976 83091840 3243840
07:03:17 67722 8192000000 10082418208 10528026624 1084969664 11.695 97 19 38 0 1174468992 85113792 4385536
07:05:28 36569 8192000000 10127278048 10567979008 1131824192 12.139 100 28 56 1 1202789792 65100016 5865584
07:07:07 40386 8192000000 10107641920 10537385984 1105332720 11.888 98 26 52 0 1185119088 74605184 5181184
07:08:57 99487 8192000000 10065232304 10512109568 1085920128 11.704 96 24 48 0 1143409216 53443728 4045360
07:11:39 81769 8192000000 10082137072 10532646912 1129360032 12.115 100 23 46 0 1173381024 39354144 4666848
07:14:03 56334 8192000000 10079210160 10549501952 1126502512 12.088 99 13 26 1 1197576896 68096816 2977568
07:15:59 70998 8192000000 10099289792 10553827328 1116107248 11.990 98 12 24 0 1176120304 57462448 2550608
07:18:10 92266 8192000000 10099110304 10586984448 1140854432 12.224 100 9 18 0 1257473136 114444512 2174192
07:20:52 77591 8192000000 10066207952 10502213632 1084002272 11.686 95 22 44 0 1125312576 36195968 5114336
07:23:10 54910 8192000000 10097651488 10556125184 1127556960 12.098 99 30 60 3 1180843616 47022192 6264464
07:25:17 40864 8192000000 10078524992 10521911296 1144639696 12.259 100 29 58 0 1184264016 33979040 5645280
07:26:58 78570 8192000000 10156044992 10625081344 1146980672 12.281 100 19 38 0 1224101232 73053616 4066944
07:29:20 80916 8192000000 10070841776 10503024640 1119100288 12.018 97 11 23 0 1210489920 89200112 2189520
07:31:43 101142 8192000000 10074451520 10568101888 1152019312 12.328 100 18 36 0 1203593424 48083168 3490944
07:36:14 98069 8192000000 10120354032 10603622400 1134767008 12.166 99 22 44 0 1205886912 66841888 4278016
07:39:30 84540 8192000000 10073816832 10533818368 1104229872 11.878 96 13 25 0 1178203600 71352592 2621136
07:41:55 38315 8192000000 10096361056 10541875200 1109968608 11.932 97 34 68 2 1145030336 28117360 6944368
07:43:45 31893 8192000000 10120413520 10555293696 1126197248 12.085 99 37 74 0 1209842096 76010752 7634096
07:45:18 55296 8192000000 10112298928 10620542976 1100717200 11.844 97 35 70 0 1212197280 104432256 7047824

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 31-10-2021 07:48:25 to 31-10-2021 07:48:40.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 161 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:02, from 31-10-2021 07:48:40 to 31-10-2021 07:48:42.

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          231945
MON$OLDEST_ACTIVE               231946
MON$OLDEST_SNAPSHOT             231946
MON$NEXT_TRANSACTION            231946
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                       2033568
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             798
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2649 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2649 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2649 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2649 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    Sun Oct 31 07:47:41 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Sun Oct 31 07:47:41 2021
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Sun Oct 31 07:47:41 2021
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:17: 
:18: 
:19: mscs-test-161.budzdorov.ru    Sun Oct 31 07:47:41 2021
: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'

31.10.21 07:48:43. 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-10-31 07:48:46 No severe PSQL-related problems occured
31.10.21 07:48:47. End of report.