Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.02.2021 07:27
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID e0e7b46250ba4f9795ceb55e0face73f
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2020-09-25 13:42
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.00
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16452168 kB
MemAvailable 31127828 kB
Buffers 0 kB
Cached 15189564 kB
SwapCached 4640 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.0
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-02-15 07:26:56
DTS_END                         2021-02-15 07:26:56.0910
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/33946
TRN_ID                          237690
ATT_ID                          643
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: 8738.69 22266 1048643
customer order: creation 172.52 396 20702
customer order: refuse 14.26 298 1711
order to supplier: creation 45.98 1349 5518
order to supplier: removal 3.18 110972 381
invoice (draft): creation 45.30 3973 5436
invoice (draft): removal 47.16 887 5659
invoice accept: apply 41.59 17485 4991
invoice accept: cancel 43.08 909 5169
customer reserve: creation 3779.63 171 453556
customer reserve: removal 4255.08 66 510610
realization accept: apply 83.75 178 10050
realization accept: cancel 77.40 118 9288
payment from customer: creation 60.05 102 7206
payment from customer: removal 5.68 130 681
payment to supplier: creation 49.38 131 5926
payment to supplier: removal 3.68 97 442
service: total inventory turnovers 4.01 3325 481
service: total monetary turnovers 6.14 653 737
service: aggregate perf. data 0.79 20751 95
service: refresh index statistics 0.03 283331 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-02-2021 07:28:02 to 15-02-2021 07:28:04.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5477
TEST_TIME 2 3564
TEST_TIME 3 3545
TEST_TIME 4 3933
TEST_TIME 5 4369
TEST_TIME 6 4776
TEST_TIME 7 4896
TEST_TIME 8 5640
TEST_TIME 9 5736
TEST_TIME 10 5826
TEST_TIME 11 5681
TEST_TIME 12 5931
TEST_TIME 13 5982
TEST_TIME 14 6340
TEST_TIME 15 6391
TEST_TIME 16 6789
TEST_TIME 17 7042
TEST_TIME 18 7391
TEST_TIME 19 7389
TEST_TIME 20 7516
TEST_TIME 21 7632
TEST_TIME 22 7945
TEST_TIME 23 8185
TEST_TIME 24 8434
TEST_TIME 25 8767
TEST_TIME 26 9253
TEST_TIME 27 9339
TEST_TIME 28 9834
TEST_TIME 29 10471
TEST_TIME 30 10373
TEST_TIME 31 10127
TEST_TIME 32 9964
TEST_TIME 33 9840
TEST_TIME 34 9842
TEST_TIME 35 9718
TEST_TIME 36 9624
TEST_TIME 37 9562
TEST_TIME 38 9466
TEST_TIME 39 9360
TEST_TIME 40 9270
TEST_TIME 41 9277
TEST_TIME 42 9079
TEST_TIME 43 8981
TEST_TIME 44 9267
TEST_TIME 45 9383
TEST_TIME 46 9271
TEST_TIME 47 9324
TEST_TIME 48 9263
TEST_TIME 49 9256
TEST_TIME 50 9231
TEST_TIME 51 9335
TEST_TIME 52 9303
TEST_TIME 53 9194
TEST_TIME 54 9420
TEST_TIME 55 9389
TEST_TIME 56 9231
TEST_TIME 57 9143
TEST_TIME 58 9077
TEST_TIME 59 9023
TEST_TIME 60 8983
TEST_TIME 61 8875
TEST_TIME 62 8898
TEST_TIME 63 8874
TEST_TIME 64 8859
TEST_TIME 65 8850
TEST_TIME 66 8966
TEST_TIME 67 8900
TEST_TIME 68 8962
TEST_TIME 69 8923
TEST_TIME 70 8871
TEST_TIME 71 8920
TEST_TIME 72 9068
TEST_TIME 73 9096
TEST_TIME 74 9177
TEST_TIME 75 9247
TEST_TIME 76 9145
TEST_TIME 77 9094
TEST_TIME 78 9025
TEST_TIME 79 8960
TEST_TIME 80 8916
TEST_TIME 81 8884
TEST_TIME 82 8834
TEST_TIME 83 8869
TEST_TIME 84 8874
TEST_TIME 85 8853
TEST_TIME 86 8862
TEST_TIME 87 8850
TEST_TIME 88 8838
TEST_TIME 89 8858
TEST_TIME 90 8890
TEST_TIME 91 8963
TEST_TIME 92 9015
TEST_TIME 93 8971
TEST_TIME 94 8913
TEST_TIME 95 8887
TEST_TIME 96 8859
TEST_TIME 97 8828
TEST_TIME 98 8786
TEST_TIME 99 8810
TEST_TIME 100 8796
TEST_TIME 101 8847
TEST_TIME 102 8904
TEST_TIME 103 8878
TEST_TIME 104 8843
TEST_TIME 105 8917
TEST_TIME 106 8935
TEST_TIME 107 8942
TEST_TIME 108 8903
TEST_TIME 109 8952
TEST_TIME 110 8879
TEST_TIME 111 8840
TEST_TIME 112 8801
TEST_TIME 113 8762
TEST_TIME 114 8772
TEST_TIME 115 8731
TEST_TIME 116 8707
TEST_TIME 117 8693
TEST_TIME 118 8703
TEST_TIME 119 8680
TEST_TIME 120 8743

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 15-02-2021 07:28:06 to 15-02-2021 07:28:09.

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 1468265 1468265 0 0.00 0 18480 6 0 0
doc_list_biud 1468263 1468263 0 0.00 0 48517 29 0 0
sp_add_invoice_to_stock 5134 4991 143 2.78 29 182529 17485 0 143
sp_cancel_adding_invoice 5169 5169 0 0.00 5 62273 910 0 0
sp_cancel_client_order 1711 1711 0 0.00 5 5767 299 0 0
sp_cancel_customer_reserve 510610 510610 0 0.00 1 48521 67 0 0
sp_cancel_pay_from_customer 681 681 0 0.00 2 14757 130 0 0
sp_cancel_pay_to_supplier 442 442 0 0.00 3 5244 97 0 0
sp_cancel_supplier_invoice 5672 5659 13 0.22 2 18199 888 0 13
sp_cancel_supplier_order 381 381 0 0.00 9 1070411 110973 0 0
sp_cancel_write_off 9288 9288 0 0.00 1 8009 119 0 0
sp_client_order 20702 20702 0 0.00 4 33606 397 0 0
sp_customer_reserve 453556 453556 0 0.00 0 119956 171 0 0
sp_fill_shopping_cart 31652 31652 0 0.00 1 24158 55 0 0
sp_fill_shopping_cart_clo_res 453552 453552 0 0.00 0 23774 19 0 0
sp_get_clo_for_invoice 4986 4986 0 0.00 0 63373 566 0 0
sp_kill_cost_storno 15580 15580 0 0.00 0 10277 63 0 0
sp_kill_qstorno_ret_qs2qd 1036548 1036548 0 0.00 0 48492 36 0 0
sp_kill_qty_storno 547854 547854 0 0.00 0 48517 77 0 0
sp_lock_selected_doc 20730 20730 0 0.00 0 8978 7 0 0
sp_make_cost_storno 28168 28168 0 0.00 0 18474 57 0 0
sp_multiply_rows_for_pdistr 28168 28168 0 0.00 0 8669 30 0 0
sp_multiply_rows_for_qdistr 20702 20702 0 0.00 0 17972 216 0 0
sp_pay_from_customer 7206 7206 0 0.00 4 20967 102 0 0
sp_pay_to_supplier 5926 5926 0 0.00 5 15312 131 0 0
sp_payment_common 13132 13132 0 0.00 1 6587 25 0 0
sp_qd_handle_on_cancel_clo 1711 1711 0 0.00 0 3615 270 0 0
sp_qd_handle_on_invoice_upd_sts 10155 10155 0 0.00 0 14798 375 0 0
sp_qd_handle_on_reserve_upd_sts 10050 10050 0 0.00 0 7512 47 0 0
sp_reserve_write_off 10050 10050 0 0.00 7 17299 179 0 0
sp_supplier_invoice 5436 5436 0 0.00 6 101435 3973 0 0
sp_supplier_order 5518 5518 0 0.00 5 98279 1349 0 0
srv_aggregate_perf_data 95 95 0 0.00 2688 331165 20751 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1283 125843 47092 0 0
srv_find_qd_qs_mism 1000935 1000935 0 0.00 0 9850 5 0 0
srv_make_invnt_saldo 481 481 0 0.00 40 75140 3325 0 0
srv_make_money_saldo 737 737 0 0.00 1 14077 653 0 0
srv_recalc_idx_stat 4 4 0 0.00 64455 391733 283332 0 0
t$perf-abend:sp_add_invoice_to_stock 143 143 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4993 4993 0 0.00 0 6936 151 0 0
t$perf-norm:sp_cancel_adding_invoice 395 395 0 0.00 0 4722 87 0 0
t$perf-norm:sp_cancel_client_order 1711 1711 0 0.00 0 1891 9 0 0
t$perf-norm:sp_cancel_customer_reserve 712 712 0 0.00 0 683 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 681 681 0 0.00 0 657 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 442 442 0 0.00 0 1641 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 373 373 0 0.00 0 952 18 0 0
t$perf-norm:sp_cancel_supplier_order 381 381 0 0.00 0 18521 1355 0 0
t$perf-norm:sp_cancel_write_off 999 999 0 0.00 0 9276 23 0 0
t$perf-norm:sp_client_order 20702 20702 0 0.00 0 8554 17 0 0
t$perf-norm:sp_customer_reserve 1023 1023 0 0.00 0 2105 8 0 0
t$perf-norm:sp_pay_from_customer 7206 7206 0 0.00 0 4079 7 0 0
t$perf-norm:sp_pay_to_supplier 5926 5926 0 0.00 0 5622 8 0 0
t$perf-norm:sp_reserve_write_off 10050 10050 0 0.00 0 3019 8 0 0
t$perf-norm:sp_supplier_invoice 5436 5436 0 0.00 0 8790 17 0 0
t$perf-norm:sp_supplier_order 5518 5518 0 0.00 0 1801 15 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 1073 27 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 380 11 0 0
t$perf-norm:srv_make_invnt_saldo 481 481 0 0.00 0 328 5 0 0
t$perf-norm:srv_make_money_saldo 737 737 0 0.00 0 436 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 10258 9972 286 2.78 0 6485 16 0 286
v_all_customers 37702 37702 0 0.00 0 30 1 0 0
v_all_suppliers 30102 30102 0 0.00 0 30 1 0 0
v_all_wares 41404 41404 0 0.00 0 13 1 0 0
v_cancel_adding_invoice 790 790 0 0.00 0 348 7 0 0
v_cancel_client_order 3422 3422 0 0.00 0 1939 4 0 0
v_cancel_customer_prepayment 1362 1362 0 0.00 0 5125 14 0 0
v_cancel_customer_reserve 1424 1424 0 0.00 0 419 3 0 0
v_cancel_payment_to_supplier 884 884 0 0.00 0 5221 9 0 0
v_cancel_supplier_invoice 772 746 26 3.36 0 749 15 0 26
v_cancel_supplier_order 762 762 0 0.00 0 2782 10 0 0
v_cancel_write_off 1998 1998 0 0.00 0 3775 16 0 0
v_max_id_clo_ord 5518 5518 0 0.00 0 2144 2 0 0
v_max_id_clo_res 1023 1023 0 0.00 0 1170 8 0 0
v_max_id_ord_sup 5432 5432 0 0.00 0 6577 7 0 0
v_max_non_paid_invoice 1825 1825 0 0.00 0 2801 5 0 0
v_max_non_paid_realizn 3917 3917 0 0.00 0 591 3 0 0
v_min_id_clo_ord 5518 5518 0 0.00 0 2063 4 0 0
v_min_id_clo_res 1023 1023 0 0.00 0 3004 13 0 0
v_min_id_ord_sup 5432 5432 0 0.00 0 6179 8 0 0
v_min_non_paid_invoice 5926 5926 0 0.00 0 6583 22 0 0
v_min_non_paid_realizn 7206 7206 0 0.00 0 5736 14 0 0
v_our_firm 10280 10280 0 0.00 0 22 1 0 0
v_reserve_write_off 20100 20100 0 0.00 0 6048 5 0 0
x_lock_depdocs_on_canc_invoice 10455 10455 0 0.00 0 32581 126 0 0
x_lock_depdocs_on_canc_sup_ord 381 381 0 0.00 0 11098 356 0 0
x_make_qstorno_1000_1200 5518 5518 0 0.00 2 98266 1271 0 0
x_make_qstorno_1200_2000 5436 5436 0 0.00 3 96945 3744 0 0
x_make_qstorno_2100_3300 453556 453556 0 0.00 0 119954 150 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-02-2021 07:28:10 to 15-02-2021 07:28:14.

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
04:56:59 1016 8192000000 8956598528 9019285504 7904800 0.096 4 2 4 0 8069152 92864 71488
04:58:01 20261 8192000000 9717243600 10010517504 791469712 8.810 100 81 161 1 818986688 7745168 19771808
04:59:28 20169 8192000000 9764054336 10069426176 838341840 9.283 100 93 186 0 873698704 13206384 22150480
05:00:52 6467 8192000000 9782560816 10097655808 859116896 9.491 100 79 158 1 895077776 17828544 18132336
05:02:06 9679 8192000000 9861262768 10189852672 905453600 9.952 100 75 150 1 967194624 45155200 16585824
05:03:18 9875 8192000000 9912875440 10251239424 942193408 10.315 100 87 174 1 1026675760 66098000 18384352
05:04:30 5271 8192000000 9954059568 10303254528 984581648 10.729 100 77 152 2 1063262480 63134992 15545840
05:05:38 2069 8192000000 10001109696 10359345152 1015721248 11.031 100 86 172 1 1114204880 81209616 17274016
05:06:42 2197 8192000000 10028297840 10393239552 1047565232 11.337 100 90 181 1 1141718128 75220032 18932864
05:07:45 1410 8192000000 10057737456 10430484480 1073328736 11.584 100 87 174 0 1169618240 78627824 17661680
05:08:47 14298 8192000000 9998541152 10397339648 1071635600 11.568 100 37 74 2 1118630768 39097216 7897952
05:10:06 4765 8192000000 10004358592 10397679616 1084218272 11.688 100 49 98 1 1118506576 23566736 10721568
05:11:15 5268 8192000000 9999146816 10401185792 1089999296 11.743 100 33 67 0 1122800000 25266288 7534416
05:12:21 6681 8192000000 10004103200 10405871616 1097042864 11.810 100 35 73 3 1125130080 20071520 8015696
05:13:32 45098 8192000000 10011623936 10422710272 1098001408 11.819 100 39 78 2 1126292192 19218064 9072720
05:15:20 5969 8192000000 9997744000 10392371200 1093344496 11.774 99 29 58 0 1119410112 19619424 6446192
05:16:28 9322 8192000000 10013617776 10431090688 1100718880 11.844 100 41 80 4 1136890784 27003808 9168096
05:17:40 8061 8192000000 9985008032 10375553024 1027953648 11.149 96 32 64 2 1049961072 15135776 6871648
05:18:51 27988 8192000000 9937880848 10332655616 935355216 10.247 88 31 61 3 981639248 39570448 6713584
05:20:20 9008 8192000000 9924304112 10313048064 975739312 10.643 97 35 70 0 1015883488 31856560 8287616
05:21:34 10834 8192000000 9943045632 10337673216 1020630512 11.078 99 36 72 1 1043555840 14699392 8225936
05:22:47 61019 8192000000 9947814480 10346909696 1029949728 11.168 99 19 36 1 1056630608 22239440 4441440
05:24:49 8633 8192000000 9970563616 10367815680 1054853808 11.407 100 38 76 0 1072683728 9768848 8061072
05:25:58 8482 8192000000 9999189152 10408325120 1062143968 11.477 100 23 46 1 1092967136 25807936 5015232
05:27:08 15368 8192000000 10003648496 10402365440 1091287104 11.755 100 37 72 0 1114927136 15619232 8020800
05:28:26 49626 8192000000 10035273344 10436538368 1103141008 11.867 100 47 94 0 1150001120 36906016 9954096
05:30:24 24549 8192000000 10074870832 10479341568 1113300000 11.964 100 48 96 0 1183257472 60507952 9449520
05:32:00 16580 8192000000 10107246128 10509684736 1125126960 12.075 100 53 104 3 1203995136 67402816 11465360
05:33:19 16083 8192000000 10138705632 10547220480 1131883696 12.139 100 58 116 1 1244961936 101311840 11766400
05:34:43 3094 8192000000 10112262544 10528276480 1144031536 12.253 100 56 109 2 1218034512 61844144 12158832
05:35:57 9887 8192000000 10142036576 10554277888 1156392848 12.369 100 53 106 2 1246907472 79678768 10835856
05:37:10 7337 8192000000 10164721040 10572591104 1151908944 12.327 100 57 112 0 1269741920 105928000 11904976
05:38:25 1283 8192000000 10206549984 10608091136 1153289472 12.340 99 69 135 1 1306728160 139456480 13982208
05:39:29 9075 8192000000 10245115360 10649616384 1152012928 12.328 100 77 152 2 1343720864 175779296 15928640
05:40:45 4435 8192000000 10264952224 10682486784 1153867056 12.346 99 56 113 0 1358345648 191181472 13297120
05:41:57 3740 8192000000 10282439568 10688167936 1139692240 12.213 99 70 139 0 1379060416 224049888 15318288
05:43:07 3232 8192000000 10304876720 10735906816 1167226112 12.471 100 74 149 0 1411041936 227474512 16341312
05:44:18 7250 8192000000 10240361808 10668331008 1122243296 12.048 100 74 147 0 1366449552 227147568 17058688
05:45:29 4155 8192000000 10323477824 10724048896 1138136432 12.198 100 73 144 1 1421137472 266804400 16196640
05:46:36 2439 8192000000 10374368112 10780246016 1146973824 12.281 100 89 179 0 1471959328 303840816 21144688
05:47:42 2386 8192000000 10390034512 10800807936 1141267280 12.227 100 65 129 0 1489762032 332342224 16152528
05:48:49 6259 8192000000 10399222240 10809184256 1145258656 12.265 99 55 109 1 1496979280 338712864 13007760
05:50:02 7102 8192000000 10401087360 10859933696 1159150640 12.395 100 40 80 2 1523055328 352583888 11320800
05:51:16 11786 8192000000 10378413152 10813956096 1150706528 12.316 100 39 78 0 1489521472 328700608 10114336
05:52:37 25686 8192000000 10290291568 10741194752 1112597232 11.957 97 20 40 0 1349625584 231964560 5063792
05:54:33 45511 8192000000 10203609920 10695188480 1103628016 11.872 99 11 22 0 1282672128 176163680 2880432
05:56:21 76318 8192000000 10059770352 10492260352 1123700112 12.062 100 20 38 0 1165396384 37149040 4547232
05:58:41 41171 8192000000 10051712016 10488815616 1109655200 11.929 98 33 65 1 1165693360 49266400 6771760
06:00:25 56766 8192000000 10065539328 10512351232 1123879616 12.064 100 9 18 0 1159940944 34472336 1588992
06:02:22 76473 8192000000 10073571008 10517237760 1136000032 12.178 100 14 28 2 1166472064 27222336 3249696
06:04:45 69845 8192000000 10117407312 10554298368 1136359456 12.181 99 16 32 1 1207047552 67243840 3444256
06:06:55 90889 8192000000 10129920496 10616147968 1151675440 12.325 100 17 35 0 1264024912 108359264 3990208
06:09:44 52924 8192000000 10117915568 10665287680 1138094336 12.198 99 11 22 1 1237102016 96407072 2600608
06:11:39 79516 8192000000 10121918160 10576011264 1150606736 12.315 100 30 59 2 1217452656 60792592 6053328
06:14:20 112994 8192000000 10082297056 10558779392 1093419296 11.775 95 19 38 1 1147295104 49928448 3947360
06:17:13 59332 8192000000 10064676704 10520453120 1140001376 12.216 99 22 44 0 1181757216 36806400 4949440
06:19:14 40737 8192000000 10161004048 10607546368 1141992848 12.234 99 41 82 1 1239327600 88623440 8711312
06:20:55 125843 8192000000 10064802592 10522873856 1113633584 11.967 97 8 16 1 1146729040 31291408 1804048
06:24:01 29954 8192000000 10029322032 10493399040 1104409728 11.879 98 10 20 1 1139794240 33091520 2292992
06:25:33 68723 8192000000 10075400224 10537635840 1129404848 12.116 99 10 20 0 1163696384 32668736 1622800
06:27:59 47954 8192000000 10067814032 10532663296 1097497056 11.814 96 8 16 1 1138613216 39942224 1173936
06:30:21 24806 8192000000 10096657488 10536574976 1133027104 12.150 99 18 36 1 1180078016 43511056 3539856
06:31:54 62412 8192000000 10072985936 10567806976 1104467632 11.880 97 19 38 2 1156804016 48033472 4302912
06:34:03 59069 8192000000 10068428848 10524459008 1094920032 11.789 97 23 45 1 1152813056 52669616 5223408
06:36:19 81637 8192000000 10178427392 10687643648 1117677712 12.005 98 27 54 0 1306246496 182148576 6420208
06:38:42 59883 8192000000 10112378304 10553634816 1097724336 11.816 97 30 60 1 1171776496 68116624 5935536
06:40:48 102111 8192000000 10060791152 10539311104 1107710640 11.911 98 17 34 0 1190329088 79175760 3442688
06:44:03 58905 8192000000 10044090304 10500341760 1111276624 11.945 98 9 18 0 1142476000 29593520 1605856
06:46:02 34901 8192000000 10046330160 10518437888 1117202544 12.001 100 6 10 0 1146921888 28773168 946176
06:50:07 44405 8192000000 10066278848 10517856256 1112113104 11.952 98 29 58 0 1141254000 23151456 5989440
06:51:56 50338 8192000000 10066725952 10529984512 1120921520 12.036 99 26 53 1 1181274880 54812736 5540624
06:53:53 38763 8192000000 10101674992 10560163840 1122854768 12.054 99 32 63 2 1184389376 54832208 6702400
06:55:33 101584 8192000000 10097258736 10618380288 1142410352 12.238 100 14 28 1 1225603296 80750960 2441984
06:58:50 54985 8192000000 10067332512 10498588672 1090585984 11.748 96 23 46 1 1125013120 30002624 4424512
07:00:45 80922 8192000000 10067644160 10528587776 1140012336 12.216 100 19 37 1 1194360288 50039904 4308048
07:03:06 61553 8192000000 10094302304 10548236288 1127428736 12.097 98 7 14 0 1160939936 32294768 1216432
07:05:13 78798 8192000000 10132393680 10612838400 1131959120 12.140 98 22 45 0 1234905200 97840576 5105504
07:07:32 89401 8192000000 10083754944 10604101632 1124983632 12.074 98 6 12 1 1183314576 57244880 1086064
07:10:02 83847 8192000000 10126048960 10596069376 1080238880 11.650 94 19 38 0 1122627536 38846640 3542016
07:12:29 66237 8192000000 10087463632 10541842432 1090199056 11.745 96 7 14 0 1131291920 39618624 1474240
07:14:36 27092 8192000000 10148512016 10611056640 1136129488 12.179 100 40 78 3 1211073296 67062384 7881424
07:16:13 104669 8192000000 10074461584 10513702912 1107414672 11.908 97 26 52 1 1155859952 41975024 6470256
07:19:04 68526 8192000000 10091923024 10559127552 1121147584 12.038 99 8 16 1 1194648912 71998288 1503040
07:21:12 79840 8192000000 10101328976 10549891072 1095592528 11.796 96 24 49 0 1151692944 50661376 5439040
07:23:33 57082 8192000000 10101973664 10571005952 1106649536 11.901 98 22 44 1 1171865104 60445456 4770112
07:25:39 41505 8192000000 10103591584 10549612544 1123568032 12.061 100 36 70 3 1212416128 80002192 8845904

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 143 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 15-02-2021 07:28:32 to 15-02-2021 07:28:34.

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          236769
MON$OLDEST_ACTIVE               240190
MON$OLDEST_SNAPSHOT             240190
MON$NEXT_TRANSACTION            240190
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                       2106112
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             811
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

Database statistics

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

Database validation

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

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

--- start of diff output ---
:462: 
:463: mscs-test-161.budzdorov.ru    Mon Feb 15 07:27:01 2021
:464:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:465: 
:466: 
:467: mscs-test-161.budzdorov.ru    Mon Feb 15 07:27:10 2021
:468:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:469: 
:470: 
:471: mscs-test-161.budzdorov.ru    Mon Feb 15 07:27:10 2021
:472:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:473: 
:474: 
:475: mscs-test-161.budzdorov.ru    Mon Feb 15 07:27:10 2021
:476:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:477: 
--- end of diff output ---

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

15.02.21 07:28:35. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2021-02-15 07:28:37 No severe PSQL-related problems occured
15.02.21 07:28:39. End of report.