Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 02.02.2021 13:28
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 16963720 kB
MemAvailable 31260524 kB
Buffers 0 kB
Cached 14745968 kB
SwapCached 4876 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-02 13:26:55
DTS_END                         2021-02-02 13:26:55.1080
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/33496
TRN_ID                          212820
ATT_ID                          620
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: 8516.39 22510 1021967
customer order: creation 150.56 447 18067
customer order: refuse 12.98 351 1557
order to supplier: creation 40.48 1289 4857
order to supplier: removal 3.13 132577 376
invoice (draft): creation 40.97 3721 4916
invoice (draft): removal 49.64 938 5957
invoice accept: apply 37.52 19174 4502
invoice accept: cancel 44.58 951 5349
customer reserve: creation 3496.13 185 419535
customer reserve: removal 4370.41 75 524449
realization accept: apply 73.03 176 8763
realization accept: cancel 83.08 116 9970
payment from customer: creation 52.60 113 6312
payment from customer: removal 4.87 62 584
payment to supplier: creation 43.25 138 5190
payment to supplier: removal 2.79 121 335
service: total inventory turnovers 3.68 3247 441
service: total monetary turnovers 5.86 586 703
service: aggregate perf. data 0.83 15181 100
service: refresh index statistics 0.03 270760 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 02-02-2021 13:28:32 to 02-02-2021 13:28:35.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2170
TEST_TIME 2 3007
TEST_TIME 3 4244
TEST_TIME 4 4789
TEST_TIME 5 4525
TEST_TIME 6 4652
TEST_TIME 7 4493
TEST_TIME 8 4611
TEST_TIME 9 4722
TEST_TIME 10 4872
TEST_TIME 11 5119
TEST_TIME 12 5532
TEST_TIME 13 5872
TEST_TIME 14 5869
TEST_TIME 15 5926
TEST_TIME 16 6066
TEST_TIME 17 5938
TEST_TIME 18 6150
TEST_TIME 19 6496
TEST_TIME 20 7033
TEST_TIME 21 7541
TEST_TIME 22 7794
TEST_TIME 23 8041
TEST_TIME 24 8810
TEST_TIME 25 9100
TEST_TIME 26 10160
TEST_TIME 27 10787
TEST_TIME 28 10714
TEST_TIME 29 10494
TEST_TIME 30 10147
TEST_TIME 31 10094
TEST_TIME 32 10065
TEST_TIME 33 10054
TEST_TIME 34 9895
TEST_TIME 35 9792
TEST_TIME 36 9644
TEST_TIME 37 9576
TEST_TIME 38 9490
TEST_TIME 39 9453
TEST_TIME 40 9369
TEST_TIME 41 9409
TEST_TIME 42 9334
TEST_TIME 43 9313
TEST_TIME 44 9307
TEST_TIME 45 9207
TEST_TIME 46 9101
TEST_TIME 47 9072
TEST_TIME 48 9042
TEST_TIME 49 8921
TEST_TIME 50 9050
TEST_TIME 51 9018
TEST_TIME 52 8996
TEST_TIME 53 9013
TEST_TIME 54 8997
TEST_TIME 55 9012
TEST_TIME 56 9054
TEST_TIME 57 9103
TEST_TIME 58 9114
TEST_TIME 59 9197
TEST_TIME 60 9213
TEST_TIME 61 9144
TEST_TIME 62 9018
TEST_TIME 63 8929
TEST_TIME 64 8939
TEST_TIME 65 8878
TEST_TIME 66 8822
TEST_TIME 67 8845
TEST_TIME 68 8783
TEST_TIME 69 8770
TEST_TIME 70 8788
TEST_TIME 71 8838
TEST_TIME 72 8823
TEST_TIME 73 8857
TEST_TIME 74 8831
TEST_TIME 75 8866
TEST_TIME 76 8818
TEST_TIME 77 8877
TEST_TIME 78 8854
TEST_TIME 79 8772
TEST_TIME 80 8787
TEST_TIME 81 8718
TEST_TIME 82 8718
TEST_TIME 83 8691
TEST_TIME 84 8753
TEST_TIME 85 8726
TEST_TIME 86 8694
TEST_TIME 87 8668
TEST_TIME 88 8717
TEST_TIME 89 8762
TEST_TIME 90 8833
TEST_TIME 91 8820
TEST_TIME 92 8830
TEST_TIME 93 8827
TEST_TIME 94 8815
TEST_TIME 95 8845
TEST_TIME 96 8880
TEST_TIME 97 8796
TEST_TIME 98 8753
TEST_TIME 99 8705
TEST_TIME 100 8641
TEST_TIME 101 8618
TEST_TIME 102 8594
TEST_TIME 103 8573
TEST_TIME 104 8653
TEST_TIME 105 8631
TEST_TIME 106 8618
TEST_TIME 107 8632
TEST_TIME 108 8605
TEST_TIME 109 8653
TEST_TIME 110 8681
TEST_TIME 111 8661
TEST_TIME 112 8680
TEST_TIME 113 8755
TEST_TIME 114 8688
TEST_TIME 115 8620
TEST_TIME 116 8611
TEST_TIME 117 8575
TEST_TIME 118 8565
TEST_TIME 119 8557
TEST_TIME 120 8520

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 02-02-2021 13:28:36 to 02-02-2021 13:28:39.

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 1408261 1408261 0 0.00 0 20110 7 0 0
doc_list_biud 1408257 1408257 0 0.00 0 55010 35 0 0
sp_add_invoice_to_stock 4614 4502 112 2.42 34 171201 19175 0 112
sp_cancel_adding_invoice 5349 5349 0 0.00 4 77298 951 0 0
sp_cancel_client_order 1557 1557 0 0.00 4 7851 352 0 0
sp_cancel_customer_reserve 524449 524449 0 0.00 1 33098 75 0 0
sp_cancel_pay_from_customer 584 584 0 0.00 3 1724 63 0 0
sp_cancel_pay_to_supplier 335 335 0 0.00 3 14486 122 0 0
sp_cancel_supplier_invoice 5969 5957 12 0.20 2 55016 938 0 12
sp_cancel_supplier_order 376 376 0 0.00 7 1278580 132577 0 0
sp_cancel_write_off 9970 9970 0 0.00 1 10420 116 0 0
sp_client_order 18067 18067 0 0.00 4 21301 448 0 0
sp_customer_reserve 419535 419535 0 0.00 0 72282 186 0 0
sp_fill_shopping_cart 27815 27815 0 0.00 1 23169 48 0 0
sp_fill_shopping_cart_clo_res 419528 419528 0 0.00 0 25709 21 0 0
sp_get_clo_for_invoice 4495 4495 0 0.00 0 46564 493 0 0
sp_kill_cost_storno 16238 16238 0 0.00 0 11185 65 0 0
sp_kill_qstorno_ret_qs2qd 1065201 1065201 0 0.00 0 55008 40 0 0
sp_kill_qty_storno 560916 560916 0 0.00 0 55009 86 0 0
sp_lock_selected_doc 18323 18323 0 0.00 0 7675 4 0 0
sp_make_cost_storno 24760 24760 0 0.00 0 12217 54 0 0
sp_multiply_rows_for_pdistr 24760 24760 0 0.00 0 13094 33 0 0
sp_multiply_rows_for_qdistr 18067 18067 0 0.00 0 21293 264 0 0
sp_pay_from_customer 6312 6312 0 0.00 4 19461 114 0 0
sp_pay_to_supplier 5190 5190 0 0.00 5 20320 138 0 0
sp_payment_common 11502 11502 0 0.00 1 12013 31 0 0
sp_qd_handle_on_cancel_clo 1557 1557 0 0.00 0 7786 320 0 0
sp_qd_handle_on_invoice_upd_sts 9844 9844 0 0.00 0 29400 432 0 0
sp_qd_handle_on_reserve_upd_sts 8763 8763 0 0.00 0 7718 55 0 0
sp_reserve_write_off 8763 8763 0 0.00 6 17253 177 0 0
sp_supplier_invoice 4916 4916 0 0.00 6 110248 3721 0 0
sp_supplier_order 4857 4857 0 0.00 5 34080 1290 0 0
srv_aggregate_perf_data 100 100 0 0.00 2222 339982 15182 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 938 164885 49755 0 0
srv_find_qd_qs_mism 978387 978387 0 0.00 0 20109 6 0 0
srv_make_invnt_saldo 441 441 0 0.00 66 119886 3248 0 0
srv_make_money_saldo 703 703 0 0.00 1 15189 586 0 0
srv_recalc_idx_stat 4 4 0 0.00 124428 513909 270761 0 0
t$perf-abend:sp_add_invoice_to_stock 112 112 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4502 4502 0 0.00 0 20813 194 0 0
t$perf-norm:sp_cancel_adding_invoice 352 352 0 0.00 0 2020 84 0 0
t$perf-norm:sp_cancel_client_order 1557 1557 0 0.00 0 2172 12 0 0
t$perf-norm:sp_cancel_customer_reserve 627 627 0 0.00 0 2780 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 584 584 0 0.00 0 2410 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 335 335 0 0.00 0 495 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 333 333 0 0.00 0 1286 27 0 0
t$perf-norm:sp_cancel_supplier_order 376 376 0 0.00 0 38932 1641 0 0
t$perf-norm:sp_cancel_write_off 901 901 0 0.00 0 2315 7 0 0
t$perf-norm:sp_client_order 18067 18067 0 0.00 0 18256 19 0 0
t$perf-norm:sp_customer_reserve 863 863 0 0.00 0 873 4 0 0
t$perf-norm:sp_pay_from_customer 6312 6312 0 0.00 0 27032 13 0 0
t$perf-norm:sp_pay_to_supplier 5190 5190 0 0.00 0 17051 10 0 0
t$perf-norm:sp_reserve_write_off 8763 8763 0 0.00 0 16351 13 0 0
t$perf-norm:sp_supplier_invoice 4916 4916 0 0.00 0 17256 22 0 0
t$perf-norm:sp_supplier_order 4857 4857 0 0.00 0 6202 17 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 622 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 192 6 0 0
t$perf-norm:srv_make_invnt_saldo 441 441 0 0.00 0 6931 29 0 0
t$perf-norm:srv_make_money_saldo 703 703 0 0.00 0 5639 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 9 5 0 0
v_add_invoice_to_stock 9214 8990 224 2.43 0 7698 18 0 224
v_all_customers 33790 33790 0 0.00 0 136 1 0 0
v_all_suppliers 26976 26976 0 0.00 0 34 1 0 0
v_all_wares 36134 36134 0 0.00 0 37 1 0 0
v_cancel_adding_invoice 704 704 0 0.00 0 2241 12 0 0
v_cancel_client_order 3114 3114 0 0.00 0 2165 6 0 0
v_cancel_customer_prepayment 1168 1168 0 0.00 0 1271 7 0 0
v_cancel_customer_reserve 1254 1254 0 0.00 0 2239 5 0 0
v_cancel_payment_to_supplier 670 670 0 0.00 0 3295 13 0 0
v_cancel_supplier_invoice 690 666 24 3.47 0 2604 22 0 24
v_cancel_supplier_order 752 752 0 0.00 0 2502 7 0 0
v_cancel_write_off 1802 1802 0 0.00 0 2239 15 0 0
v_max_id_clo_ord 4842 4842 0 0.00 0 1215 2 0 0
v_max_id_clo_res 863 863 0 0.00 0 3081 12 0 0
v_max_id_ord_sup 4905 4905 0 0.00 0 7327 5 0 0
v_max_non_paid_invoice 1449 1449 0 0.00 0 2809 4 0 0
v_max_non_paid_realizn 3052 3052 0 0.00 0 2816 4 0 0
v_min_id_clo_ord 4842 4842 0 0.00 0 9791 9 0 0
v_min_id_clo_res 863 863 0 0.00 0 2422 14 0 0
v_min_id_ord_sup 4905 4905 0 0.00 0 8385 12 0 0
v_min_non_paid_invoice 5190 5190 0 0.00 0 12009 26 0 0
v_min_non_paid_realizn 6312 6312 0 0.00 0 9180 19 0 0
v_our_firm 8864 8864 0 0.00 0 16 1 0 0
v_reserve_write_off 17526 17526 0 0.00 0 5095 6 0 0
x_lock_depdocs_on_canc_invoice 10973 10973 0 0.00 0 11638 111 0 0
x_lock_depdocs_on_canc_sup_ord 376 376 0 0.00 0 6227 366 0 0
x_make_qstorno_1000_1200 4857 4857 0 0.00 2 34059 1207 0 0
x_make_qstorno_1200_2000 4916 4916 0 0.00 3 104735 3535 0 0
x_make_qstorno_2100_3300 419535 419535 0 0.00 0 72280 162 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 02-02-2021 13:28:40 to 02-02-2021 13:28:45.

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
10:56:58 2 8192000000 8796640128 8807108608 3168304 0.038 1 1 2 0 3265920 61840 35776
10:58:00 31674 8192000000 9729333360 10027302912 801545904 8.912 100 88 176 1 831347792 7793696 22008192
10:59:37 23253 8192000000 9768558144 10074136576 841340576 9.313 100 86 172 1 873772080 12274256 20157248
11:01:07 8010 8192000000 9803946912 10121027584 866803568 9.568 100 83 166 0 914720944 28766624 19150752
11:02:23 18158 8192000000 9840830464 10174275584 900059760 9.899 100 75 150 0 949716208 32977504 16678944
11:03:47 10151 8192000000 9903581072 10243178496 941684208 10.310 100 76 152 1 1010829344 53265952 15879184
11:05:02 6724 8192000000 9957415264 10304417792 980413376 10.688 100 83 167 2 1064049040 67162448 16473216
11:06:13 3397 8192000000 9990106912 10347483136 1008903408 10.965 100 85 170 0 1107921136 81687968 17329760
11:07:19 2174 8192000000 10027495520 10392809472 1033434720 11.202 100 85 168 1 1135079232 84425968 17218544
11:08:24 2233 8192000000 10021535984 10389807104 1051031184 11.371 100 92 183 0 1137952272 67661760 19259328
11:09:28 9794 8192000000 10036006752 10410774528 1058670816 11.444 100 81 161 1 1161262912 86395008 16197088
11:10:41 7563 8192000000 9987052544 10378100736 1064208400 11.497 100 42 84 1 1115409296 42098960 9101936
11:11:50 3445 8192000000 9990007136 10384830464 1071436832 11.566 100 41 82 0 1110112576 29111792 9563952
11:12:54 3861 8192000000 9978567968 10370445312 1080192400 11.649 100 46 91 1 1096514704 7839744 8482560
11:13:59 64916 8192000000 9994674832 10405986304 1091931376 11.761 100 36 72 1 1119845024 20387824 7525824
11:16:21 7304 8192000000 10020347392 10432884736 1088636576 11.730 100 51 103 0 1140675904 40572048 11467280
11:17:31 5041 8192000000 9998269856 10388414464 1083039728 11.676 100 31 63 0 1103534512 12991136 7503648
11:18:37 8077 8192000000 9975813936 10378072064 1027003232 11.140 96 21 42 1 1066455648 35065776 4386640
11:19:48 42190 8192000000 9931734288 10324353024 953805696 10.428 90 17 34 1 981209856 23966896 3437264
11:21:32 10650 8192000000 9940665456 10332979200 1003490816 10.912 98 45 90 0 1026496336 12261296 10744224
11:22:48 6084 8192000000 9945732080 10332258304 1028062544 11.150 100 41 83 1 1061344032 23422912 9858576
11:24:01 8461 8192000000 9953562192 10344128512 1045965488 11.322 100 36 72 3 1066657696 12363088 8329120
11:25:10 27970 8192000000 9948173392 10353549312 1025031360 11.121 98 20 40 1 1066217328 36759008 4426960
11:26:39 6594 8192000000 9985215408 10382540800 1070189232 11.554 100 34 64 1 1095499504 17699728 7610544
11:27:55 23646 8192000000 10011318720 10405064704 1092845072 11.770 100 42 84 2 1120572848 18819680 8908096
11:29:22 19451 8192000000 10105552320 10505056256 1112856752 11.959 100 58 116 2 1211734320 86061024 12816544
11:30:48 48121 8192000000 10124187760 10528534528 1124582592 12.070 100 41 82 0 1237637152 104092480 8962080
11:32:43 18739 8192000000 10163447680 10571669504 1134247056 12.161 100 52 102 2 1266365408 121270688 10847664
11:34:12 15843 8192000000 10217247792 10622816256 1152448448 12.332 100 49 96 1 1330934240 167806640 10679152
11:35:34 3560 8192000000 10262027808 10665537536 1162478176 12.426 100 74 149 1 1371694656 192736448 16480032
11:36:43 2719 8192000000 10318938208 10721452032 1163782112 12.439 99 73 142 1 1427631936 248426000 15423824
11:37:54 5914 8192000000 10385975280 10786922496 1176028736 12.553 100 69 137 1 1484012608 291798144 16185728
11:39:04 2946 8192000000 10398097664 10800254976 1179215856 12.583 100 89 180 1 1500746368 302567648 18962864
11:40:11 2993 8192000000 10420753232 10823634944 1193609920 12.717 100 88 173 1 1521775888 307998000 20167968
11:41:21 3724 8192000000 10473619744 10878332928 1190394528 12.687 100 78 156 0 1572162320 363979552 17788240
11:42:33 7695 8192000000 10486487440 10901164032 1172800032 12.523 100 68 136 1 1586138512 396608560 16729920
11:43:45 6754 8192000000 10525358000 10930024448 1176467584 12.557 100 81 162 0 1623299968 427722928 19109456
11:44:56 5128 8192000000 10560130032 10960273408 1167886848 12.477 100 82 164 3 1649206112 461681536 19637728
11:46:08 936 8192000000 10548762928 10950275072 1167228672 12.471 100 89 180 0 1644609952 456046320 21334960
11:47:14 8909 8192000000 10487890272 10929127424 1172190832 12.517 100 44 87 0 1593642960 410474544 10977584
11:48:30 1646 8192000000 10517882528 10947489792 1148468368 12.295 99 90 176 0 1613749488 443825920 21455200
11:49:34 13597 8192000000 10490611808 10984128512 1154499168 12.352 100 35 70 2 1582593744 418647168 9447408
11:50:58 31484 8192000000 10415561312 10892177408 1149167696 12.302 100 29 58 0 1517960976 361029744 7763536
11:52:31 31764 8192000000 10190342976 10671407104 1120631024 12.033 99 21 41 1 1343599616 217573056 5395536
11:54:09 36047 8192000000 10096579856 10572980224 1108665824 11.920 98 7 14 0 1177529376 67379504 1484048
11:56:54 96480 8192000000 10101820848 10541449216 1093177184 11.773 97 21 42 0 1174896128 77235744 4483200
11:59:32 164885 8192000000 10048294832 10491826176 1071309664 11.565 95 13 26 1 1114380304 40754736 2315904
12:04:00 38798 8192000000 10130387408 10582728704 1136020512 12.178 100 30 60 1 1215005200 73060720 5923968
12:05:45 38783 8192000000 10110106304 10554814464 1110532960 11.937 97 27 54 0 1202010192 85531776 5945456
12:07:25 107757 8192000000 10119253504 10598633472 1130188064 12.123 100 7 14 0 1234793328 102834768 1770496
12:10:13 76153 8192000000 10083588384 10573262848 1118924624 12.017 99 15 30 0 1171251056 49264304 3062128
12:13:24 34404 8192000000 10063480640 10568019968 1121438352 12.041 100 6 11 1 1174105648 51251488 1415808
12:15:36 33137 8192000000 10119612224 10553729024 1135244384 12.171 100 47 95 1 1200477408 55244560 9988464
12:17:15 49748 8192000000 10077982896 10524352512 1138892896 12.205 100 23 46 0 1187786496 44248064 4645536
12:19:08 60085 8192000000 10070220512 10501505024 1110410544 11.936 98 22 44 1 1155682016 40818032 4453440
12:21:13 26045 8192000000 10085960976 10529738752 1109532352 11.928 98 44 88 1 1166550544 48430256 8587936
12:22:40 103442 8192000000 10113760304 10577367040 1106822720 11.902 98 15 30 0 1168523648 58958784 2742144
12:25:30 89920 8192000000 10070559120 10506035200 1086457728 11.709 96 13 24 4 1128542976 39921200 2164048
12:28:03 129790 8192000000 10086267760 10535333888 1118805664 12.016 99 20 40 0 1186703520 63910624 3987232
12:31:18 48203 8192000000 10055431776 10547019776 1128471104 12.107 100 5 10 1 1158767808 29436704 860000
12:33:07 98326 8192000000 10084413472 10539122688 1092051520 11.762 96 10 21 0 1134562016 40979392 1531104
12:35:47 60385 8192000000 10097435008 10592215040 1131358128 12.134 99 21 42 0 1209722224 73880720 4483376
12:37:54 59849 8192000000 10102403728 10554867712 1072678128 11.578 94 16 32 0 1142486064 66587168 3220768
12:39:58 23332 8192000000 10047562800 10486382592 1086674752 11.711 97 21 40 3 1118854288 27845760 4333776
12:41:25 67320 8192000000 10103659440 10548801536 1098465088 11.823 98 19 37 0 1131696480 29471536 3759856
12:44:09 99120 8192000000 10095750000 10602631168 1108464768 11.918 99 11 22 1 1194995824 84107696 2423360
12:47:23 52203 8192000000 10085140832 10566606848 1122467520 12.050 100 6 12 0 1184547888 60811952 1268416
12:49:47 84514 8192000000 10074519056 10573004800 1101955104 11.856 98 23 47 0 1180459392 73751536 4752752
12:52:30 90538 8192000000 10128021712 10570080256 1096770736 11.807 97 30 60 2 1159547888 56645184 6131968
12:55:01 104345 8192000000 10119010432 10579079168 1110677920 11.939 98 11 22 0 1195787408 82766608 2342880
12:57:46 17681 8192000000 10075077728 10497265664 1130618864 12.127 100 46 90 2 1156668512 16427312 9622336
12:59:06 47834 8192000000 10086527232 10507694080 1083702544 11.683 95 24 48 0 1126784080 37874448 5207088
13:00:55 16246 8192000000 10114175520 10537607168 1129706736 12.119 100 31 62 1 1208685872 72779664 6199472
13:02:11 139232 8192000000 10085827600 10587492352 1132339344 12.143 100 31 61 0 1207207728 69116384 5752000
13:05:32 36594 8192000000 10027140352 10469433344 1099996608 11.838 98 10 20 0 1122114480 20134176 1983696
13:07:09 53495 8192000000 10063751520 10525237248 1129885408 12.120 100 5 10 0 1168530144 37763744 880992
13:09:10 99561 8192000000 10080311472 10594615296 1088492352 11.728 96 13 26 0 1161948496 70585552 2870592
13:11:50 77973 8192000000 10111557728 10567516160 1110153536 11.934 98 23 46 0 1154340976 38972944 5214496
13:14:21 28590 8192000000 10125113264 10555088896 1134872448 12.167 100 37 74 0 1211357520 69246640 7238432
13:15:51 56780 8192000000 10083038832 10556653568 1115110912 11.981 98 16 32 2 1200558656 82222672 3225072
13:17:55 58208 8192000000 10127003216 10592784384 1105723744 11.892 97 22 44 1 1170320736 59991072 4605920
13:20:02 84841 8192000000 10055827584 10503774208 1111588032 11.947 99 13 25 2 1157205440 42351296 3266112
13:22:34 73407 8192000000 10066482592 10541162496 1131771664 12.138 100 4 8 0 1162916656 30632688 512304
13:24:48 65607 8192000000 10090041520 10544779264 1144404288 12.257 100 15 30 0 1191358768 43626000 3328480

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 02-02-2021 13:28:46 to 02-02-2021 13:29:01.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 112 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 02-02-2021 13:29:02 to 02-02-2021 13:29:04.

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          210931
MON$OLDEST_ACTIVE               213378
MON$OLDEST_SNAPSHOT             213378
MON$NEXT_TRANSACTION            213378
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                       2061024
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             759
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2357 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2357 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2357 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.2357 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 ---
--- end of diff output ---

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

02.02.21 13:29:05. 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-02 13:29:07 No severe PSQL-related problems occured
02.02.21 13:29:09. End of report.