Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.01.2021 10:30
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 16979116 kB
MemAvailable 31335528 kB
Buffers 0 kB
Cached 14649864 kB
SwapCached 4864 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-01-07 10:26:56
DTS_END                         2021-01-07 10:26:56.6660
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/58966
TRN_ID                          215388
ATT_ID                          608
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: 8763.71 24552 1051645
customer order: creation 154.91 470 18589
customer order: refuse 13.50 348 1620
order to supplier: creation 40.47 1540 4856
order to supplier: removal 3.38 142518 405
invoice (draft): creation 40.79 3839 4895
invoice (draft): removal 52.70 1076 6324
invoice accept: apply 36.90 19156 4428
invoice accept: cancel 47.30 958 5676
customer reserve: creation 3450.61 181 414073
customer reserve: removal 4657.43 81 558892
realization accept: apply 74.27 200 8912
realization accept: cancel 77.81 126 9337
payment from customer: creation 53.41 109 6409
payment from customer: removal 4.86 117 583
payment to supplier: creation 42.88 141 5146
payment to supplier: removal 3.03 111 364
service: total inventory turnovers 3.36 3910 403
service: total monetary turnovers 5.39 574 647
service: aggregate perf. data 0.68 17033 82
service: refresh index statistics 0.03 298546 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 07-01-2021 10:30:22 to 07-01-2021 10:30:24.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1773
TEST_TIME 2 2410
TEST_TIME 3 3705
TEST_TIME 4 4157
TEST_TIME 5 4357
TEST_TIME 6 4626
TEST_TIME 7 4536
TEST_TIME 8 4628
TEST_TIME 9 4639
TEST_TIME 10 4699
TEST_TIME 11 4755
TEST_TIME 12 5345
TEST_TIME 13 5705
TEST_TIME 14 5786
TEST_TIME 15 5794
TEST_TIME 16 5973
TEST_TIME 17 6604
TEST_TIME 18 6689
TEST_TIME 19 6799
TEST_TIME 20 7092
TEST_TIME 21 7372
TEST_TIME 22 7553
TEST_TIME 23 8022
TEST_TIME 24 8591
TEST_TIME 25 8734
TEST_TIME 26 9115
TEST_TIME 27 9757
TEST_TIME 28 11008
TEST_TIME 29 11102
TEST_TIME 30 10825
TEST_TIME 31 10689
TEST_TIME 32 10622
TEST_TIME 33 10411
TEST_TIME 34 10445
TEST_TIME 35 10282
TEST_TIME 36 10144
TEST_TIME 37 9954
TEST_TIME 38 9838
TEST_TIME 39 9948
TEST_TIME 40 9765
TEST_TIME 41 9665
TEST_TIME 42 9527
TEST_TIME 43 9337
TEST_TIME 44 9296
TEST_TIME 45 9425
TEST_TIME 46 9524
TEST_TIME 47 9396
TEST_TIME 48 9363
TEST_TIME 49 9298
TEST_TIME 50 9255
TEST_TIME 51 9180
TEST_TIME 52 9130
TEST_TIME 53 9076
TEST_TIME 54 9128
TEST_TIME 55 9111
TEST_TIME 56 9085
TEST_TIME 57 9048
TEST_TIME 58 9117
TEST_TIME 59 9112
TEST_TIME 60 9083
TEST_TIME 61 9081
TEST_TIME 62 9158
TEST_TIME 63 9194
TEST_TIME 64 9133
TEST_TIME 65 9087
TEST_TIME 66 8962
TEST_TIME 67 8984
TEST_TIME 68 8919
TEST_TIME 69 8842
TEST_TIME 70 8798
TEST_TIME 71 8809
TEST_TIME 72 8803
TEST_TIME 73 8803
TEST_TIME 74 8751
TEST_TIME 75 8830
TEST_TIME 76 8802
TEST_TIME 77 8761
TEST_TIME 78 8904
TEST_TIME 79 8917
TEST_TIME 80 9061
TEST_TIME 81 9100
TEST_TIME 82 9127
TEST_TIME 83 9114
TEST_TIME 84 9086
TEST_TIME 85 8989
TEST_TIME 86 8952
TEST_TIME 87 8884
TEST_TIME 88 8800
TEST_TIME 89 8742
TEST_TIME 90 8714
TEST_TIME 91 8786
TEST_TIME 92 8734
TEST_TIME 93 8763
TEST_TIME 94 8763
TEST_TIME 95 8824
TEST_TIME 96 8802
TEST_TIME 97 8878
TEST_TIME 98 8893
TEST_TIME 99 8893
TEST_TIME 100 8871
TEST_TIME 101 8918
TEST_TIME 102 8926
TEST_TIME 103 8931
TEST_TIME 104 8905
TEST_TIME 105 8839
TEST_TIME 106 8818
TEST_TIME 107 8763
TEST_TIME 108 8778
TEST_TIME 109 8737
TEST_TIME 110 8740
TEST_TIME 111 8725
TEST_TIME 112 8711
TEST_TIME 113 8809
TEST_TIME 114 8809
TEST_TIME 115 8784
TEST_TIME 116 8788
TEST_TIME 117 8842
TEST_TIME 118 8822
TEST_TIME 119 8825
TEST_TIME 120 8767

