Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.01.2021 04: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 16497168 kB
MemAvailable 31230088 kB
Buffers 20 kB
Cached 14994028 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 04:26:55
DTS_END                         2021-01-07 04:26:55.3270
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/55518
TRN_ID                          229279
ATT_ID                          678
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: 9037.83 21443 1084540
customer order: creation 167.73 401 20127
customer order: refuse 13.98 344 1678
order to supplier: creation 44.43 1505 5332
order to supplier: removal 3.39 124684 407
invoice (draft): creation 44.83 3731 5380
invoice (draft): removal 50.73 1007 6088
invoice accept: apply 41.17 18494 4940
invoice accept: cancel 46.16 913 5539
customer reserve: creation 3806.96 181 456835
customer reserve: removal 4526.73 73 543208
realization accept: apply 81.30 178 9756
realization accept: cancel 85.08 121 10210
payment from customer: creation 57.41 112 6889
payment from customer: removal 5.38 83 645
payment to supplier: creation 48.23 142 5788
payment to supplier: removal 3.38 82 405
service: total inventory turnovers 3.85 3727 462
service: total monetary turnovers 6.22 562 746
service: aggregate perf. data 0.84 15357 101
service: refresh index statistics 0.03 257166 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 07-01-2021 04:27:44 to 07-01-2021 04:27:46.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3048
TEST_TIME 2 2985
TEST_TIME 3 3272
TEST_TIME 4 4266
TEST_TIME 5 4341
TEST_TIME 6 4472
TEST_TIME 7 4712
TEST_TIME 8 4768
TEST_TIME 9 4684
TEST_TIME 10 4968
TEST_TIME 11 5004
TEST_TIME 12 5485
TEST_TIME 13 5649
TEST_TIME 14 5756
TEST_TIME 15 5796
TEST_TIME 16 6181
TEST_TIME 17 6595
TEST_TIME 18 6504
TEST_TIME 19 6950
TEST_TIME 20 7075
TEST_TIME 21 7420
TEST_TIME 22 7496
TEST_TIME 23 7438
TEST_TIME 24 7799
TEST_TIME 25 8044
TEST_TIME 26 8227
TEST_TIME 27 8346
TEST_TIME 28 8617
TEST_TIME 29 8942
TEST_TIME 30 8785
TEST_TIME 31 9511
TEST_TIME 32 10555
TEST_TIME 33 10440
TEST_TIME 34 10453
TEST_TIME 35 10585
TEST_TIME 36 10586
TEST_TIME 37 10474
TEST_TIME 38 10349
TEST_TIME 39 10197
TEST_TIME 40 9980
TEST_TIME 41 10127
TEST_TIME 42 9989
TEST_TIME 43 9969
TEST_TIME 44 9979
TEST_TIME 45 9902
TEST_TIME 46 9800
TEST_TIME 47 9698
TEST_TIME 48 9678
TEST_TIME 49 9629
TEST_TIME 50 9673
TEST_TIME 51 9751
TEST_TIME 52 9762
TEST_TIME 53 9817
TEST_TIME 54 9765
TEST_TIME 55 9692
TEST_TIME 56 9743
TEST_TIME 57 9773
TEST_TIME 58 9772
TEST_TIME 59 9831
TEST_TIME 60 9862
TEST_TIME 61 9817
TEST_TIME 62 9922
TEST_TIME 63 9911
TEST_TIME 64 9785
TEST_TIME 65 9671
TEST_TIME 66 9602
TEST_TIME 67 9544
TEST_TIME 68 9446
TEST_TIME 69 9448
TEST_TIME 70 9370
TEST_TIME 71 9369
TEST_TIME 72 9411
TEST_TIME 73 9365
TEST_TIME 74 9339
TEST_TIME 75 9308
TEST_TIME 76 9292
TEST_TIME 77 9279
TEST_TIME 78 9241
TEST_TIME 79 9258
TEST_TIME 80 9333
TEST_TIME 81 9328
TEST_TIME 82 9303
TEST_TIME 83 9307
TEST_TIME 84 9325
TEST_TIME 85 9379
TEST_TIME 86 9281
TEST_TIME 87 9216
TEST_TIME 88 9189
TEST_TIME 89 9148
TEST_TIME 90 9159
TEST_TIME 91 9131
TEST_TIME 92 9137
TEST_TIME 93 9206
TEST_TIME 94 9215
TEST_TIME 95 9207
TEST_TIME 96 9217
TEST_TIME 97 9213
TEST_TIME 98 9222
TEST_TIME 99 9235
TEST_TIME 100 9264
TEST_TIME 101 9241
TEST_TIME 102 9295
TEST_TIME 103 9260
TEST_TIME 104 9363
TEST_TIME 105 9279
TEST_TIME 106 9228
TEST_TIME 107 9204
TEST_TIME 108 9144
TEST_TIME 109 9092
TEST_TIME 110 9102
TEST_TIME 111 9080
TEST_TIME 112 9031
TEST_TIME 113 9012
TEST_TIME 114 9007
TEST_TIME 115 9026
TEST_TIME 116 8995
TEST_TIME 117 9036
TEST_TIME 118 9034
TEST_TIME 119 9025
TEST_TIME 120 9049

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 07-01-2021 04:27:48 to 07-01-2021 04:27:51.

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 1505917 1505917 0 0.00 0 19635 7 0 0
doc_list_biud 1505912 1505912 0 0.00 0 56292 33 0 0
sp_add_invoice_to_stock 5059 4940 119 2.35 33 236649 18494 0 119
sp_cancel_adding_invoice 5539 5539 0 0.00 4 58553 914 0 0
sp_cancel_client_order 1678 1678 0 0.00 4 11273 344 0 0
sp_cancel_customer_reserve 543208 543208 0 0.00 1 56294 74 0 0
sp_cancel_pay_from_customer 645 645 0 0.00 2 8117 83 0 0
sp_cancel_pay_to_supplier 405 405 0 0.00 3 5162 83 0 0
sp_cancel_supplier_invoice 6100 6088 12 0.19 2 17368 1008 0 12
sp_cancel_supplier_order 407 407 0 0.00 8 1125829 124684 0 0
sp_cancel_write_off 10210 10210 0 0.00 1 14389 122 0 0
sp_client_order 20127 20127 0 0.00 4 29199 401 0 0
sp_customer_reserve 456835 456835 0 0.00 0 129849 182 0 0
sp_fill_shopping_cart 30830 30830 0 0.00 1 14095 43 0 0
sp_fill_shopping_cart_clo_res 456826 456826 0 0.00 0 23268 16 0 0
sp_get_clo_for_invoice 4926 4926 0 0.00 0 34331 384 0 0
sp_kill_cost_storno 16799 16799 0 0.00 0 8107 61 0 0
sp_kill_qstorno_ret_qs2qd 1103121 1103121 0 0.00 0 56288 40 0 0
sp_kill_qty_storno 581812 581812 0 0.00 0 56292 85 0 0
sp_lock_selected_doc 20258 20258 0 0.00 0 8489 5 0 0
sp_make_cost_storno 27359 27359 0 0.00 0 12150 52 0 0
sp_multiply_rows_for_pdistr 27359 27359 0 0.00 0 9174 32 0 0
sp_multiply_rows_for_qdistr 20127 20127 0 0.00 0 17134 209 0 0
sp_pay_from_customer 6889 6889 0 0.00 4 21134 112 0 0
sp_pay_to_supplier 5788 5788 0 0.00 5 16401 143 0 0
sp_payment_common 12677 12677 0 0.00 1 10512 18 0 0
sp_qd_handle_on_cancel_clo 1678 1678 0 0.00 0 11265 316 0 0
sp_qd_handle_on_invoice_upd_sts 10465 10465 0 0.00 0 44354 387 0 0
sp_qd_handle_on_reserve_upd_sts 9756 9756 0 0.00 0 22861 54 0 0
sp_reserve_write_off 9756 9756 0 0.00 6 28026 178 0 0
sp_supplier_invoice 5380 5380 0 0.00 6 124109 3732 0 0
sp_supplier_order 5332 5332 0 0.00 5 34016 1505 0 0
srv_aggregate_perf_data 101 101 0 0.00 3059 156667 15358 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1470 143951 46358 0 0
srv_find_qd_qs_mism 1037003 1037003 0 0.00 0 15114 6 0 0
srv_make_invnt_saldo 462 462 0 0.00 36 105807 3728 0 0
srv_make_money_saldo 746 746 0 0.00 1 17678 563 0 0
srv_recalc_idx_stat 4 4 0 0.00 83978 434899 257166 0 0
t$perf-abend:sp_add_invoice_to_stock 119 119 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 4940 4940 0 0.00 0 16293 159 0 0
t$perf-norm:sp_cancel_adding_invoice 418 418 0 0.00 0 5218 115 0 0
t$perf-norm:sp_cancel_client_order 1678 1678 0 0.00 0 4249 13 0 0
t$perf-norm:sp_cancel_customer_reserve 690 690 0 0.00 0 1465 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 645 645 0 0.00 0 1942 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 405 405 0 0.00 0 2376 18 0 0
t$perf-norm:sp_cancel_supplier_invoice 386 386 0 0.00 0 3179 36 0 0
t$perf-norm:sp_cancel_supplier_order 407 407 0 0.00 0 16422 1667 0 0
t$perf-norm:sp_cancel_write_off 947 947 0 0.00 0 1129 6 0 0
t$perf-norm:sp_client_order 20127 20127 0 0.00 0 6652 21 0 0
t$perf-norm:sp_customer_reserve 957 957 0 0.00 0 1937 9 0 0
t$perf-norm:sp_pay_from_customer 6889 6889 0 0.00 0 10779 7 0 0
t$perf-norm:sp_pay_to_supplier 5788 5788 0 0.00 0 11157 15 0 0
t$perf-norm:sp_reserve_write_off 9756 9756 0 0.00 0 15432 10 0 0
t$perf-norm:sp_supplier_invoice 5380 5380 0 0.00 0 11311 19 0 0
t$perf-norm:sp_supplier_order 5332 5332 0 0.00 0 11381 19 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 1668 42 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 864 14 0 0
t$perf-norm:srv_make_invnt_saldo 462 462 0 0.00 0 1422 14 0 0
t$perf-norm:srv_make_money_saldo 746 746 0 0.00 0 762 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 10090 9852 238 2.35 0 10447 16 0 238
v_all_customers 36946 36946 0 0.00 0 49 1 0 0
v_all_suppliers 29842 29842 0 0.00 0 17 1 0 0
v_all_wares 40254 40254 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 836 836 0 0.00 0 303 5 0 0
v_cancel_client_order 3356 3356 0 0.00 0 1227 5 0 0
v_cancel_customer_prepayment 1290 1290 0 0.00 0 255 4 0 0
v_cancel_customer_reserve 1380 1380 0 0.00 0 7282 10 0 0
v_cancel_payment_to_supplier 810 810 0 0.00 0 5137 15 0 0
v_cancel_supplier_invoice 796 772 24 3.01 0 1435 17 0 24
v_cancel_supplier_order 814 814 0 0.00 0 881 6 0 0
v_cancel_write_off 1894 1894 0 0.00 0 14146 25 0 0
v_max_id_clo_ord 5325 5325 0 0.00 0 837 1 0 0
v_max_id_clo_res 957 957 0 0.00 0 6049 16 0 0
v_max_id_ord_sup 5378 5378 0 0.00 0 9181 7 0 0
v_max_non_paid_invoice 1559 1559 0 0.00 0 2114 4 0 0
v_max_non_paid_realizn 3516 3516 0 0.00 0 3415 4 0 0
v_min_id_clo_ord 5325 5325 0 0.00 0 9773 13 0 0
v_min_id_clo_res 957 957 0 0.00 0 576 3 0 0
v_min_id_ord_sup 5378 5378 0 0.00 0 9729 17 0 0
v_min_non_paid_invoice 5788 5788 0 0.00 0 3576 10 0 0
v_min_non_paid_realizn 6889 6889 0 0.00 0 8061 12 0 0
v_our_firm 10054 10054 0 0.00 0 96 1 0 0
v_reserve_write_off 19512 19512 0 0.00 0 14140 7 0 0
x_lock_depdocs_on_canc_invoice 11241 11241 0 0.00 0 17800 118 0 0
x_lock_depdocs_on_canc_sup_ord 407 407 0 0.00 0 13520 424 0 0
x_make_qstorno_1000_1200 5332 5332 0 0.00 2 33986 1416 0 0
x_make_qstorno_1200_2000 5380 5380 0 0.00 3 123596 3570 0 0
x_make_qstorno_2100_3300 456835 456835 0 0.00 0 129847 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-01-2021 04:27:52 to 07-01-2021 04:27:56.

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
01:56:59 22 8192000000 8803405024 8816418816 5992736 0.073 3 1 2 0 6094016 65424 35856
01:58:00 27561 8192000000 9727068720 10022748160 800579728 8.902 100 92 184 0 830459248 7335056 22544464
01:59:35 32013 8192000000 9763513984 10068033536 833911200 9.239 100 92 184 0 868319152 12402944 22005008
02:01:23 31438 8192000000 9815853440 10140442624 874066032 9.641 100 79 159 2 920157664 28266528 17825104
02:03:02 15563 8192000000 9874705072 10212605952 930362048 10.198 100 75 150 1 989356864 43267440 15727376
02:04:21 5299 8192000000 9932833008 10278977536 968052592 10.568 100 78 155 1 1042846112 58555808 16237712
02:05:29 5333 8192000000 9965905488 10323972096 1001315504 10.891 100 76 152 2 1087039952 70654128 15070320
02:06:36 3383 8192000000 9989020000 10357272576 1033072608 11.198 100 76 151 1 1109403312 60582640 15748064
02:07:43 2467 8192000000 10024765216 10395189248 1052458576 11.384 100 91 182 2 1136357360 64973024 18925760
02:08:47 2308 8192000000 10039443712 10408734720 1065809280 11.512 100 85 170 1 1145962016 63204224 16948512
02:09:52 4412 8192000000 10020824768 10400010240 1067271600 11.526 100 75 150 1 1140812688 58257312 15283776
02:10:58 2773 8192000000 10038675904 10425524224 1075303904 11.603 100 65 130 2 1156671024 68255232 13111888
02:12:04 3625 8192000000 10002579728 10391048192 1080620496 11.653 100 44 88 4 1121805760 31874976 9310288
02:13:08 53002 8192000000 10004507168 10427150336 1084870320 11.694 100 35 68 2 1110226080 17709792 7645968
02:15:05 6003 8192000000 10015440528 10409799680 1088858208 11.732 100 36 71 5 1129920288 33222320 7839760
02:16:12 6179 8192000000 9997519184 10399404032 1092194272 11.764 100 17 34 3 1125456416 29207520 4054624
02:17:23 8355 8192000000 10018382624 10427068416 1074536112 11.595 98 33 63 3 1103798144 22678032 6584000
02:18:37 9966 8192000000 10014578896 10392367104 1076481616 11.614 100 70 139 0 1115418880 23173488 15763776
02:19:47 3457 8192000000 9991865088 10379296768 1044161904 11.305 98 45 90 5 1086560976 32472384 9926688
02:20:54 8977 8192000000 9940508896 10332119040 1010085152 10.976 97 22 44 1 1042569872 27831104 4653616
02:22:03 49288 8192000000 9933004960 10329534464 1019623440 11.068 99 24 47 0 1039117760 14402288 5092032
02:23:55 7480 8192000000 9954126496 10341756928 1031745792 11.185 99 37 71 1 1062557776 21327552 9484432
02:25:03 7830 8192000000 9960170144 10357166080 1041999520 11.284 99 34 66 2 1063856048 14142608 7713920
02:26:12 3558 8192000000 10027712048 10417422336 1075501520 11.605 100 50 102 3 1128901744 43728032 9672192
02:27:18 77192 8192000000 10015607344 10414702592 1091497792 11.757 100 42 83 0 1114496320 14564848 8433680
02:29:41 21272 8192000000 10075039232 10480209920 1112536528 11.956 100 54 108 1 1190875472 67772656 10566288
02:31:11 17059 8192000000 10078703808 10482098176 1128728000 12.109 100 46 91 0 1184764160 46120496 9915664
02:32:36 11133 8192000000 10139605040 10545737728 1147217168 12.283 100 58 116 2 1247174304 87119328 12837808
02:33:51 10521 8192000000 10175438496 10586890240 1157392160 12.379 100 51 102 0 1286911120 118286896 11232064
02:35:11 21999 8192000000 10189348784 10610786304 1160777888 12.411 100 38 76 1 1302633680 132889808 8965984
02:36:41 24342 8192000000 10220075392 10641629184 1168969536 12.487 100 38 75 3 1327405424 149857008 8578880
02:38:12 8182 8192000000 10234930944 10677792768 1175750288 12.551 100 48 95 1 1351314208 164469536 11094384
02:39:26 15040 8192000000 10239460016 10667802624 1175707424 12.550 100 59 117 1 1333531936 145401296 12423216
02:40:46 1795 8192000000 10291956384 10700435456 1178901152 12.580 100 85 169 2 1395025344 197699040 18425152
02:41:55 4017 8192000000 10319506896 10730835968 1181382400 12.603 100 81 158 2 1419421392 220750928 17288064
02:43:03 6820 8192000000 10310745584 10750889984 1168980464 12.487 99 55 107 0 1413546288 231773280 12792544
02:44:17 4011 8192000000 10323602048 10736549888 1186753136 12.653 100 78 152 1 1422631104 218805200 17072768
02:45:27 2378 8192000000 10361789632 10785984512 1187768496 12.663 100 64 127 2 1473808608 270725776 15314336
02:46:37 2543 8192000000 10362735728 10766651392 1174153888 12.536 99 86 172 4 1456836352 262566224 20116240
02:47:44 6791 8192000000 10324954272 10757148672 1161882064 12.421 100 42 84 1 1442047984 270334192 9831728
02:48:55 16450 8192000000 10411880400 10839011328 1173197264 12.527 100 40 80 1 1519590592 335903968 10489360
02:50:21 1468 8192000000 10446662864 10862653440 1165306288 12.453 100 85 170 1 1541859920 356130048 20423584
02:51:27 31896 8192000000 10428389408 10886348800 1135384224 12.172 97 36 72 2 1492484224 347328512 9771488
02:53:01 7305 8192000000 10449099296 10878058496 1149973648 12.309 100 35 70 1 1552271440 392828144 9469648
02:54:20 15621 8192000000 10432383520 10914910208 1147281312 12.284 100 30 60 1 1548661136 393689072 7690752
02:55:41 27598 8192000000 10406437376 10903883776 1127248864 12.095 99 17 34 0 1479421904 347613728 4559312
02:57:47 26499 8192000000 10129524288 10755588096 1108728112 11.920 99 20 38 2 1365132752 251272416 5132224
02:59:22 33595 8192000000 10040610928 10474590208 1064838896 11.503 96 34 66 1 1088473408 16899168 6735344
03:00:56 54445 8192000000 10057848224 10557620224 1088410608 11.728 98 12 22 1 1165493024 74955296 2127120
03:02:52 81634 8192000000 10040960384 10464571392 1051853776 11.378 94 39 76 4 1078168048 18776208 7538064
03:05:15 104387 8192000000 10113990896 10558062592 1095146416 11.792 99 42 82 2 1210419808 106344176 8929216
03:09:40 42605 8192000000 10062070192 10510991360 1122722304 12.053 100 28 58 0 1185706176 57049776 5934096
03:11:27 91245 8192000000 10137062880 10582790144 1143870912 12.252 100 23 44 1 1215758144 66700992 5186240
03:13:58 61869 8192000000 10181034816 10659459072 1153060560 12.338 100 14 28 1 1284470240 128096928 3312752
03:16:00 76133 8192000000 10087917712 10624425984 1146644800 12.278 100 8 16 0 1261758000 113112176 2001024
03:18:22 68884 8192000000 10079845728 10555551744 1135627104 12.174 99 18 36 0 1199658784 60374512 3657168
03:21:12 40684 8192000000 10126381856 10558812160 1133263152 12.152 98 41 79 1 1200775568 59682688 7829728
03:22:53 90455 8192000000 10107153520 10574532608 1135981952 12.178 99 14 28 1 1211413344 72381328 3050064
03:25:24 53210 8192000000 10107657616 10545025024 1117773600 12.006 97 30 60 1 1171377568 46407360 7196608
03:27:28 26386 8192000000 10134351952 10560389120 1094562176 11.786 95 39 79 2 1187634768 85691552 7381040
03:28:57 96916 8192000000 10053807584 10506629120 1107632832 11.910 97 11 21 1 1136714832 26986912 2095088
03:31:35 33868 8192000000 10058454640 10493526016 1109121904 11.924 97 32 64 0 1135430640 20005088 6303648
03:33:19 91097 8192000000 10071378720 10517630976 1097365648 11.813 97 15 30 0 1156377072 55284688 3726736
03:35:51 52950 8192000000 10068906400 10519937024 1106136464 11.896 98 21 42 1 1169385648 58582928 4666256
03:38:00 61214 8192000000 10039154624 10523852800 1090327504 11.746 97 18 36 0 1163623312 68882624 4413184
03:40:03 19563 8192000000 10038889808 10468888576 1094696000 11.787 98 52 106 2 1124673056 19042848 10934208
03:41:22 63859 8192000000 10093052064 10549854208 1115874432 11.988 99 28 54 2 1158560176 36945536 5740208
03:43:27 41341 8192000000 10035678736 10461237248 1051477376 11.375 94 15 30 2 1074811280 19904320 3429584
03:45:15 68085 8192000000 10105820096 10539491328 1081698528 11.664 97 30 60 1 1144487552 56870784 5918240
03:47:23 69587 8192000000 10068444464 10512801792 1074185952 11.592 96 15 30 2 1130411984 52961200 3264832
03:49:34 77689 8192000000 10137932464 10603655168 1072359648 11.575 96 16 32 0 1189441232 113750752 3330832
03:51:52 102529 8192000000 10070748384 10565808128 1100167840 11.839 99 21 40 1 1167441584 63143632 4130112
03:55:06 27844 8192000000 10033522576 10486632448 1107843344 11.912 99 7 13 2 1127672592 19082096 747152
03:56:35 79002 8192000000 10102633568 10551463936 1113056496 11.961 98 16 32 0 1187441984 70901296 3484192
03:59:14 93140 8192000000 10062187632 10531954688 1114105520 11.971 98 11 22 0 1148977392 32824816 2047056
04:01:48 45759 8192000000 10098573600 10538487808 1129244928 12.114 99 31 62 2 1193944480 58140848 6558704
04:03:43 68913 8192000000 10079657344 10527391744 1110698512 11.939 98 24 48 1 1146344320 30695056 4950752
04:05:53 31607 8192000000 10085721504 10544816128 1127698208 12.100 99 18 36 1 1182593776 51222320 3673248
04:07:28 18752 8192000000 10089533424 10516439040 1127175440 12.095 99 22 43 1 1159555248 27959152 4420656
04:08:51 84051 8192000000 10117883376 10601553920 1130928960 12.130 99 16 32 1 1215108416 80947136 3232320
04:11:17 143951 8192000000 10078599712 10578939904 1143916016 12.252 100 14 28 0 1206995344 59978128 3101200
04:14:48 54401 8192000000 10114902016 10572615680 1148448528 12.295 100 12 24 0 1215028656 64125344 2454784
04:16:43 59430 8192000000 10086416512 10609098752 1144776624 12.260 100 7 12 0 1198017504 51735936 1504944
04:18:58 66340 8192000000 10107028656 10561261568 1144428048 12.257 99 18 36 0 1208856704 60507328 3921328
04:21:05 52788 8192000000 10085323920 10535731200 1138017712 12.197 99 20 40 1 1179295872 37270304 4007856
04:22:58 89096 8192000000 10090747728 10602708992 1104033152 11.876 96 23 46 0 1159546288 50716752 4796384
04:25:27 39228 8192000000 10110225648 10567622656 1098788592 11.826 96 35 70 0 1164716960 58628816 7299552

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 07-01-2021 04:27:58 to 07-01-2021 04:28:13.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 119 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 07-01-2021 04:28:14 to 07-01-2021 04:28:16.

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          227714
MON$OLDEST_ACTIVE               229807
MON$OLDEST_SNAPSHOT             229807
MON$NEXT_TRANSACTION            229807
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                       2109760
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             783
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 ---
:85: 
:86: mscs-test-161.budzdorov.ru    Thu Jan  7 04:26:57 2021
:87:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:88: 
--- end of diff output ---

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

07.01.21 04:28:17. 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 04:28:20 No severe PSQL-related problems occured
07.01.21 04:28:21. End of report.