Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 29.10.2021 07: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 15845360 kB
MemAvailable 30833244 kB
Buffers 0 kB
Cached 15834396 kB
SwapCached 4792 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-29 07:37:08
DTS_END                         2021-10-29 07:37:08.4520
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/48978
TRN_ID                          236848
ATT_ID                          691
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: 8711.26 23127 1045351
customer order: creation 172.85 351 20742
customer order: refuse 14.73 335 1768
order to supplier: creation 45.92 1380 5510
order to supplier: removal 3.12 121577 374
invoice (draft): creation 46.52 3788 5582
invoice (draft): removal 48.18 992 5781
invoice accept: apply 41.52 18813 4982
invoice accept: cancel 43.23 956 5187
customer reserve: creation 3810.28 187 457234
customer reserve: removal 4196.77 71 503612
realization accept: apply 82.63 181 9916
realization accept: cancel 77.56 131 9307
payment from customer: creation 58.72 113 7046
payment from customer: removal 5.71 105 685
payment to supplier: creation 49.25 115 5910
payment to supplier: removal 3.48 78 418
service: total inventory turnovers 3.82 3293 458
service: total monetary turnovers 6.12 616 734
service: aggregate perf. data 0.83 18523 100
service: refresh index statistics 0.04 290938 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 29-10-2021 07:38:18 to 29-10-2021 07:38:20.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9416
TEST_TIME 2 5950
TEST_TIME 3 5169
TEST_TIME 4 6294
TEST_TIME 5 6371
TEST_TIME 6 6216
TEST_TIME 7 5834
TEST_TIME 8 5720
TEST_TIME 9 5657
TEST_TIME 10 5687
TEST_TIME 11 6480
TEST_TIME 12 6408
TEST_TIME 13 6565
TEST_TIME 14 6740
TEST_TIME 15 6744
TEST_TIME 16 7009
TEST_TIME 17 7349
TEST_TIME 18 7503
TEST_TIME 19 7822
TEST_TIME 20 8194
TEST_TIME 21 8232
TEST_TIME 22 8233
TEST_TIME 23 8573
TEST_TIME 24 8625
TEST_TIME 25 8594
TEST_TIME 26 8584
TEST_TIME 27 8793
TEST_TIME 28 9584
TEST_TIME 29 10753
TEST_TIME 30 11020
TEST_TIME 31 11073
TEST_TIME 32 10955
TEST_TIME 33 10936
TEST_TIME 34 11192
TEST_TIME 35 10953
TEST_TIME 36 10669
TEST_TIME 37 10603
TEST_TIME 38 10387
TEST_TIME 39 10255
TEST_TIME 40 10188
TEST_TIME 41 10038
TEST_TIME 42 9907
TEST_TIME 43 9871
TEST_TIME 44 9803
TEST_TIME 45 9669
TEST_TIME 46 9471
TEST_TIME 47 9577
TEST_TIME 48 9530
TEST_TIME 49 9457
TEST_TIME 50 9379
TEST_TIME 51 9302
TEST_TIME 52 9227
TEST_TIME 53 9121
TEST_TIME 54 9072
TEST_TIME 55 9080
TEST_TIME 56 9080
TEST_TIME 57 9067
TEST_TIME 58 9069
TEST_TIME 59 9099
TEST_TIME 60 9045
TEST_TIME 61 9097
TEST_TIME 62 9087
TEST_TIME 63 9055
TEST_TIME 64 9147
TEST_TIME 65 9187
TEST_TIME 66 9182
TEST_TIME 67 9232
TEST_TIME 68 9118
TEST_TIME 69 9106
TEST_TIME 70 9026
TEST_TIME 71 8995
TEST_TIME 72 8928
TEST_TIME 73 8897
TEST_TIME 74 8811
TEST_TIME 75 8922
TEST_TIME 76 8983
TEST_TIME 77 8981
TEST_TIME 78 8956
TEST_TIME 79 8964
TEST_TIME 80 8941
TEST_TIME 81 8977
TEST_TIME 82 8986
TEST_TIME 83 8982
TEST_TIME 84 8986
TEST_TIME 85 9008
TEST_TIME 86 9011
TEST_TIME 87 8951
TEST_TIME 88 8928
TEST_TIME 89 8889
TEST_TIME 90 8876
TEST_TIME 91 8886
TEST_TIME 92 8831
TEST_TIME 93 8811
TEST_TIME 94 8764
TEST_TIME 95 8728
TEST_TIME 96 8744
TEST_TIME 97 8758
TEST_TIME 98 8735
TEST_TIME 99 8804
TEST_TIME 100 8820
TEST_TIME 101 8798
TEST_TIME 102 8862
TEST_TIME 103 8871
TEST_TIME 104 8859
TEST_TIME 105 8834
TEST_TIME 106 8916
TEST_TIME 107 8921
TEST_TIME 108 8960
TEST_TIME 109 8964
TEST_TIME 110 8905
TEST_TIME 111 8871
TEST_TIME 112 8860
TEST_TIME 113 8827
TEST_TIME 114 8781
TEST_TIME 115 8756
TEST_TIME 116 8731
TEST_TIME 117 8757
TEST_TIME 118 8766
TEST_TIME 119 8786
TEST_TIME 120 8715

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 29-10-2021 07:38:21 to 29-10-2021 07:38:25.

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 1467710 1467710 0 0.00 0 17372 7 0 0
doc_list_biud 1467704 1467704 0 0.00 0 54734 31 0 0
sp_add_invoice_to_stock 5115 4982 133 2.60 15 177909 18813 0 133
sp_cancel_adding_invoice 5187 5187 0 0.00 5 60610 956 0 0
sp_cancel_client_order 1768 1768 0 0.00 5 11633 336 0 0
sp_cancel_customer_reserve 503612 503612 0 0.00 1 33759 71 0 0
sp_cancel_pay_from_customer 685 685 0 0.00 3 5326 105 0 0
sp_cancel_pay_to_supplier 418 418 0 0.00 3 3252 79 0 0
sp_cancel_supplier_invoice 5791 5781 10 0.17 2 25749 993 0 10
sp_cancel_supplier_order 374 374 0 0.00 9 1234438 121577 0 0
sp_cancel_write_off 9307 9307 0 0.00 1 18618 132 0 0
sp_client_order 20742 20742 0 0.00 4 29233 351 0 0
sp_customer_reserve 457234 457234 0 0.00 0 70083 187 0 0
sp_fill_shopping_cart 31826 31826 0 0.00 1 17352 53 0 0
sp_fill_shopping_cart_clo_res 457229 457229 0 0.00 0 12048 14 0 0
sp_get_clo_for_invoice 4974 4974 0 0.00 0 30804 327 0 0
sp_kill_cost_storno 15597 15597 0 0.00 0 6668 66 0 0
sp_kill_qstorno_ret_qs2qd 1022686 1022686 0 0.00 0 33744 39 0 0
sp_kill_qty_storno 540916 540916 0 0.00 0 54734 84 0 0
sp_lock_selected_doc 20680 20680 0 0.00 0 7645 5 0 0
sp_make_cost_storno 27846 27846 0 0.00 0 16878 54 0 0
sp_multiply_rows_for_pdistr 27846 27846 0 0.00 0 10665 30 0 0
sp_multiply_rows_for_qdistr 20742 20742 0 0.00 0 20198 158 0 0
sp_pay_from_customer 7046 7046 0 0.00 4 12144 114 0 0
sp_pay_to_supplier 5910 5910 0 0.00 5 9336 115 0 0
sp_payment_common 12956 12956 0 0.00 1 12119 20 0 0
sp_qd_handle_on_cancel_clo 1768 1768 0 0.00 0 11626 312 0 0
sp_qd_handle_on_invoice_upd_sts 10159 10159 0 0.00 0 54731 400 0 0
sp_qd_handle_on_reserve_upd_sts 9915 9915 0 0.00 0 11108 58 0 0
sp_reserve_write_off 9916 9916 0 0.00 7 17382 181 0 0
sp_supplier_invoice 5582 5582 0 0.00 8 94019 3789 0 0
sp_supplier_order 5510 5510 0 0.00 5 33908 1381 0 0
srv_aggregate_perf_data 100 100 0 0.00 2818 222610 18524 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1072 155037 46388 0 0
srv_find_qd_qs_mism 997288 997288 0 0.00 0 13613 6 0 0
srv_make_invnt_saldo 458 458 0 0.00 26 118190 3294 0 0
srv_make_money_saldo 734 734 0 0.00 1 11551 616 0 0
srv_recalc_idx_stat 5 5 0 0.00 122692 407333 290938 0 0
t$perf-abend:sp_add_invoice_to_stock 133 133 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4983 4983 0 0.00 0 9283 178 0 0
t$perf-norm:sp_cancel_adding_invoice 432 432 0 0.00 0 2292 85 0 0
t$perf-norm:sp_cancel_client_order 1768 1768 0 0.00 0 1007 11 0 0
t$perf-norm:sp_cancel_customer_reserve 655 655 0 0.00 0 3879 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 685 685 0 0.00 0 991 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 418 418 0 0.00 0 1462 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 418 418 0 0.00 0 938 19 0 0
t$perf-norm:sp_cancel_supplier_order 374 374 0 0.00 0 36815 1668 0 0
t$perf-norm:sp_cancel_write_off 1043 1043 0 0.00 0 2116 9 0 0
t$perf-norm:sp_client_order 20742 20742 0 0.00 0 8549 21 0 0
t$perf-norm:sp_customer_reserve 1006 1006 0 0.00 0 3779 13 0 0
t$perf-norm:sp_pay_from_customer 7046 7046 0 0.00 0 5264 9 0 0
t$perf-norm:sp_pay_to_supplier 5910 5910 0 0.00 0 10093 11 0 0
t$perf-norm:sp_reserve_write_off 9916 9916 0 0.00 0 7558 10 0 0
t$perf-norm:sp_supplier_invoice 5582 5582 0 0.00 0 3705 18 0 0
t$perf-norm:sp_supplier_order 5510 5510 0 0.00 0 5556 15 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 156 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 1756 32 0 0
t$perf-norm:srv_make_invnt_saldo 458 458 0 0.00 0 459 8 0 0
t$perf-norm:srv_make_money_saldo 734 734 0 0.00 0 3796 13 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 6 4 0 0
v_add_invoice_to_stock 10209 9944 265 2.59 0 4547 18 0 265
v_all_customers 37758 37758 0 0.00 0 115 1 0 0
v_all_suppliers 30522 30522 0 0.00 0 20 1 0 0
v_all_wares 41484 41484 0 0.00 0 75 1 0 0
v_cancel_adding_invoice 864 864 0 0.00 0 1122 10 0 0
v_cancel_client_order 3536 3536 0 0.00 0 2140 5 0 0
v_cancel_customer_prepayment 1370 1370 0 0.00 0 2094 11 0 0
v_cancel_customer_reserve 1310 1310 0 0.00 0 1603 7 0 0
v_cancel_payment_to_supplier 836 836 0 0.00 0 1994 7 0 0
v_cancel_supplier_invoice 856 836 20 2.33 0 587 14 0 20
v_cancel_supplier_order 748 748 0 0.00 0 1446 6 0 0
v_cancel_write_off 2086 2086 0 0.00 0 2386 19 0 0
v_max_id_clo_ord 5506 5506 0 0.00 0 2571 2 0 0
v_max_id_clo_res 1006 1006 0 0.00 0 2605 11 0 0
v_max_id_ord_sup 5578 5578 0 0.00 0 6050 7 0 0
v_max_non_paid_invoice 1733 1733 0 0.00 0 1074 3 0 0
v_max_non_paid_realizn 3670 3670 0 0.00 0 840 4 0 0
v_min_id_clo_ord 5506 5506 0 0.00 0 5543 8 0 0
v_min_id_clo_res 1006 1006 0 0.00 0 3868 11 0 0
v_min_id_ord_sup 5578 5578 0 0.00 0 6543 6 0 0
v_min_non_paid_invoice 5910 5910 0 0.00 0 3909 10 0 0
v_min_non_paid_realizn 7046 7046 0 0.00 0 7229 15 0 0
v_our_firm 10478 10478 0 0.00 0 30 1 0 0
v_reserve_write_off 19830 19830 0 0.00 0 2484 6 0 0
x_lock_depdocs_on_canc_invoice 10550 10550 0 0.00 0 12727 113 0 0
x_lock_depdocs_on_canc_sup_ord 374 374 0 0.00 0 13770 395 0 0
x_make_qstorno_1000_1200 5510 5510 0 0.00 2 33594 1291 0 0
x_make_qstorno_1200_2000 5582 5582 0 0.00 4 84195 3579 0 0
x_make_qstorno_2100_3300 457234 457234 0 0.00 0 70081 171 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-10-2021 07:38:26 to 29-10-2021 07:38:30.

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:07:12 4 8192000000 8798449840 8809664512 4478816 0.054 2 1 2 0 4578208 63616 35776
05:08:13 35078 8192000000 9697144032 9991897088 770665632 8.598 100 80 161 0 797942896 7474528 19802736
05:09:53 18604 8192000000 9748257472 10052423680 821186480 9.110 100 85 170 0 854054912 12177632 20690800
05:11:17 17035 8192000000 9793142000 10114416640 862796656 9.528 100 80 161 0 903524272 22728688 17998928
05:12:39 11234 8192000000 9869360528 10200055808 911614400 10.013 100 85 170 0 979429072 49143008 18671664
05:13:54 6804 8192000000 9913850592 10260418560 957231600 10.462 100 69 137 0 1029946432 58470944 14243888
05:15:02 4559 8192000000 9971747328 10324566016 994606640 10.826 100 80 160 0 1071706848 61138032 15962176
05:16:08 2738 8192000000 9996719760 10359427072 1017290400 11.046 100 87 171 0 1114566080 79773440 17502240
05:17:14 2368 8192000000 10018182784 10387197952 1045923232 11.322 100 84 169 1 1130669248 67191680 17554336
05:18:18 2608 8192000000 10028475904 10399289344 1061583824 11.472 100 86 173 0 1129132704 50174288 17374592
05:19:21 5221 8192000000 10004646944 10400141312 1070153840 11.554 100 47 93 1 1124015472 44147600 9714032
05:20:28 2728 8192000000 10020142000 10412941312 1080130208 11.649 100 68 136 1 1136882192 42807488 13944496
05:21:32 16942 8192000000 9993801792 10394546176 1082675792 11.673 100 52 101 1 1101133488 6885904 11571792
05:22:50 6178 8192000000 10014004800 10427224064 1087427184 11.718 100 40 79 1 1140338672 44293920 8617568
05:23:57 5989 8192000000 10038358448 10433732608 1093388512 11.775 100 42 84 1 1139274480 36921152 8964816
05:25:04 53864 8192000000 10009192688 10410848256 1097928544 11.818 100 28 54 0 1127896912 24530736 5437632
05:26:58 7974 8192000000 10010349296 10416447488 1070627152 11.558 98 31 62 0 1107438192 30731776 6079264
05:28:06 3207 8192000000 9989595824 10378899456 1067852128 11.532 99 51 103 2 1098531536 19336848 11342560
05:29:10 7993 8192000000 9968428272 10363461632 1015767568 11.031 97 37 72 1 1045225440 21388048 8069824
05:30:18 20076 8192000000 9963735408 10363351040 1033506288 11.202 98 34 66 3 1068263712 27666960 7090464
05:31:41 3364 8192000000 9949856672 10326540288 1025951552 11.129 100 46 94 0 1062801664 26058592 10791520
05:32:45 3570 8192000000 9945429504 10321756160 1014811184 11.022 99 49 99 2 1057556304 31539120 11206000
05:33:50 56272 8192000000 9953684336 10348085248 1043792704 11.301 100 30 60 2 1066770384 16447520 6530160
05:35:47 6865 8192000000 9970697520 10366111744 1057188800 11.430 100 43 86 1 1080043744 13667024 9187920
05:36:55 6900 8192000000 9972721472 10369912832 1065315024 11.507 100 41 80 2 1094480608 20227968 8937616
05:38:07 7066 8192000000 10033573104 10425344000 1095009872 11.790 100 56 112 0 1138249648 31608352 11631424
05:39:18 54791 8192000000 10047342576 10453278720 1103302128 11.869 100 45 89 3 1151278576 38585776 9390672
05:41:15 14128 8192000000 10067665264 10485018624 1120571248 12.032 100 43 85 0 1196397168 66928272 8897648
05:42:34 7288 8192000000 10110693616 10514579456 1138974480 12.206 100 61 120 0 1219331600 67603280 12753840
05:43:45 38073 8192000000 10140451648 10550099968 1146273312 12.275 100 48 96 0 1247326320 91328768 9724240
05:45:30 7723 8192000000 10179484624 10585415680 1142035520 12.235 99 57 111 0 1274131120 120159984 11935616
05:46:42 21027 8192000000 10214134704 10623991808 1158712608 12.391 100 42 83 3 1303786528 135219744 9854176
05:48:05 8486 8192000000 10247872976 10657476608 1157853440 12.383 99 57 113 1 1344253632 173246640 13153552
05:49:16 2382 8192000000 10265335632 10671341568 1172438352 12.520 100 72 144 0 1369716368 180713216 16564800
05:50:24 4634 8192000000 10293133728 10708930560 1170979200 12.506 100 64 122 4 1405976240 221464464 13532576
05:51:34 4068 8192000000 10301918192 10714083328 1161548064 12.418 99 61 121 2 1397579888 222151808 13880016
05:52:41 2530 8192000000 10346143168 10749865984 1159134000 12.395 99 69 135 0 1435625408 261306976 15184432
05:53:49 1852 8192000000 10379216768 10778300416 1155284608 12.359 100 83 163 1 1486631520 312869920 18476992
05:54:55 3045 8192000000 10388455712 10789666816 1154288128 12.350 100 77 149 1 1486706928 315028608 17390192
05:56:07 1067 8192000000 10395012112 10787061760 1138299440 12.200 100 97 194 0 1488021632 327624224 22097968
05:57:13 1981 8192000000 10376995472 10784555008 1147631200 12.287 99 61 119 1 1471298896 309577680 14090016
05:58:18 3661 8192000000 10380712688 10793467904 1157682944 12.382 100 64 127 2 1477082224 303788240 15611040
05:59:29 6277 8192000000 10429742048 10855292928 1145670592 12.269 100 56 111 0 1533743232 374522736 13549904
06:00:41 14507 8192000000 10427025664 10844123136 1167313072 12.472 100 40 80 0 1528299424 350536512 10449840
06:02:00 14260 8192000000 10490911840 10913898496 1164079760 12.441 100 33 66 0 1583514464 411017424 8417280
06:03:19 18111 8192000000 10497978704 10931826688 1137523712 12.192 99 33 66 1 1587575664 441380112 8671840
06:04:44 22416 8192000000 10471030864 10979479552 1128402400 12.106 100 26 52 1 1631189232 495351024 7435808
06:06:12 1132 8192000000 10355058256 10796523520 1116835648 11.997 100 74 146 0 1446172720 311698496 17638576
06:07:13 24107 8192000000 10066987552 10480455680 1108104128 11.914 98 35 70 0 1139141600 23864336 7173136
06:08:41 77572 8192000000 10076715648 10508873728 1101451824 11.851 97 8 16 1 1123245248 20417680 1375744
06:11:03 124842 8192000000 10061398176 10497118208 1093755632 11.778 96 11 23 0 1173458240 77253344 2449264
06:15:04 51157 8192000000 10081747216 10512719872 1140341552 12.219 100 35 69 2 1180668848 32762208 7565088
06:17:02 53117 8192000000 10077006352 10555568128 1124966560 12.074 99 7 14 0 1182119648 55729440 1423648
06:18:56 43856 8192000000 10097890224 10547507200 1145910240 12.271 100 14 28 0 1205193632 56744528 2538864
06:20:41 39231 8192000000 10103165600 10530258944 1138672064 12.203 99 24 46 1 1228736832 85345456 4719312
06:22:27 155037 8192000000 10081795696 10536087552 1147738112 12.288 99 18 35 1 1222895792 71352720 3804960
06:26:07 33613 8192000000 10065140864 10516860928 1130600224 12.127 98 21 42 0 1162612288 27426672 4585392
06:27:42 55777 8192000000 10098878416 10551058432 1146833200 12.280 100 11 21 0 1185366752 36386080 2147472
06:29:38 102883 8192000000 10103066944 10555269120 1121576592 12.042 98 22 44 2 1197057872 70620128 4861152
06:33:03 70626 8192000000 10065317888 10501885952 1105048368 11.886 97 8 15 1 1139386544 32464000 1874176
06:35:17 43885 8192000000 10069413728 10522591232 1090727760 11.750 96 16 31 1 1157343200 63518544 3096896
06:37:04 32020 8192000000 10103170272 10541064192 1125713296 12.081 99 40 80 0 1152665552 18713888 8238368
06:38:36 55523 8192000000 10060595952 10523729920 1108365600 11.917 98 21 40 1 1148354560 36163056 3825904
06:40:32 33163 8192000000 10122932672 10581590016 1108794096 11.921 98 40 81 0 1197841440 81067648 7979696
06:42:18 90468 8192000000 10052350592 10477613056 1064697520 11.501 94 23 46 0 1117436560 48248848 4490192
06:44:48 133314 8192000000 10037922960 10497515520 1092915248 11.770 97 24 48 2 1152504768 54279312 5310208
06:48:15 76306 8192000000 10068769600 10530115584 1131240672 12.133 100 18 36 0 1190424320 55391280 3792368
06:52:38 90426 8192000000 10065129728 10542006272 1087766592 11.721 96 12 24 1 1171121744 80420048 2935104
06:55:08 43760 8192000000 10074073360 10501390336 1123321664 12.058 100 28 56 0 1169394080 40319360 5753056
06:56:54 27888 8192000000 10053814016 10481614848 1104834112 11.883 98 30 60 0 1127903136 16997488 6071536
06:58:24 33013 8192000000 10054828000 10504912896 1114156960 11.972 99 36 70 1 1159417216 37676928 7583328
06:59:58 65339 8192000000 10080908880 10533462016 1120290720 12.030 99 25 49 1 1179495488 54211680 4993088
07:02:07 72330 8192000000 10131456512 10625056768 1131256688 12.133 100 14 28 0 1219661008 85661392 2742928
07:04:20 110843 8192000000 10082444832 10514698240 1128519264 12.107 99 32 64 1 1190296064 54969792 6807008
07:07:18 69821 8192000000 10067751856 10555879424 1137529664 12.192 100 8 16 0 1194282176 54800096 1952416
07:09:40 78234 8192000000 10038664880 10497843200 1096809184 11.807 97 12 23 2 1130953456 31372848 2771424
07:12:00 76229 8192000000 10072975952 10536341504 1115200128 11.982 99 14 28 0 1172583120 54401520 2981472
07:14:17 50889 8192000000 10093502176 10549649408 1131729568 12.138 99 18 36 1 1194779904 59201232 3849104
07:16:09 60420 8192000000 10060065472 10519924736 1125074672 12.075 99 20 40 0 1199347936 70509200 3764064
07:18:09 44042 8192000000 10100513344 10536071168 1145616368 12.268 100 31 62 2 1210234544 58237056 6381120
07:19:56 62284 8192000000 10086023488 10546221056 1119636944 12.024 98 30 58 2 1160029392 34514000 5878448
07:22:05 22480 8192000000 10082807488 10509910016 1110350256 11.936 98 46 91 0 1169053184 49661296 9041632
07:23:29 103144 8192000000 10103272352 10594467840 1116436816 11.993 98 30 60 0 1218105136 95524944 6143376
07:26:13 145077 8192000000 10069523920 10520227840 1071828544 11.570 94 20 40 0 1103448880 27704560 3915776
07:30:02 54210 8192000000 10070847248 10556710912 1112687456 11.958 99 13 26 0 1179957616 64971776 2298384
07:31:57 59543 8192000000 10071886752 10526269440 1105689712 11.892 98 11 22 0 1157751728 49532144 2529872
07:33:57 43879 8192000000 10065600960 10551091200 1106222192 11.897 99 11 22 1 1162910816 54222096 2466528
07:36:01 51417 8192000000 10038931280 10489294848 1077839168 11.627 96 19 38 1 1124356160 42451520 4065472

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 29-10-2021 07:38:31 to 29-10-2021 07:38:46.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 133 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 29-10-2021 07:38:47 to 29-10-2021 07:38:49.

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          236086
MON$OLDEST_ACTIVE               237463
MON$OLDEST_SNAPSHOT             237463
MON$NEXT_TRANSACTION            237463
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                       2065376
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.2646 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2646 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2646 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2646 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 ---
:58: 
:59: mscs-test-161.budzdorov.ru    Fri Oct 29 07:37:11 2021
:60:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:61: 
:62: 
:63: mscs-test-161.budzdorov.ru    Fri Oct 29 07:37:11 2021
:64:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:65: 
:66: 
:67: mscs-test-161.budzdorov.ru    Fri Oct 29 07:37:14 2021
:68:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:69: 
--- end of diff output ---

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

29.10.21 07:38:50. 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-29 07:38:53 No severe PSQL-related problems occured
29.10.21 07:38:54. End of report.