Performance per minute, chart


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

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 1435357 1435357 0 0.00 0 13474 7 0 0
doc_list_biud 1435357 1435357 0 0.00 0 53293 39 0 0
sp_add_invoice_to_stock 4493 4428 65 1.44 33 167203 19156 0 65
sp_cancel_adding_invoice 5676 5676 0 0.00 4 72740 958 0 0
sp_cancel_client_order 1620 1620 0 0.00 4 9294 348 0 0
sp_cancel_customer_reserve 558892 558892 0 0.00 1 22489 82 0 0
sp_cancel_pay_from_customer 583 583 0 0.00 2 6037 118 0 0
sp_cancel_pay_to_supplier 364 364 0 0.00 3 4249 111 0 0
sp_cancel_supplier_invoice 6329 6324 5 0.07 1 53297 1076 0 5
sp_cancel_supplier_order 405 405 0 0.00 11 1256299 142519 0 0
sp_cancel_write_off 9337 9337 0 0.00 1 14974 126 0 0
sp_client_order 18589 18589 0 0.00 4 20589 470 0 0
sp_customer_reserve 414073 414073 0 0.00 0 50782 182 0 0
sp_fill_shopping_cart 28337 28337 0 0.00 1 24323 46 0 0
sp_fill_shopping_cart_clo_res 414070 414070 0 0.00 0 27104 19 0 0
sp_get_clo_for_invoice 4424 4424 0 0.00 0 73201 559 0 0
sp_kill_cost_storno 15960 15960 0 0.00 0 7032 69 0 0
sp_kill_qstorno_ret_qs2qd 1133850 1133850 0 0.00 0 53292 44 0 0
sp_kill_qty_storno 595590 595590 0 0.00 0 53292 94 0 0
sp_lock_selected_doc 18538 18538 0 0.00 0 11390 7 0 0
sp_make_cost_storno 24891 24891 0 0.00 0 13326 58 0 0
sp_multiply_rows_for_pdistr 24891 24891 0 0.00 0 11611 36 0 0
sp_multiply_rows_for_qdistr 18589 18589 0 0.00 0 20090 268 0 0
sp_pay_from_customer 6409 6409 0 0.00 4 11624 110 0 0
sp_pay_to_supplier 5146 5146 0 0.00 5 11634 142 0 0
sp_payment_common 11555 11555 0 0.00 1 8804 22 0 0
sp_qd_handle_on_cancel_clo 1620 1620 0 0.00 0 6489 317 0 0
sp_qd_handle_on_invoice_upd_sts 10100 10100 0 0.00 0 23180 453 0 0
sp_qd_handle_on_reserve_upd_sts 8912 8912 0 0.00 0 19396 66 0 0
sp_reserve_write_off 8912 8912 0 0.00 7 19411 200 0 0
sp_supplier_invoice 4895 4895 0 0.00 7 109343 3840 0 0
sp_supplier_order 4856 4856 0 0.00 6 107844 1540 0 0
srv_aggregate_perf_data 82 82 0 0.00 2791 132978 17034 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1475 135600 45205 0 0
srv_find_qd_qs_mism 1009225 1009225 0 0.00 0 12231 5 0 0
srv_make_invnt_saldo 403 403 0 0.00 34 124093 3910 0 0
srv_make_money_saldo 647 647 0 0.00 1 18999 575 0 0
srv_recalc_idx_stat 4 4 0 0.00 159317 415866 298547 0 0
t$perf-abend:sp_add_invoice_to_stock 65 65 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4428 4428 0 0.00 0 12253 214 0 0
t$perf-norm:sp_cancel_adding_invoice 369 369 0 0.00 0 4296 110 0 0
t$perf-norm:sp_cancel_client_order 1620 1620 0 0.00 0 5944 16 0 0
t$perf-norm:sp_cancel_customer_reserve 649 649 0 0.00 0 1304 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 583 583 0 0.00 0 6427 31 0 0
t$perf-norm:sp_cancel_pay_to_supplier 364 364 0 0.00 0 239 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 354 354 0 0.00 0 2084 23 0 0
t$perf-norm:sp_cancel_supplier_order 405 405 0 0.00 0 20161 1765 0 0
t$perf-norm:sp_cancel_write_off 858 858 0 0.00 0 1479 7 0 0
t$perf-norm:sp_client_order 18589 18589 0 0.00 0 10748 22 0 0
t$perf-norm:sp_customer_reserve 925 925 0 0.00 0 2758 13 0 0
t$perf-norm:sp_pay_from_customer 6409 6409 0 0.00 0 8635 14 0 0
t$perf-norm:sp_pay_to_supplier 5146 5146 0 0.00 0 10307 15 0 0
t$perf-norm:sp_reserve_write_off 8912 8912 0 0.00 0 9774 19 0 0
t$perf-norm:sp_supplier_invoice 4895 4895 0 0.00 0 4835 18 0 0
t$perf-norm:sp_supplier_order 4856 4856 0 0.00 0 5383 21 0 0
t$perf-norm:srv_aggregate_perf_data 82 82 0 0.00 0 186 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 9 1 0 0
t$perf-norm:srv_make_invnt_saldo 403 403 0 0.00 0 2128 14 0 0
t$perf-norm:srv_make_money_saldo 647 647 0 0.00 0 830 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 3 0 0
v_add_invoice_to_stock 8978 8848 130 1.44 0 9505 17 0 130
v_all_customers 34322 34322 0 0.00 0 129 1 0 0
v_all_suppliers 27302 27302 0 0.00 0 19 1 0 0
v_all_wares 37178 37178 0 0.00 0 156 1 0 0
v_cancel_adding_invoice 738 738 0 0.00 0 253 6 0 0
v_cancel_client_order 3240 3240 0 0.00 0 2027 4 0 0
v_cancel_customer_prepayment 1166 1166 0 0.00 0 4013 8 0 0
v_cancel_customer_reserve 1298 1298 0 0.00 0 1129 4 0 0
v_cancel_payment_to_supplier 728 728 0 0.00 0 2718 9 0 0
v_cancel_supplier_invoice 718 708 10 1.39 0 1594 14 0 10
v_cancel_supplier_order 810 810 0 0.00 0 668 4 0 0
v_cancel_write_off 1716 1716 0 0.00 0 4148 15 0 0
v_max_id_clo_ord 4856 4856 0 0.00 0 116 1 0 0
v_max_id_clo_res 925 925 0 0.00 0 1271 8 0 0
v_max_id_ord_sup 4892 4892 0 0.00 0 6519 7 0 0
v_max_non_paid_invoice 1243 1243 0 0.00 0 6610 7 0 0
v_max_non_paid_realizn 3235 3235 0 0.00 0 2600 4 0 0
v_min_id_clo_ord 4856 4856 0 0.00 0 8061 12 0 0
v_min_id_clo_res 925 925 0 0.00 0 1697 3 0 0
v_min_id_ord_sup 4892 4892 0 0.00 0 6866 7 0 0
v_min_non_paid_invoice 5146 5146 0 0.00 0 6343 12 0 0
v_min_non_paid_realizn 6409 6409 0 0.00 0 4921 14 0 0
v_our_firm 9204 9204 0 0.00 0 16 1 0 0
v_reserve_write_off 17824 17824 0 0.00 0 2711 4 0 0
x_lock_depdocs_on_canc_invoice 11646 11646 0 0.00 0 11405 116 0 0
x_lock_depdocs_on_canc_sup_ord 405 405 0 0.00 0 16295 495 0 0
x_make_qstorno_1000_1200 4856 4856 0 0.00 2 107828 1453 0 0
x_make_qstorno_1200_2000 4895 4895 0 0.00 3 109329 3665 0 0
x_make_qstorno_2100_3300 414073 414073 0 0.00 0 50780 160 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-01-2021 10:30:30 to 07-01-2021 10:30:34.

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
07:57:00 9 8192000000 8796637808 8807108608 3167696 0.038 1 1 2 0 3265392 61840 35856
07:58:01 15683 8192000000 9711518912 10002980864 790697952 8.802 100 91 183 0 820830720 7474128 22658640
07:59:21 28352 8192000000 9754218624 10058059776 828748560 9.187 100 87 174 0 861002320 11205104 21048656
08:00:58 12538 8192000000 9801904528 10115112960 866488560 9.565 100 84 168 0 906654704 21222608 18943536
08:02:16 18689 8192000000 9847020720 10176471040 905309968 9.951 100 75 150 0 957437600 35792944 16334688
08:03:37 9260 8192000000 9900944240 10240901120 946116816 10.353 100 81 162 2 1016040336 52927424 16996096
08:04:49 9056 8192000000 9957501552 10309406720 985587904 10.739 100 84 168 0 1068082192 65219008 17275280
08:06:00 3123 8192000000 9980464816 10338996224 1009836992 10.974 100 89 174 2 1106334928 79405040 17092896
08:07:05 9949 8192000000 10001057776 10368401408 1036096352 11.227 100 76 152 0 1127194112 75825776 15271984
08:08:18 2359 8192000000 10034028400 10400890880 1051147360 11.372 100 95 190 0 1147554864 76098368 20309136
08:09:21 3872 8192000000 10002590656 10390585344 1062122112 11.477 100 56 111 3 1132769488 59110352 11537024
08:10:28 5293 8192000000 10003773424 10393161728 1071415232 11.566 100 40 77 3 1124717600 44972080 8330288
08:11:35 8932 8192000000 9983722368 10380460032 1073471392 11.585 100 36 71 3 1104277456 22869104 7936960
08:12:46 29673 8192000000 10020759024 10400083968 1083270640 11.679 100 82 162 1 1111136688 10054672 17811376
08:14:17 4160 8192000000 9996065728 10401828864 1089591360 11.739 100 45 89 4 1123848704 23879120 10378224
08:15:23 5253 8192000000 10015170560 10417946624 1095134896 11.791 100 43 87 2 1134023824 29725280 9163648
08:16:29 25265 8192000000 10007864336 10414551040 1085286928 11.698 99 40 80 1 1110359088 15939168 9132992
08:17:54 13085 8192000000 9997612016 10404048896 1082129536 11.668 99 41 82 2 1108586080 17016960 9439584
08:19:10 5407 8192000000 9975307328 10373304320 1053441472 11.394 99 50 100 1 1073975024 9679872 10853680
08:20:20 5948 8192000000 9937634944 10328719360 1029417760 11.163 100 38 78 1 1052327616 14516416 8393440
08:21:28 31466 8192000000 9922711360 10315862016 954856768 10.439 92 23 45 1 989275424 29697280 4721376
08:23:01 10153 8192000000 9920544272 10309255168 995006368 10.830 98 30 61 0 1012888800 11438304 6444128
08:24:11 9054 8192000000 9959262752 10353446912 1026342752 11.133 98 44 87 1 1057898832 20792304 10763776
08:25:21 43221 8192000000 9966008976 10359652352 1057429936 11.432 100 25 48 3 1077819616 14365936 6023744
08:27:05 35614 8192000000 9990371584 10385575936 1078265152 11.631 100 39 78 4 1101011504 14758240 7988112
08:28:49 14438 8192000000 10069261104 10470076416 1099019104 11.828 100 49 98 1 1179473552 70525360 9929088
08:30:08 15702 8192000000 10111191856 10511757312 1116487120 11.994 100 55 106 1 1208901024 81751392 10662512
08:31:32 26148 8192000000 10148346096 10554777600 1135801200 12.176 100 46 92 0 1247254976 101493712 9960064
08:33:03 23642 8192000000 10187654624 10598768640 1152658832 12.334 100 46 92 2 1294918160 131492816 10766512
08:34:42 6071 8192000000 10249918768 10651009024 1167047872 12.469 100 65 127 1 1355232448 173629040 14555536
08:35:54 7797 8192000000 10308773280 10720817152 1183948640 12.627 100 62 124 2 1414912064 216457984 14505440
08:37:07 6205 8192000000 10355278176 10764242944 1190966656 12.692 100 67 133 4 1454234240 247917088 15350496
08:38:18 14159 8192000000 10371015824 10789535744 1197752416 12.755 100 59 117 1 1479553184 267872304 13928464
08:39:42 1515 8192000000 10393808512 10805383168 1204632304 12.819 100 81 160 0 1497433120 275034656 17766160
08:40:50 4996 8192000000 10463054608 10878496768 1208393568 12.854 100 70 140 0 1566478400 342502064 15582768
08:42:01 1866 8192000000 10520045696 10926972928 1205718512 12.829 100 87 171 1 1623271744 396682416 20870816
08:43:10 3072 8192000000 10569243072 10999209984 1205465648 12.827 100 89 178 0 1665572288 438944400 21162240
08:44:18 6912 8192000000 10548644032 10966962176 1205632096 12.829 100 62 124 2 1645467600 424524032 15311472
08:45:29 1677 8192000000 10574961328 10981642240 1205279808 12.825 100 86 168 2 1671595216 446337552 19977856
08:46:38 1472 8192000000 10563020464 10975358976 1199759888 12.774 100 83 164 2 1663133248 443177024 20196336
08:47:43 8281 8192000000 10565482336 10996236288 1182755104 12.616 100 49 97 2 1669646720 473967520 12924096
08:48:57 9384 8192000000 10551183088 11033649152 1168530800 12.483 99 46 91 0 1643033728 462429456 12073472
08:50:10 9889 8192000000 10508241696 11021123584 1170014512 12.497 100 35 70 0 1615845056 436311296 9519248
08:51:24 10553 8192000000 10474233168 10940837888 1153049152 12.338 100 29 58 1 1574562736 413220496 8293088
08:52:36 21962 8192000000 10433461312 10928467968 1106000928 11.895 98 35 70 2 1576912496 460699424 10212144
08:53:59 34102 8192000000 10166677920 10724335616 1103301792 11.869 98 18 36 0 1327754576 219524720 4928064
08:56:04 25603 8192000000 10056778704 10482339840 1096844064 11.808 98 39 75 3 1146648080 41457712 8346304
08:57:39 50490 8192000000 10064327216 10538008576 1111109472 11.943 99 14 28 3 1152902016 39656352 2136192
08:59:41 30549 8192000000 10101352160 10534125568 1118006064 12.008 99 26 51 2 1197129856 73875792 5248000
09:01:22 34702 8192000000 10053433488 10488905728 1115318560 11.983 98 22 44 2 1143942416 24273888 4349968
09:03:06 61458 8192000000 10143531968 10582765568 1113659920 11.967 98 29 57 0 1188391168 68519488 6211760
09:06:09 79844 8192000000 10070746624 10518425600 1111199376 11.944 98 25 50 0 1168420336 52125040 5095920
09:08:30 43069 8192000000 10117569936 10554503168 1098074144 11.819 97 15 30 0 1184341856 83087216 3180496
09:10:17 58165 8192000000 10132355296 10606206976 1124754176 12.072 100 39 74 1 1258129088 125255456 8119456
09:12:46 69985 8192000000 10054778064 10498371584 1094893616 11.789 97 11 22 0 1137939920 40314784 2731520
09:15:06 60175 8192000000 10053197616 10540924928 1125317024 12.077 100 16 32 1 1185288416 56023488 3947904
09:17:12 89098 8192000000 10035909200 10480517120 1113021968 11.961 99 20 38 1 1142990848 26032640 3936240
09:19:47 43512 8192000000 10075322768 10536665088 1125182464 12.076 99 35 70 2 1173152160 41489216 6480480
09:21:34 95389 8192000000 10072535840 10521939968 1114329552 11.973 98 24 48 1 1194808208 75466528 5012128
09:24:16 39585 8192000000 10054026496 10489061376 1135170704 12.170 100 23 45 0 1170053776 30296448 4586624
09:26:00 79265 8192000000 10114758832 10545942528 1101540288 11.852 97 23 46 1 1168328288 62501088 4286912
09:28:24 132487 8192000000 10120077440 10597982208 1100257952 11.840 97 7 14 0 1131386448 30086784 1041712
09:31:43 106440 8192000000 10070257776 10535383040 1135362544 12.172 100 26 52 1 1193697616 52639376 5695696
09:34:36 54323 8192000000 10103627376 10548318208 1139216848 12.208 100 11 22 0 1183658640 42130800 2310992
09:36:32 62477 8192000000 10114297632 10603585536 1127738576 12.100 99 10 20 0 1220507840 89945760 2823504
09:38:52 50562 8192000000 10107412016 10536177664 1113147856 11.962 97 20 41 0 1192211392 73907680 5155856
09:40:48 72644 8192000000 10112520432 10595848192 1083613776 11.682 95 21 41 1 1171881776 83305888 4962112
09:43:06 42313 8192000000 10171205888 10599907328 1110687056 11.939 98 27 54 1 1238082992 121173216 6222720
09:44:50 53205 8192000000 10137837008 10600640512 1121191312 12.038 99 14 28 0 1239628880 115526592 2910976
09:46:58 46637 8192000000 10084115952 10560196608 1113543296 11.966 98 12 24 1 1185196288 69007152 2645840
09:48:51 27141 8192000000 10070721824 10478186496 1101865296 11.855 97 38 73 1 1130013360 20934928 7213136
09:50:20 113807 8192000000 10057458672 10502709248 1120803552 12.035 99 28 57 1 1156376368 30287856 5284960
09:53:27 40516 8192000000 10081226864 10534150144 1138915712 12.205 100 6 10 1 1176505616 36357856 1232048
09:55:41 69521 8192000000 10104505952 10570149888 1102622064 11.863 98 9 18 1 1182367120 77789824 1955232
09:58:45 44474 8192000000 10094534896 10561433600 1110879168 11.941 98 20 40 1 1176552928 61484416 4189344
10:01:11 77755 8192000000 10143451632 10639343616 1130721376 12.128 99 15 30 1 1225646992 91661376 3264240
10:03:30 67709 8192000000 10059197952 10517041152 1076252576 11.612 95 13 26 0 1120420048 41527808 2639664
10:05:45 85332 8192000000 10120531952 10565578752 1100635280 11.844 97 36 71 5 1140226608 32461312 7130016
10:08:58 26400 8192000000 10084030176 10507239424 1125798576 12.082 100 37 73 0 1165071376 31533616 7739184
10:10:25 135600 8192000000 10096818064 10541842432 1118471696 12.013 99 29 58 2 1183585024 58674720 6438608
10:13:47 45270 8192000000 10075805760 10581618688 1128577520 12.108 100 5 10 1 1207865360 78234944 1052896
10:15:34 68862 8192000000 10057998240 10522673152 1097017632 11.809 98 13 25 1 1131236480 31232080 2986768
10:17:48 57862 8192000000 10128433808 10578550784 1128347024 12.106 100 30 59 2 1197761536 63348192 6066320
10:20:32 79442 8192000000 10094051136 10553638912 1101202976 11.849 98 12 24 0 1141904640 38558736 2142928
10:22:52 56709 8192000000 10071497072 10548547584 1103835616 11.874 98 27 55 1 1164939264 55787584 5316064
10:24:50 47578 8192000000 10096828880 10525126656 1115444128 11.984 99 33 66 1 1158834448 37089600 6300720
10:26:41 56378 8192000000 9467927632 9813745664 570081728 6.506 50 8 13 1 613892112 42250224 1560160

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 07-01-2021 10:30:36 to 07-01-2021 10:30:50.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 65 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 07-01-2021 10:30:51 to 07-01-2021 10:30:53.

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          214846
MON$OLDEST_ACTIVE               215919
MON$OLDEST_SNAPSHOT             215919
MON$NEXT_TRANSACTION            215919
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                       2065248
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                        {8C105897-B735-40F5-7D8B-A1F76E952B78}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             770
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2317 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2317 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2317 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.2317 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 ---
:103: 
:104: mscs-test-161.budzdorov.ru    Thu Jan  7 10:27:54 2021
:105:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:106: 
--- end of diff output ---

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

07.01.21 10:30:54. 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-01-07 10:30:57 No severe PSQL-related problems occured
07.01.21 10:30:58. End of report.